Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.06.2022 10:37
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 15678968 kB
MemAvailable 31181980 kB
Buffers 0 kB
Cached 15997892 kB
SwapCached 2500 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-06-26 10:37:17
DTS_END                         2022-06-26 10:37:17.9860
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/49678
TRN_ID                          223695
ATT_ID                          617
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: 9204.26 24479 1104511
customer order: creation 179.02 497 21482
customer order: refuse 15.33 479 1840
order to supplier: creation 47.24 1444 5669
order to supplier: removal 3.49 190221 419
invoice (draft): creation 47.04 3133 5645
invoice (draft): removal 49.40 1165 5928
invoice accept: apply 43.18 27597 5181
invoice accept: cancel 44.73 1405 5367
customer reserve: creation 4085.44 255 490253
customer reserve: removal 4388.96 118 526675
realization accept: apply 87.25 317 10470
realization accept: cancel 77.72 190 9326
payment from customer: creation 62.02 195 7442
payment from customer: removal 5.58 127 670
payment to supplier: creation 51.31 215 6157
payment to supplier: removal 3.68 131 442
service: total inventory turnovers 4.16 4325 499
service: total monetary turnovers 7.77 399 932
service: aggregate perf. data 0.90 14350 108
service: refresh index statistics 0.05 243021 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-06-2022 10:38:11 to 26-06-2022 10:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6447
TEST_TIME 2 5105
TEST_TIME 3 4996
TEST_TIME 4 4547
TEST_TIME 5 4865
TEST_TIME 6 4759
TEST_TIME 7 5039
TEST_TIME 8 5709
TEST_TIME 9 6250
TEST_TIME 10 6423
TEST_TIME 11 6521
TEST_TIME 12 6478
TEST_TIME 13 6522
TEST_TIME 14 6672
TEST_TIME 15 6839
TEST_TIME 16 7043
TEST_TIME 17 7097
TEST_TIME 18 7394
TEST_TIME 19 7317
TEST_TIME 20 7585
TEST_TIME 21 7665
TEST_TIME 22 7606
TEST_TIME 23 7704
TEST_TIME 24 7716
TEST_TIME 25 7711
TEST_TIME 26 7788
TEST_TIME 27 8129
TEST_TIME 28 8237
TEST_TIME 29 8410
TEST_TIME 30 8388
TEST_TIME 31 8846
TEST_TIME 32 9353
TEST_TIME 33 9301
TEST_TIME 34 9621
TEST_TIME 35 9760
TEST_TIME 36 9654
TEST_TIME 37 9927
TEST_TIME 38 10177
TEST_TIME 39 10597
TEST_TIME 40 10760
TEST_TIME 41 10720
TEST_TIME 42 10614
TEST_TIME 43 10719
TEST_TIME 44 10567
TEST_TIME 45 10571
TEST_TIME 46 10571
TEST_TIME 47 10507
TEST_TIME 48 10380
TEST_TIME 49 10295
TEST_TIME 50 10240
TEST_TIME 51 10207
TEST_TIME 52 10083
TEST_TIME 53 10009
TEST_TIME 54 9930
TEST_TIME 55 9919
TEST_TIME 56 9840
TEST_TIME 57 9829
TEST_TIME 58 9842
TEST_TIME 59 9766
TEST_TIME 60 9785
TEST_TIME 61 9770
TEST_TIME 62 9711
TEST_TIME 63 9677
TEST_TIME 64 9822
TEST_TIME 65 9908
TEST_TIME 66 9961
TEST_TIME 67 9931
TEST_TIME 68 9851
TEST_TIME 69 9878
TEST_TIME 70 9988
TEST_TIME 71 10032
TEST_TIME 72 9981
TEST_TIME 73 9925
TEST_TIME 74 9982
TEST_TIME 75 9924
TEST_TIME 76 9974
TEST_TIME 77 9894
TEST_TIME 78 9791
TEST_TIME 79 9772
TEST_TIME 80 9734
TEST_TIME 81 9690
TEST_TIME 82 9644
TEST_TIME 83 9676
TEST_TIME 84 9636
TEST_TIME 85 9627
TEST_TIME 86 9568
TEST_TIME 87 9525
TEST_TIME 88 9460
TEST_TIME 89 9452
TEST_TIME 90 9451
TEST_TIME 91 9451
TEST_TIME 92 9499
TEST_TIME 93 9435
TEST_TIME 94 9500
TEST_TIME 95 9490
TEST_TIME 96 9465
TEST_TIME 97 9471
TEST_TIME 98 9465
TEST_TIME 99 9558
TEST_TIME 100 9584
TEST_TIME 101 9557
TEST_TIME 102 9485
TEST_TIME 103 9442
TEST_TIME 104 9378
TEST_TIME 105 9385
TEST_TIME 106 9361
TEST_TIME 107 9319
TEST_TIME 108 9292
TEST_TIME 109 9257
TEST_TIME 110 9200
TEST_TIME 111 9220
TEST_TIME 112 9202
TEST_TIME 113 9178
TEST_TIME 114 9158
TEST_TIME 115 9160
TEST_TIME 116 9143
TEST_TIME 117 9129
TEST_TIME 118 9209
TEST_TIME 119 9247
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-06-2022 10:38:15 to 26-06-2022 10:38:18.

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 1554642 1554642 0 0.00 0 18899 15 0 0
doc_list_biud 1554642 1554642 0 0.00 0 31119 46 0 0
sp_add_invoice_to_stock 5300 5181 119 2.24 38 281169 27598 0 119
sp_cancel_adding_invoice 5367 5367 0 0.00 6 80588 1405 0 0
sp_cancel_client_order 1840 1840 0 0.00 6 16502 479 0 0
sp_cancel_customer_reserve 526675 526675 0 0.00 1 26215 118 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 4 6215 128 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 4 1661 132 0 0
sp_cancel_supplier_invoice 5939 5928 11 0.18 3 31178 1166 0 11
sp_cancel_supplier_order 419 419 0 0.00 8 1497852 190221 0 0
sp_cancel_write_off 9326 9326 0 0.00 2 10490 191 0 0
sp_client_order 21482 21482 0 0.00 6 21682 498 0 0
sp_customer_reserve 490253 490253 0 0.00 0 192261 256 0 0
sp_fill_shopping_cart 32791 32791 0 0.00 1 13409 88 0 0
sp_fill_shopping_cart_clo_res 490245 490245 0 0.00 0 18584 20 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 32715 277 0 0
sp_kill_cost_storno 15805 15805 0 0.00 0 7256 85 0 0
sp_kill_qstorno_ret_qs2qd 1069023 1069023 0 0.00 0 31113 51 0 0
sp_kill_qty_storno 565160 565160 0 0.00 0 31118 117 0 0
sp_lock_selected_doc 21547 21547 0 0.00 0 10179 10 0 0
sp_make_cost_storno 29204 29204 0 0.00 0 18885 87 0 0
sp_multiply_rows_for_pdistr 29204 29204 0 0.00 0 15866 42 0 0
sp_multiply_rows_for_qdistr 21482 21482 0 0.00 0 10501 181 0 0
sp_pay_from_customer 7442 7442 0 0.00 5 15894 195 0 0
sp_pay_to_supplier 6157 6157 0 0.00 6 15435 216 0 0
sp_payment_common 13599 13599 0 0.00 2 5624 45 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 0 16456 391 0 0
sp_qd_handle_on_invoice_upd_sts 10502 10502 0 0.00 1 19674 611 0 0
sp_qd_handle_on_reserve_upd_sts 10470 10470 0 0.00 0 18181 74 0 0
sp_reserve_write_off 10470 10470 0 0.00 8 18443 317 0 0
sp_supplier_invoice 5645 5645 0 0.00 11 96937 3133 0 0
sp_supplier_order 5669 5669 0 0.00 8 29722 1444 0 0
srv_aggregate_perf_data 108 108 0 0.00 2354 210897 14351 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 735 196159 31721 0 0
srv_find_qd_qs_mism 1052552 1052552 0 0.00 0 8909 12 0 0
srv_make_invnt_saldo 499 499 0 0.00 37 95534 4325 0 0
srv_make_money_saldo 932 932 0 0.00 1 19810 400 0 0
srv_recalc_idx_stat 6 6 0 0.00 44258 416307 243021 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5181 5181 0 0.00 1 12710 1081 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 2 9196 515 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 2745 32 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 3700 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 2485 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 698 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1149 39 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 130807 8118 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 1695 25 0 0
t$perf-norm:sp_client_order 21482 21482 0 0.00 0 8013 44 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 895 16 0 0
t$perf-norm:sp_pay_from_customer 7442 7442 0 0.00 0 3255 21 0 0
t$perf-norm:sp_pay_to_supplier 6157 6157 0 0.00 0 9070 23 0 0
t$perf-norm:sp_reserve_write_off 10470 10470 0 0.00 0 3254 24 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 5818 33 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 6353 34 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1102 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 520 15 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1356 23 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 498 6 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 224 86 0 0
v_add_invoice_to_stock 10508 10270 238 2.26 0 3910 28 0 238
v_all_customers 40066 40066 0 0.00 0 260 4 0 0
v_all_suppliers 31874 31874 0 0.00 0 262 5 0 0
v_all_wares 42962 42962 0 0.00 0 256 5 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1101 17 0 0
v_cancel_client_order 3680 3680 0 0.00 0 3580 14 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1653 12 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 1 2253 15 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 1214 17 0 0
v_cancel_supplier_invoice 834 812 22 2.63 0 2077 29 0 22
v_cancel_supplier_order 838 838 0 0.00 0 4311 17 0 0
v_cancel_write_off 2064 2064 0 0.00 0 1934 27 0 0
v_max_id_clo_ord 5669 5669 0 0.00 0 695 6 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 1904 15 0 0
v_max_id_ord_sup 5641 5641 0 0.00 0 5355 7 0 0
v_max_non_paid_invoice 1530 1530 0 0.00 0 426 9 0 0
v_max_non_paid_realizn 3674 3674 0 0.00 0 2511 8 0 0
v_min_id_clo_ord 5669 5669 0 0.00 0 1739 9 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 9751 27 0 0
v_min_id_ord_sup 5641 5641 0 0.00 0 5067 9 0 0
v_min_non_paid_invoice 6157 6157 0 0.00 0 4403 14 0 0
v_min_non_paid_realizn 7442 7442 0 0.00 0 5618 14 0 0
v_our_firm 10432 10432 0 0.00 0 254 5 0 0
v_reserve_write_off 20940 20940 0 0.00 0 7508 13 0 0
x_lock_depdocs_on_canc_invoice 10889 10889 0 0.00 0 26764 143 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 25993 439 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 2 29707 1255 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 5 96840 2901 0 0
x_make_qstorno_2100_3300 490253 490253 0 0.00 0 192257 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-06-2022 10:38:19 to 26-06-2022 10:38:23.

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
08:07:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 26915 8192000000 9720228528 10015346688 793646784 8.832 100 91 182 0 823677696 7561728 22469184
08:09:52 18790 8192000000 9755034048 10059730944 828830256 9.187 100 94 186 0 863686224 12551120 22304848
08:11:26 9174 8192000000 9795514960 10110328832 864149744 9.542 100 90 180 0 909147360 23956224 21041392
08:12:50 8108 8192000000 9863936816 10199396352 916048304 10.057 100 79 159 0 979525888 46038736 17438848
08:14:11 4748 8192000000 9934042224 10280136704 964552608 10.534 100 84 168 0 1043921024 61634544 17733872
08:15:19 2676 8192000000 9987176736 10343088128 1000845152 10.887 100 88 168 0 1099083696 81404160 16834384
08:16:24 1498 8192000000 9994462784 10355466240 1020370016 11.076 100 92 182 0 1108490976 70458928 17662032
08:17:28 1082 8192000000 10022715312 10393800704 1042610256 11.290 100 95 186 1 1136593296 74956000 19027040
08:18:32 1161 8192000000 10030080944 10402824192 1060345728 11.460 100 86 167 1 1148006224 71365040 16295456
08:19:35 1426 8192000000 10046590160 10424426496 1068504208 11.538 100 92 181 0 1169091120 81986528 18600384
08:20:39 982 8192000000 10069972256 10454573056 1069096272 11.543 100 96 188 2 1193852784 104770592 19985920
08:21:41 922 8192000000 10051747712 10430492672 1072789600 11.579 100 95 185 1 1171500880 79445360 19265920
08:22:43 1231 8192000000 10071904496 10458923008 1088573328 11.729 100 97 183 0 1192642000 85744928 18323744
08:23:45 1155 8192000000 10038067408 10424209408 1093957904 11.780 100 84 146 4 1158075376 50512896 13604576
08:24:49 1128 8192000000 10083884512 10468917248 1096374368 11.803 100 97 188 2 1206750976 90899088 19477520
08:25:50 2360 8192000000 10054475264 10447962112 1095892048 11.799 100 75 141 0 1177473168 68405280 13175840
08:26:54 1057 8192000000 10048905504 10436050944 1089641328 11.739 100 95 182 0 1169463328 62412736 17409264
08:27:56 1148 8192000000 10088350368 10479915008 1100862000 11.846 100 97 186 0 1212246880 91381664 20003216
08:28:59 1051 8192000000 10053035280 10437263360 1090980736 11.752 100 99 190 3 1173148800 63973440 18194624
08:30:01 3062 8192000000 10031087888 10421653504 1086697296 11.711 100 61 119 2 1154092624 54582944 12812384
08:31:06 7301 8192000000 10043348192 10433540096 1090855712 11.751 100 76 138 0 1164608400 60786208 12966480
08:32:15 5767 8192000000 10002009152 10385461248 1051642000 11.376 99 64 115 3 1114814800 50914464 12258336
08:33:22 1039 8192000000 10020338352 10394636288 1045072032 11.313 99 95 179 0 1141568720 76991600 19505088
08:34:25 1529 8192000000 9986409360 10356322304 1015941616 11.033 98 95 180 3 1112086304 77288784 18855904
08:35:28 810 8192000000 9983961680 10353614848 1022020544 11.092 99 88 169 1 1105332848 66319104 16993200
08:36:30 1074 8192000000 9970069248 10339524608 1025953264 11.129 100 92 170 4 1091152192 47071392 18127536
08:37:34 1088 8192000000 10013798768 10392293376 1052822224 11.388 100 87 152 3 1128226304 59088096 16315984
08:38:44 5752 8192000000 10008421392 10388979712 1064072048 11.495 100 72 133 6 1125031216 48290576 12668592
08:39:52 2983 8192000000 10054886480 10443452416 1075863536 11.608 100 74 136 5 1170307200 80540528 13903136
08:41:03 20532 8192000000 10065685200 10457649152 1082888432 11.675 100 60 118 2 1177156112 82338768 11928912
08:42:28 1115 8192000000 10114785568 10495991808 1081118976 11.658 99 86 162 0 1231659136 131657008 18883152
08:43:33 2931 8192000000 10117569744 10507423744 1094222096 11.783 100 66 126 0 1221380000 113803264 13354640
08:44:47 1085 8192000000 10172707296 10557399040 1116450096 11.993 100 92 173 0 1285225568 150374192 18401280
08:45:50 3641 8192000000 10181874928 10582695936 1127583904 12.099 100 73 140 1 1302136272 159619344 14933024
08:46:55 1998 8192000000 10205484256 10609188864 1139292544 12.209 100 79 155 2 1321300368 164742256 17265568
08:48:03 1339 8192000000 10222457632 10623365120 1143167008 12.245 100 80 150 1 1334219552 174140256 16912288
08:49:10 876 8192000000 10271818704 10672001024 1159638160 12.400 100 89 163 0 1382993760 205939808 17415792
08:50:15 3789 8192000000 10290004544 10703888384 1165002288 12.450 100 84 166 0 1402351712 218642688 18706736
08:51:26 1594 8192000000 10321051936 10735898624 1174602672 12.540 100 91 174 0 1437861600 241037776 22221152
08:52:33 1178 8192000000 10358349552 10770075648 1176800064 12.560 100 97 189 0 1469980752 271383888 21796800
08:53:38 1183 8192000000 10390872592 10801434624 1187038464 12.656 100 97 191 1 1496426016 287829360 21558192
08:54:42 878 8192000000 10414368768 10856828928 1201693552 12.792 100 99 196 1 1524726592 299958768 23074272
08:55:47 1754 8192000000 10419776544 10848886784 1200843264 12.784 100 93 182 1 1531420032 308162000 22414768
08:56:52 798 8192000000 10454171840 10898493440 1206628752 12.838 100 97 189 0 1562586240 333341392 22616096
08:57:56 1469 8192000000 10480910016 10908004352 1204447408 12.818 100 94 186 1 1587193632 359793344 22952880
08:59:02 1773 8192000000 10519278208 10930348032 1204170320 12.815 100 94 183 1 1626046672 399886960 21989392
09:00:06 1549 8192000000 10572802224 10994364416 1203669280 12.810 100 96 189 0 1674124112 449061296 21393536
09:01:12 746 8192000000 10559712752 10982793216 1199978576 12.776 100 93 178 0 1670373248 447088464 23306208
09:02:17 1399 8192000000 10616572432 11040784384 1200146016 12.778 100 100 195 1 1722436432 499416384 22874032
09:03:22 3079 8192000000 10601836384 11066114048 1197920288 12.757 100 88 174 1 1706085648 486199024 21966336
09:04:28 891 8192000000 10633417712 11101528064 1200370736 12.780 100 85 170 0 1735427824 512792992 22264096
09:05:32 4814 8192000000 10636356000 11073462272 1198148048 12.759 100 76 143 0 1745388736 529436144 17804544
09:06:42 16192 8192000000 10615643648 11086123008 1184617920 12.633 100 53 106 0 1728767632 530135984 14013728
09:08:06 4992 8192000000 10569581616 11097710592 1181649648 12.606 100 64 127 0 1673793344 475867392 16276304
09:09:20 8853 8192000000 10450914976 10989068288 1176594032 12.558 100 42 84 0 1609538208 422108032 10836144
09:10:36 22723 8192000000 10401214576 10895953920 1143198176 12.246 97 39 74 0 1520768848 368360992 9209680
09:12:08 7273 8192000000 10403412352 10847727616 1179732416 12.588 100 43 84 0 1501152544 310938560 10481568
09:13:16 16786 8192000000 10391386496 10859204608 1167664512 12.475 100 41 83 0 1513895008 336794256 9436240
09:14:34 20528 8192000000 10319262304 10833350656 1145561296 12.268 98 27 54 0 1405913904 253427792 6924816
09:15:55 13532 8192000000 10273545296 10749689856 1145255344 12.265 99 29 58 0 1371613088 219629328 6728416
09:17:09 62481 8192000000 10068727632 10500800512 1127482016 12.098 98 20 35 1 1197911456 66367360 4062080
09:19:13 91364 8192000000 10094394672 10604666880 1133627168 12.156 98 14 26 0 1224854576 88690720 2536688
09:21:44 26898 8192000000 10116057840 10545041408 1154707456 12.354 100 36 70 0 1206659872 44542880 7409536
09:23:17 71246 8192000000 10083562864 10589888512 1146296096 12.275 99 7 14 1 1204012048 55624928 2091024
09:25:37 83303 8192000000 10075887312 10527662080 1120148160 12.028 98 41 76 0 1157653808 28113904 9391744
09:28:06 9265 8192000000 10068808608 10493362176 1126969888 12.093 99 68 121 0 1159204640 18821728 13413024
09:29:16 54484 8192000000 10070809328 10497949696 1118962048 12.017 98 33 61 0 1159452720 34088224 6402448
09:31:15 730 8192000000 10081394656 10511339520 1140679408 12.222 100 64 117 1 1183933312 29741408 13512496
09:32:17 3083 8192000000 10150160400 10576736256 1135278528 12.171 100 62 123 0 1250373648 102195248 12899872
09:33:22 13048 8192000000 10162128912 10586972160 1146230336 12.274 100 56 103 5 1257447136 99014480 12202320
09:34:42 7575 8192000000 10191364080 10653605888 1157354384 12.378 100 42 75 2 1296442144 129779168 9308592
09:35:59 196159 8192000000 10223834608 10727714816 1160948672 12.412 100 27 50 1 1330536112 163614992 5972448
09:40:20 80581 8192000000 10138025712 10674569216 1138029504 12.197 98 20 40 0 1316064576 173526880 4508192
09:42:41 99397 8192000000 10111166576 10554019840 1098949664 11.828 95 21 36 0 1135345136 32918384 3477088
09:45:21 19940 8192000000 10149695360 10590003200 1156849184 12.374 100 39 76 0 1237947808 73634352 7464272
09:46:41 60874 8192000000 10121936656 10566598656 1117544000 12.004 97 16 30 0 1201442576 80193072 3705504
09:48:42 56963 8192000000 10093489936 10530816000 1113948992 11.970 97 29 58 1 1156243760 36608640 5686128
09:50:40 30093 8192000000 10122550400 10559819776 1125431408 12.078 99 51 101 0 1194485504 58571296 10482800
09:52:12 12174 8192000000 10050410656 10475552768 1098118016 11.820 99 43 79 2 1137585968 31148896 8319056
09:53:28 65773 8192000000 10052382768 10484240384 1069872848 11.551 95 28 56 1 1106518368 31274736 5370784
09:55:35 109992 8192000000 10057357296 10479190016 1082089744 11.667 97 41 80 1 1149652192 59300736 8261712
09:58:33 34213 8192000000 10082808816 10559209472 1123699712 12.062 100 27 53 0 1192134528 63413840 5020976
10:00:09 44691 8192000000 10058807680 10524635136 1120982016 12.036 99 35 57 1 1186418144 60510032 4926096
10:01:54 54773 8192000000 10102316416 10563338240 1133124912 12.151 99 32 60 0 1199933536 60477152 6331472
10:03:53 61049 8192000000 10085425680 10557136896 1136839968 12.186 100 11 22 0 1202372432 63030656 2501808
10:06:42 71578 8192000000 10099358912 10550444032 1137134304 12.189 100 15 30 0 1214862928 74199872 3528752
10:08:59 4265 8192000000 10103511520 10525863936 1119087904 12.018 99 74 140 6 1188481968 53048992 16345072
10:10:06 19317 8192000000 10084452368 10517250048 1108407584 11.917 97 52 102 1 1154936176 35968464 10560128
10:11:32 75080 8192000000 10063360160 10507079680 1119001808 12.018 98 25 50 0 1172681024 48356304 5322912
10:13:50 34733 8192000000 10138114560 10571280384 1110365520 11.936 98 27 54 1 1208775680 92540976 5869184
10:15:29 40269 8192000000 10137144928 10610614272 1130266768 12.124 99 29 58 0 1230719696 94560736 5892192
10:17:17 86588 8192000000 10064428688 10504916992 1054384768 11.403 92 18 36 0 1101137440 43587232 3165440
10:19:49 85874 8192000000 10091640640 10549391360 1129734480 12.119 99 28 54 0 1189491664 54311072 5446112
10:22:45 54487 8192000000 10077137888 10533945344 1142605104 12.240 100 20 37 0 1193483200 46911776 3966320
10:24:43 62424 8192000000 10065186640 10514276352 1094636800 11.787 96 14 28 0 1138108032 40134336 3336896
10:26:48 89303 8192000000 10090612240 10575224832 1142645312 12.240 100 18 35 0 1191463360 45472544 3345504
10:29:21 50641 8192000000 10090341648 10543403008 1113488640 11.965 97 23 46 0 1157709056 40032224 4188192
10:31:16 27609 8192000000 10128866832 10570866688 1135340016 12.172 99 35 66 0 1217621104 74718368 7562720
10:32:53 36510 8192000000 10198561840 10637406208 1145422672 12.267 99 42 81 0 1276731216 122303632 9004912
10:34:32 30048 8192000000 10247654640 10698330112 1153415712 12.342 100 61 118 0 1327449216 161446096 12587408
10:36:06 78739 8192000000 9319517184 9680691200 409100768 4.756 36 8 17 0 479885184 68973968 1810448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-06-2022 10:38:25 to 26-06-2022 10:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-06-2022 10:38:42 to 26-06-2022 10:38:44.

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          225223
MON$OLDEST_ACTIVE               225224
MON$OLDEST_SNAPSHOT             225224
MON$NEXT_TRANSACTION            225224
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                       2094464
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2789 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2789 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2789 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2789 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Sun Jun 26 10:37:29 2022
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
--- end of diff output ---

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

26.06.22 10:38:45. 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-06-26 10:38:48 No severe PSQL-related problems occured
26.06.22 10:38:49. End of report.