Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 04:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16046460 kB
MemAvailable 31411248 kB
Buffers 20 kB
Cached 15628888 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-21 04:37:17
DTS_END                         2022-05-21 04:37:17.3220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57810
TRN_ID                          217804
ATT_ID                          592
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8802.48 21141 1056297
customer order: creation 173.17 468 20780
customer order: refuse 14.49 477 1739
order to supplier: creation 46.83 1413 5619
order to supplier: removal 3.08 174955 369
invoice (draft): creation 44.73 3433 5368
invoice (draft): removal 45.92 1082 5510
invoice accept: apply 42.51 30315 5101
invoice accept: cancel 41.88 1423 5026
customer reserve: creation 3985.99 285 478319
customer reserve: removal 4117.16 101 494059
realization accept: apply 83.48 291 10017
realization accept: cancel 74.13 198 8896
payment from customer: creation 59.48 193 7137
payment from customer: removal 5.58 150 669
payment to supplier: creation 47.46 223 5695
payment to supplier: removal 3.64 170 437
service: total inventory turnovers 4.01 4094 481
service: total monetary turnovers 8.03 377 964
service: aggregate perf. data 0.89 16839 107
service: refresh index statistics 0.03 186336 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-05-2022 04:39:33 to 21-05-2022 04:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7829
TEST_TIME 2 6603
TEST_TIME 3 6293
TEST_TIME 4 6433
TEST_TIME 5 6120
TEST_TIME 6 6678
TEST_TIME 7 6680
TEST_TIME 8 6682
TEST_TIME 9 6883
TEST_TIME 10 7022
TEST_TIME 11 7344
TEST_TIME 12 7339
TEST_TIME 13 7418
TEST_TIME 14 7493
TEST_TIME 15 7491
TEST_TIME 16 7457
TEST_TIME 17 7863
TEST_TIME 18 8191
TEST_TIME 19 8174
TEST_TIME 20 8176
TEST_TIME 21 8245
TEST_TIME 22 8178
TEST_TIME 23 8295
TEST_TIME 24 8394
TEST_TIME 25 8703
TEST_TIME 26 9012
TEST_TIME 27 9003
TEST_TIME 28 9267
TEST_TIME 29 9231
TEST_TIME 30 9426
TEST_TIME 31 9653
TEST_TIME 32 9958
TEST_TIME 33 10139
TEST_TIME 34 10251
TEST_TIME 35 10391
TEST_TIME 36 10406
TEST_TIME 37 10755
TEST_TIME 38 10583
TEST_TIME 39 10578
TEST_TIME 40 10850
TEST_TIME 41 10730
TEST_TIME 42 10664
TEST_TIME 43 10617
TEST_TIME 44 10441
TEST_TIME 45 10303
TEST_TIME 46 10121
TEST_TIME 47 10029
TEST_TIME 48 9932
TEST_TIME 49 9842
TEST_TIME 50 9838
TEST_TIME 51 9904
TEST_TIME 52 9811
TEST_TIME 53 9700
TEST_TIME 54 9633
TEST_TIME 55 9666
TEST_TIME 56 9607
TEST_TIME 57 9552
TEST_TIME 58 9634
TEST_TIME 59 9609
TEST_TIME 60 9509
TEST_TIME 61 9532
TEST_TIME 62 9533
TEST_TIME 63 9597
TEST_TIME 64 9577
TEST_TIME 65 9655
TEST_TIME 66 9693
TEST_TIME 67 9683
TEST_TIME 68 9747
TEST_TIME 69 9714
TEST_TIME 70 9697
TEST_TIME 71 9738
TEST_TIME 72 9713
TEST_TIME 73 9645
TEST_TIME 74 9585
TEST_TIME 75 9608
TEST_TIME 76 9506
TEST_TIME 77 9457
TEST_TIME 78 9431
TEST_TIME 79 9392
TEST_TIME 80 9374
TEST_TIME 81 9322
TEST_TIME 82 9247
TEST_TIME 83 9291
TEST_TIME 84 9329
TEST_TIME 85 9300
TEST_TIME 86 9278
TEST_TIME 87 9303
TEST_TIME 88 9282
TEST_TIME 89 9287
TEST_TIME 90 9349
TEST_TIME 91 9355
TEST_TIME 92 9307
TEST_TIME 93 9302
TEST_TIME 94 9279
TEST_TIME 95 9364
TEST_TIME 96 9301
TEST_TIME 97 9261
TEST_TIME 98 9201
TEST_TIME 99 9153
TEST_TIME 100 9103
TEST_TIME 101 9057
TEST_TIME 102 9011
TEST_TIME 103 9021
TEST_TIME 104 9003
TEST_TIME 105 8994
TEST_TIME 106 8971
TEST_TIME 107 8952
TEST_TIME 108 8931
TEST_TIME 109 8927
TEST_TIME 110 8943
TEST_TIME 111 8982
TEST_TIME 112 9000
TEST_TIME 113 9020
TEST_TIME 114 8995
TEST_TIME 115 8988
TEST_TIME 116 8992
TEST_TIME 117 8944
TEST_TIME 118 8904
TEST_TIME 119 8874
TEST_TIME 120 8831

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-05-2022 04:39:37 to 21-05-2022 04:39:40.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1493087 1493087 0 0.00 0 28131 16 0 0
doc_list_biud 1493082 1493082 0 0.00 0 82835 40 0 0
sp_add_invoice_to_stock 5284 5101 183 3.46 52 274029 30316 0 183
sp_cancel_adding_invoice 5026 5026 0 0.00 6 85562 1423 0 0
sp_cancel_client_order 1739 1739 0 0.00 6 21093 477 0 0
sp_cancel_customer_reserve 494059 494059 0 0.00 1 82857 102 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 4528 151 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 4 5294 170 0 0
sp_cancel_supplier_invoice 5515 5510 5 0.09 3 18549 1083 0 5
sp_cancel_supplier_order 369 369 0 0.00 11 1301034 174955 0 0
sp_cancel_write_off 8896 8896 0 0.00 2 10010 199 0 0
sp_client_order 20780 20780 0 0.00 6 25911 468 0 0
sp_customer_reserve 478319 478319 0 0.00 1 62185 286 0 0
sp_fill_shopping_cart 31753 31753 0 0.00 1 20349 86 0 0
sp_fill_shopping_cart_clo_res 478290 478290 0 0.00 0 13037 19 0 0
sp_get_clo_for_invoice 5060 5060 0 0.00 0 38188 273 0 0
sp_kill_cost_storno 15028 15028 0 0.00 0 28117 88 0 0
sp_kill_qstorno_ret_qs2qd 1002893 1002893 0 0.00 0 82827 44 0 0
sp_kill_qty_storno 530673 530673 0 0.00 0 82832 104 0 0
sp_lock_selected_doc 20792 20792 0 0.00 0 9298 8 0 0
sp_make_cost_storno 27907 27907 0 0.00 0 13664 88 0 0
sp_multiply_rows_for_pdistr 27906 27906 0 0.00 0 10785 40 0 0
sp_multiply_rows_for_qdistr 20780 20780 0 0.00 0 10440 173 0 0
sp_pay_from_customer 7137 7137 0 0.00 5 14314 194 0 0
sp_pay_to_supplier 5695 5695 0 0.00 6 21815 223 0 0
sp_payment_common 12832 12832 0 0.00 2 10174 49 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 21085 399 0 0
sp_qd_handle_on_invoice_upd_sts 10083 10083 0 0.00 0 35259 568 0 0
sp_qd_handle_on_reserve_upd_sts 10017 10017 0 0.00 0 14281 65 0 0
sp_reserve_write_off 10017 10017 0 0.00 9 18411 292 0 0
sp_supplier_invoice 5368 5368 0 0.00 8 99607 3434 0 0
sp_supplier_order 5619 5619 0 0.00 8 66477 1413 0 0
srv_aggregate_perf_data 107 107 0 0.00 2495 204497 16839 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 818 106401 32796 0 0
srv_find_qd_qs_mism 1004922 1004922 0 0.00 0 12599 13 0 0
srv_make_invnt_saldo 481 481 0 0.00 29 70423 4094 0 0
srv_make_money_saldo 964 964 0 0.00 1 34094 378 0 0
srv_recalc_idx_stat 4 4 0 0.00 83697 259349 186336 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 1 10352 1084 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 2 6937 505 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 10862 42 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3212 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 4373 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 484 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 971 39 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 1 96510 8424 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1430 22 0 0
t$perf-norm:sp_client_order 20780 20780 0 0.00 0 13712 39 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1752 18 0 0
t$perf-norm:sp_pay_from_customer 7137 7137 0 0.00 0 8725 21 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 6692 22 0 0
t$perf-norm:sp_reserve_write_off 10017 10017 0 0.00 0 16649 29 0 0
t$perf-norm:sp_supplier_invoice 5368 5368 0 0.00 0 16928 38 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 4887 37 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1629 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 228 9 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 1497 21 0 0
t$perf-norm:srv_make_money_saldo 964 964 0 0.00 0 2500 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 207 103 0 0
v_add_invoice_to_stock 10480 10114 366 3.49 0 4532 25 0 366
v_all_customers 38178 38178 0 0.00 0 136 4 0 0
v_all_suppliers 30238 30238 0 0.00 0 129 4 0 0
v_all_wares 41554 41554 0 0.00 0 93 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 364 14 0 0
v_cancel_client_order 3478 3478 0 0.00 0 2267 11 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 4486 20 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1612 15 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2475 16 0 0
v_cancel_supplier_invoice 852 842 10 1.17 0 2285 27 0 10
v_cancel_supplier_order 738 738 0 0.00 0 404 12 0 0
v_cancel_write_off 2016 2016 0 0.00 0 6811 28 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 2393 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 2181 17 0 0
v_max_id_ord_sup 5359 5359 0 0.00 0 5124 9 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 104 8 0 0
v_max_non_paid_realizn 3662 3662 0 0.00 0 6990 9 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 4406 9 0 0
v_min_id_clo_res 972 972 0 0.00 0 8247 31 0 0
v_min_id_ord_sup 5359 5359 0 0.00 0 7210 8 0 0
v_min_non_paid_invoice 5695 5695 0 0.00 0 7547 19 0 0
v_min_non_paid_realizn 7137 7137 0 0.00 0 10155 19 0 0
v_our_firm 10324 10324 0 0.00 0 58 5 0 0
v_reserve_write_off 20030 20030 0 0.00 0 4344 11 0 0
x_lock_depdocs_on_canc_invoice 10115 10115 0 0.00 0 13331 134 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 6066 378 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 3 66422 1229 0 0
x_make_qstorno_1200_2000 5368 5368 0 0.00 3 99436 3203 0 0
x_make_qstorno_2100_3300 478315 478315 0 0.00 0 55081 253 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-05-2022 04:39:41 to 21-05-2022 04:39:45.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 18793 8192000000 9706756064 10006294528 791455632 8.810 100 81 162 0 818362064 7376480 19529952
02:09:46 18327 8192000000 9763416640 10070687744 838729424 9.287 100 90 180 0 872303728 12376768 21197536
02:11:08 7488 8192000000 9788445456 10101927936 860951520 9.510 100 92 184 0 900186256 17562688 21672048
02:12:22 14224 8192000000 9851205152 10178842624 898369632 9.882 100 81 162 1 953575968 37115888 18090448
02:13:39 9228 8192000000 9897234160 10239016960 935865008 10.252 100 76 152 0 1009872176 57111280 16895888
02:14:53 4700 8192000000 9919212176 10269036544 976795296 10.653 100 87 173 0 1042035568 47161776 18078496
02:16:02 2385 8192000000 10010573232 10367217664 1003804784 10.915 100 93 180 0 1125154560 103133072 18216704
02:17:06 2345 8192000000 9990508320 10356363264 1027402688 11.143 100 87 174 1 1106268880 62013936 16852256
02:18:11 1048 8192000000 10024506976 10401726464 1047542320 11.337 100 97 191 2 1142440688 76460416 18437952
02:19:14 1269 8192000000 10036976624 10413162496 1060808864 11.464 100 95 184 0 1159040592 79234768 18996960
02:20:17 1209 8192000000 10034175024 10407968768 1064587408 11.500 100 97 184 5 1156805616 72839184 19379024
02:21:21 1133 8192000000 10032711760 10413772800 1070020400 11.552 100 92 169 1 1158950576 71355376 17574800
02:22:23 1083 8192000000 10046334848 10431471616 1077484512 11.623 100 92 171 1 1171062416 75978560 17599344
02:23:25 874 8192000000 10046030656 10439839744 1079925216 11.647 100 71 139 1 1172408320 78598256 13884848
02:24:27 1249 8192000000 10069809904 10456780800 1088307376 11.727 100 96 187 1 1189737040 82504304 18925360
02:25:29 1227 8192000000 10066048944 10456825856 1093430352 11.775 100 94 181 0 1187224304 76300768 17493184
02:26:32 1235 8192000000 10088621216 10478211072 1095653744 11.796 100 93 179 0 1210090272 95660000 18776528
02:27:34 1203 8192000000 10053665472 10439458816 1096870384 11.808 100 97 182 1 1175855744 60236624 18748736
02:28:36 1078 8192000000 10036151968 10420584448 1084083504 11.686 99 93 174 1 1164550192 62876608 17590080
02:29:38 1222 8192000000 10051509808 10437033984 1095849840 11.798 100 94 186 2 1169842464 53671840 20320784
02:30:39 1197 8192000000 10076517584 10465497088 1092315808 11.765 100 81 149 2 1202490880 95492640 14682432
02:31:41 1174 8192000000 10007927536 10390372352 1068124944 11.534 99 84 158 1 1132823936 48023184 16675808
02:32:44 1625 8192000000 10009557776 10391470080 1057079200 11.429 100 80 146 3 1129875552 57080864 15715488
02:33:46 1120 8192000000 10019918368 10401013760 1051725392 11.377 100 94 185 2 1142715280 71223440 19766448
02:34:48 1161 8192000000 10007246208 10381045760 1036934848 11.235 99 93 177 1 1130515088 74551808 19028432
02:35:50 2989 8192000000 9965099584 10339057664 1016279056 11.036 100 69 137 1 1089078480 59106032 13693392
02:36:53 1273 8192000000 10011839120 10384728064 1039787264 11.263 100 89 169 0 1129593840 73929888 15876688
02:38:07 12036 8192000000 9987188944 10365718528 1047809216 11.340 99 68 127 2 1108430752 48281968 12339568
02:39:26 916 8192000000 10036121392 10420953088 1075184400 11.602 100 94 182 1 1164769712 69145792 20439520
02:40:31 4475 8192000000 10073435808 10462175232 1092780992 11.769 100 76 151 1 1196096496 88408944 14906560
02:41:50 10554 8192000000 10095303776 10489200640 1102283312 11.859 100 61 116 0 1209509472 94878080 12348080
02:43:04 4152 8192000000 10131547440 10519814144 1119190080 12.019 100 88 161 7 1256841440 121645008 16006352
02:44:11 1200 8192000000 10187194800 10580684800 1123503792 12.060 100 94 183 0 1306562960 160540880 22518288
02:45:15 815 8192000000 10194356048 10585116672 1130315824 12.124 100 93 178 1 1310708624 161357856 19034944
02:46:20 1109 8192000000 10218432576 10607517696 1131168256 12.132 100 98 191 1 1327297792 174664832 21464704
02:47:23 1010 8192000000 10272277856 10664984576 1139172112 12.208 100 94 184 0 1382241408 223356448 19712848
02:48:28 1037 8192000000 10270793456 10680557568 1148260176 12.293 100 94 178 1 1383591472 214543760 20787536
02:49:33 2297 8192000000 10282410048 10685689856 1151236864 12.321 100 80 158 2 1398056672 228488864 18330944
02:50:39 2222 8192000000 10295759104 10710130688 1156997216 12.375 100 72 143 0 1407713296 234728176 15987904
02:51:44 1887 8192000000 10340700720 10747166720 1165593056 12.456 100 91 181 1 1448694752 262619696 20482000
02:52:53 1959 8192000000 10372389840 10780811264 1175802080 12.551 100 85 168 1 1481862512 286832720 19227712
02:54:01 1218 8192000000 10334276336 10810540032 1168562688 12.483 100 94 183 1 1443394944 252692240 22140016
02:55:05 933 8192000000 10354825904 10799673344 1173330448 12.528 100 90 174 0 1462030192 268536256 20163488
02:56:12 1961 8192000000 10385581824 10819870720 1184752960 12.635 100 78 156 1 1495751520 292398528 18600032
02:57:19 2138 8192000000 10391850416 10814119936 1182721008 12.616 100 91 175 0 1499178032 296280944 20176080
02:58:24 1548 8192000000 10431778208 10862456832 1188199152 12.667 100 87 170 1 1535681712 326749072 20733488
02:59:35 902 8192000000 10445256592 10884763648 1189200992 12.676 100 95 188 1 1551280736 339627072 22452672
03:00:41 1562 8192000000 10467428752 10880557056 1182303264 12.612 100 93 183 2 1577186864 372723120 22160480
03:01:46 3003 8192000000 10456120864 10928332800 1186815456 12.654 100 65 126 1 1566444608 363858112 15771040
03:02:56 2296 8192000000 10432349296 10891497472 1176295344 12.556 100 78 148 0 1541202848 347153296 17754208
03:04:05 4100 8192000000 10427080112 10864553984 1181418144 12.603 100 56 108 0 1533566432 338833168 13315120
03:05:17 4846 8192000000 10396250416 10879614976 1167399312 12.473 100 43 86 1 1500194080 321877024 10917744
03:06:29 3295 8192000000 10423518464 10867294208 1177081520 12.563 100 58 117 0 1527638112 336292688 14263904
03:07:35 4253 8192000000 10381146528 10825510912 1171112128 12.507 100 61 120 2 1483416608 297620304 14684176
03:08:49 14654 8192000000 10325142656 10808254464 1159016064 12.394 100 45 90 0 1437506368 267455312 11034992
03:10:09 10020 8192000000 10271772288 10735308800 1157745744 12.382 100 39 72 1 1385907168 219390608 8770816
03:11:24 24837 8192000000 10297185744 10798141440 1166813008 12.467 100 25 50 0 1392228272 219132400 6282864
03:12:50 23274 8192000000 10254422208 10712416256 1157926352 12.384 100 25 50 1 1354831024 190626640 6278032
03:14:15 50847 8192000000 10210655872 10754867200 1159333232 12.397 100 15 30 0 1327091296 164283120 3474944
03:16:07 21848 8192000000 10161226064 10620211200 1129421680 12.116 98 44 80 0 1260260704 120374784 10464240
03:17:33 49345 8192000000 10120940544 10588897280 1130168464 12.123 98 15 30 2 1209400608 75804368 3427776
03:19:26 27467 8192000000 10154819664 10572742656 1145193712 12.264 100 64 118 5 1246003536 89085456 11724368
03:20:56 93477 8192000000 10079577664 10525069312 1119716368 12.024 98 35 67 1 1151476176 24083440 7676368
03:23:41 52478 8192000000 10081593856 10515816448 1130871072 12.130 99 61 118 1 1177140320 34468576 11800672
03:25:35 9198 8192000000 10102722992 10540838912 1132022848 12.140 99 62 116 1 1204843072 59200528 13619696
03:26:51 33151 8192000000 10142238048 10595315712 1134550576 12.164 99 40 80 0 1245406592 102198688 8657328
03:28:25 22864 8192000000 10097002416 10591178752 1118442528 12.012 99 28 55 0 1214034464 89664656 5927280
03:30:03 43255 8192000000 10111030736 10561159168 1114214336 11.972 98 13 26 1 1186384096 68577232 3592528
03:31:47 76404 8192000000 10127630368 10629951488 1111831344 11.950 98 16 32 0 1250454208 134439888 4182976
03:34:04 64955 8192000000 10112465472 10604216320 1120403664 12.031 99 17 31 0 1219932608 95504736 4024208
03:36:09 50728 8192000000 10046690176 10493624320 1087981600 11.723 96 13 24 1 1129384720 39245680 2157440
03:38:06 53344 8192000000 10117117552 10575577088 1109408672 11.927 98 34 68 1 1199606496 83436928 6760896
03:40:00 53854 8192000000 10121520048 10591719424 1107969264 11.913 97 27 54 1 1188724064 75117184 5637616
03:42:03 44384 8192000000 10106294624 10566017024 1103598320 11.872 97 24 48 1 1191066800 82469392 4999088
03:43:52 40369 8192000000 10133348880 10570813440 1131298192 12.134 99 43 86 1 1211349328 70939792 9111344
03:45:38 19485 8192000000 10060613584 10484809728 1110959536 11.942 97 48 95 0 1150403328 28440992 11002800
03:47:08 12702 8192000000 10104302992 10551021568 1120803696 12.035 99 39 78 1 1186154672 57433008 7917968
03:48:22 34696 8192000000 10110894384 10572697600 1118544928 12.013 99 49 98 1 1215165040 86779856 9840256
03:49:59 80267 8192000000 10156832208 10608652288 1138276720 12.199 100 48 94 2 1212719408 64670624 9772064
03:52:54 60226 8192000000 10087514064 10556096512 1130400352 12.125 99 35 70 1 1178615392 41381360 6833680
03:55:15 81812 8192000000 10106520128 10556338176 1108252896 11.916 98 20 40 1 1209096224 96580000 4263328
03:58:13 71028 8192000000 10095456064 10553491456 1111606816 11.948 98 20 39 1 1198093280 82108496 4377968
04:00:54 52277 8192000000 10051000128 10511044608 1088313952 11.727 97 9 18 0 1136718880 46160208 2244720
04:02:49 28469 8192000000 10069162240 10498879488 1085547488 11.700 97 38 74 2 1144062896 51133424 7381984
04:04:18 44788 8192000000 10084963168 10525274112 1124669920 12.071 100 44 88 1 1186053088 52197728 9185440
04:06:12 61213 8192000000 10135968064 10584571904 1139927248 12.215 100 39 78 0 1213359920 65225584 8207088
04:08:14 56574 8192000000 10070131920 10493243392 1107238672 11.906 97 21 42 1 1148447360 36890832 4317856
04:10:12 41802 8192000000 10117069008 10547105792 1127679904 12.099 99 46 92 1 1173966736 37571840 8714992
04:11:55 52793 8192000000 10123169328 10600636416 1142437680 12.238 100 28 56 0 1236891184 88873328 5580176
04:13:48 103152 8192000000 10065550640 10536431616 1140053648 12.216 100 22 44 1 1192076288 47073520 4949120
04:16:32 57133 8192000000 10064021040 10524069888 1135240608 12.171 100 7 14 0 1168464752 31810832 1413312
04:18:30 60474 8192000000 10085986304 10559475712 1115248224 11.982 98 9 18 1 1151368544 34127360 1992960
04:20:31 106400 8192000000 10026002960 10490617856 1060123184 11.458 94 7 14 1 1113634208 52040816 1470208
04:23:17 12570 8192000000 10070205552 10505900032 1115886096 11.988 100 56 110 0 1167206704 39483616 11836992
04:24:32 43200 8192000000 10126432000 10568450048 1120190368 12.029 99 23 46 2 1193840224 68850336 4799520
04:26:16 50677 8192000000 10160728800 10602332160 1118804800 12.016 98 23 47 0 1223328416 99225984 5297632
04:28:16 78995 8192000000 10081190272 10596945920 1112431504 11.955 98 8 16 0 1193541232 79229472 1880256
04:30:38 84686 8192000000 10100858576 10540392448 1071184032 11.563 95 25 51 0 1138656896 62957024 4515840
04:33:05 91283 8192000000 10068838496 10546720768 1101467696 11.852 100 50 100 0 1163657392 52208096 9981600
04:35:43 68655 8192000000 10042516864 10491457536 1073213200 11.583 97 23 46 0 1132176512 54283840 4679472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-05-2022 04:39:47 to 21-05-2022 04:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 183 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-05-2022 04:40:04 to 21-05-2022 04:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217165
MON$OLDEST_ACTIVE               218209
MON$OLDEST_SNAPSHOT             218209
MON$NEXT_TRANSACTION            218209
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2082528
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sat May 21 04:37:19 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Sat May 21 04:37:40 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat May 21 04:37:42 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat May 21 04:37:42 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat May 21 04:37:56 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sat May 21 04:38:01 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

21.05.22 04:40:07. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-21 04:40:10 No severe PSQL-related problems occured
21.05.22 04:40:11. End of report.