Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 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 16498660 kB
MemAvailable 31607252 kB
Buffers 0 kB
Cached 15159680 kB
SwapCached 0 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.1
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 2021-11-14 10:37:07
DTS_END                         2021-11-14 10:37:07.3350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41606
TRN_ID                          242831
ATT_ID                          674
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: 9092.54 22290 1091105
customer order: creation 177.41 347 21289
customer order: refuse 14.98 308 1797
order to supplier: creation 47.62 1374 5714
order to supplier: removal 3.24 114733 389
invoice (draft): creation 47.47 3373 5696
invoice (draft): removal 52.04 863 6245
invoice accept: apply 42.61 18345 5113
invoice accept: cancel 46.84 829 5621
customer reserve: creation 3841.25 183 460950
customer reserve: removal 4523.20 65 542784
realization accept: apply 85.75 180 10290
realization accept: cancel 78.23 123 9388
payment from customer: creation 61.58 99 7390
payment from customer: removal 6.11 75 733
payment to supplier: creation 50.04 125 6005
payment to supplier: removal 3.46 70 415
service: total inventory turnovers 3.33 4335 399
service: total monetary turnovers 6.55 631 786
service: aggregate perf. data 0.81 21766 97
service: refresh index statistics 0.03 277979 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 10:38:03 to 14-11-2021 10:38:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2738
TEST_TIME 2 4021
TEST_TIME 3 5210
TEST_TIME 4 5373
TEST_TIME 5 5660
TEST_TIME 6 5988
TEST_TIME 7 5960
TEST_TIME 8 6906
TEST_TIME 9 7234
TEST_TIME 10 7503
TEST_TIME 11 8182
TEST_TIME 12 8066
TEST_TIME 13 8164
TEST_TIME 14 8031
TEST_TIME 15 8194
TEST_TIME 16 8407
TEST_TIME 17 8509
TEST_TIME 18 8636
TEST_TIME 19 9091
TEST_TIME 20 9008
TEST_TIME 21 9132
TEST_TIME 22 9300
TEST_TIME 23 9208
TEST_TIME 24 9331
TEST_TIME 25 9413
TEST_TIME 26 9922
TEST_TIME 27 9794
TEST_TIME 28 10877
TEST_TIME 29 11280
TEST_TIME 30 11269
TEST_TIME 31 11271
TEST_TIME 32 11320
TEST_TIME 33 11093
TEST_TIME 34 10803
TEST_TIME 35 10580
TEST_TIME 36 10650
TEST_TIME 37 10487
TEST_TIME 38 10380
TEST_TIME 39 10341
TEST_TIME 40 10390
TEST_TIME 41 10345
TEST_TIME 42 10327
TEST_TIME 43 10212
TEST_TIME 44 10171
TEST_TIME 45 10063
TEST_TIME 46 9989
TEST_TIME 47 9965
TEST_TIME 48 9948
TEST_TIME 49 9961
TEST_TIME 50 9949
TEST_TIME 51 9987
TEST_TIME 52 9914
TEST_TIME 53 10019
TEST_TIME 54 9919
TEST_TIME 55 10088
TEST_TIME 56 10173
TEST_TIME 57 10189
TEST_TIME 58 10157
TEST_TIME 59 10187
TEST_TIME 60 10059
TEST_TIME 61 9988
TEST_TIME 62 9890
TEST_TIME 63 9890
TEST_TIME 64 9825
TEST_TIME 65 9749
TEST_TIME 66 9653
TEST_TIME 67 9683
TEST_TIME 68 9620
TEST_TIME 69 9627
TEST_TIME 70 9612
TEST_TIME 71 9601
TEST_TIME 72 9613
TEST_TIME 73 9651
TEST_TIME 74 9727
TEST_TIME 75 9756
TEST_TIME 76 9785
TEST_TIME 77 9754
TEST_TIME 78 9740
TEST_TIME 79 9802
TEST_TIME 80 9694
TEST_TIME 81 9619
TEST_TIME 82 9618
TEST_TIME 83 9626
TEST_TIME 84 9549
TEST_TIME 85 9486
TEST_TIME 86 9411
TEST_TIME 87 9459
TEST_TIME 88 9445
TEST_TIME 89 9427
TEST_TIME 90 9388
TEST_TIME 91 9372
TEST_TIME 92 9369
TEST_TIME 93 9418
TEST_TIME 94 9501
TEST_TIME 95 9543
TEST_TIME 96 9535
TEST_TIME 97 9507
TEST_TIME 98 9522
TEST_TIME 99 9542
TEST_TIME 100 9504
TEST_TIME 101 9440
TEST_TIME 102 9368
TEST_TIME 103 9370
TEST_TIME 104 9325
TEST_TIME 105 9269
TEST_TIME 106 9292
TEST_TIME 107 9278
TEST_TIME 108 9223
TEST_TIME 109 9172
TEST_TIME 110 9103
TEST_TIME 111 9146
TEST_TIME 112 9132
TEST_TIME 113 9111
TEST_TIME 114 9097
TEST_TIME 115 9123
TEST_TIME 116 9143
TEST_TIME 117 9144
TEST_TIME 118 9163
TEST_TIME 119 9143
TEST_TIME 120 9093

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2021 10:38:06 to 14-11-2021 10:38:10.

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 1519197 1519197 0 0.00 0 30609 7 0 0
doc_list_biud 1519185 1519185 0 0.00 0 37239 30 0 0
sp_add_invoice_to_stock 5277 5113 164 3.10 30 199134 18346 0 164
sp_cancel_adding_invoice 5621 5621 0 0.00 4 58370 830 0 0
sp_cancel_client_order 1797 1797 0 0.00 4 11495 309 0 0
sp_cancel_customer_reserve 542784 542784 0 0.00 1 24761 66 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 3 1822 75 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4571 71 0 0
sp_cancel_supplier_invoice 6258 6245 13 0.20 2 19825 864 0 13
sp_cancel_supplier_order 389 389 0 0.00 5 990093 114733 0 0
sp_cancel_write_off 9388 9388 0 0.00 1 10572 124 0 0
sp_client_order 21289 21289 0 0.00 4 73575 347 0 0
sp_customer_reserve 460950 460950 0 0.00 0 89603 184 0 0
sp_fill_shopping_cart 32687 32687 0 0.00 1 12004 43 0 0
sp_fill_shopping_cart_clo_res 460949 460949 0 0.00 0 18611 15 0 0
sp_get_clo_for_invoice 5112 5112 0 0.00 0 40111 359 0 0
sp_kill_cost_storno 16157 16157 0 0.00 0 6450 55 0 0
sp_kill_qstorno_ret_qs2qd 1101590 1101590 0 0.00 0 24757 35 0 0
sp_kill_qty_storno 581615 581615 0 0.00 0 37239 76 0 0
sp_lock_selected_doc 21302 21302 0 0.00 0 11249 6 0 0
sp_make_cost_storno 28797 28797 0 0.00 0 30523 55 0 0
sp_multiply_rows_for_pdistr 28785 28785 0 0.00 0 9617 32 0 0
sp_multiply_rows_for_qdistr 21289 21289 0 0.00 0 14872 181 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 10159 100 0 0
sp_pay_to_supplier 6005 6005 0 0.00 5 11938 126 0 0
sp_payment_common 13394 13394 0 0.00 1 9612 20 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 11485 285 0 0
sp_qd_handle_on_invoice_upd_sts 10722 10722 0 0.00 0 37237 357 0 0
sp_qd_handle_on_reserve_upd_sts 10290 10290 0 0.00 0 32882 59 0 0
sp_reserve_write_off 10290 10290 0 0.00 7 32916 180 0 0
sp_supplier_invoice 5696 5696 0 0.00 5 106695 3373 0 0
sp_supplier_order 5714 5714 0 0.00 6 40268 1375 0 0
srv_aggregate_perf_data 98 97 1 1.02 2715 558086 21766 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1877 158188 45714 0 0
srv_find_qd_qs_mism 1043135 1043135 0 0.00 0 13403 6 0 0
srv_make_invnt_saldo 399 399 0 0.00 55 91936 4336 0 0
srv_make_money_saldo 786 786 0 0.00 1 27023 631 0 0
srv_recalc_idx_stat 4 4 0 0.00 195477 392062 277980 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5113 5113 0 0.00 0 17960 169 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 2465 87 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 1471 11 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 6366 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 465 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 3192 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1100 14 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 20284 1496 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 2378 12 0 0
t$perf-norm:sp_client_order 21289 21289 0 0.00 0 9444 17 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 5404 10 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 3513 8 0 0
t$perf-norm:sp_pay_to_supplier 6005 6005 0 0.00 0 4727 7 0 0
t$perf-norm:sp_reserve_write_off 10290 10290 0 0.00 0 8198 7 0 0
t$perf-norm:sp_supplier_invoice 5696 5696 0 0.00 0 3404 15 0 0
t$perf-norm:sp_supplier_order 5714 5714 0 0.00 0 4321 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 57 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 286 9 0 0
t$perf-norm:srv_make_invnt_saldo 399 399 0 0.00 0 1463 10 0 0
t$perf-norm:srv_make_money_saldo 786 786 0 0.00 0 4066 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 10530 10202 328 3.11 0 6308 21 0 328
v_all_customers 38430 38430 0 0.00 0 22 1 0 0
v_all_suppliers 31338 31338 0 0.00 0 155 1 0 0
v_all_wares 42576 42576 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 854 7 0 0
v_cancel_client_order 3594 3594 0 0.00 0 1478 4 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1814 7 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 1722 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 4560 9 0 0
v_cancel_supplier_invoice 826 800 26 3.14 0 1667 18 0 26
v_cancel_supplier_order 778 778 0 0.00 0 7412 19 0 0
v_cancel_write_off 2106 2106 0 0.00 0 3477 13 0 0
v_max_id_clo_ord 5714 5714 0 0.00 0 4379 2 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 2969 12 0 0
v_max_id_ord_sup 5685 5685 0 0.00 0 4788 7 0 0
v_max_non_paid_invoice 1734 1734 0 0.00 0 328 2 0 0
v_max_non_paid_realizn 4113 4113 0 0.00 0 623 3 0 0
v_min_id_clo_ord 5714 5714 0 0.00 0 2394 6 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2143 8 0 0
v_min_id_ord_sup 5685 5685 0 0.00 0 5609 8 0 0
v_min_non_paid_invoice 6004 6004 0 0.00 0 4669 12 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 9609 17 0 0
v_our_firm 10700 10700 0 0.00 0 74 1 0 0
v_reserve_write_off 20580 20580 0 0.00 0 3965 6 0 0
x_lock_depdocs_on_canc_invoice 11466 11466 0 0.00 0 23362 109 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 21245 412 0 0
x_make_qstorno_1000_1200 5714 5714 0 0.00 2 40238 1285 0 0
x_make_qstorno_1200_2000 5696 5696 0 0.00 2 99314 3214 0 0
x_make_qstorno_2100_3300 460950 460950 0 0.00 0 89602 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 10:38:11 to 14-11-2021 10:38:15.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 29815 8192000000 9715394176 10011553792 793959904 8.835 100 85 170 0 822452240 7824080 20668256
08:09:49 12449 8192000000 9753154624 10057846784 827610688 9.175 100 90 180 0 862404784 12767840 22026256
08:11:13 22762 8192000000 9806127120 10127151104 872394304 9.624 100 73 146 0 911612480 22613088 16605088
08:12:38 12977 8192000000 9865903920 10199658496 921901744 10.115 100 81 162 1 979040176 40086448 17051984
08:13:55 11779 8192000000 9938308960 10283794432 962564560 10.514 100 86 172 2 1049573488 68849312 18159616
08:15:10 4155 8192000000 9959990496 10313515008 989641984 10.778 100 72 144 0 1069001520 64739712 14619824
08:16:16 3414 8192000000 10002007664 10364211200 1019268192 11.065 100 83 165 0 1119221376 83762448 16190736
08:17:20 1988 8192000000 10005641008 10373152768 1048846544 11.350 100 89 179 1 1121679552 54644560 18188448
08:18:24 1332 8192000000 10059935616 10430488576 1063705200 11.492 100 90 180 0 1170820240 89115520 17999520
08:19:26 12497 8192000000 9977510224 10373353472 1068556176 11.538 100 34 68 0 1096207312 20890240 6760896
08:20:39 7329 8192000000 9986058784 10392801280 1075750848 11.607 100 35 71 0 1104378496 21082464 7545184
08:21:47 8040 8192000000 9998744528 10409037824 1085167120 11.697 100 28 55 0 1113528096 22674208 5686768
08:22:57 65475 8192000000 10012531872 10404691968 1090792608 11.750 100 46 89 2 1112134336 11936256 9405472
08:25:02 4865 8192000000 10023258400 10421153792 1103341888 11.869 100 41 81 1 1129110256 16626560 9141808
08:26:09 3945 8192000000 10033738768 10442838016 1083647424 11.682 99 29 58 2 1135322960 46138496 5537040
08:27:15 58716 8192000000 9996303744 10402971648 1070227664 11.554 98 32 65 1 1091849760 14657312 6964784
08:29:17 7758 8192000000 9970321808 10356260864 1042790000 11.291 99 26 52 0 1072197312 23445776 5961536
08:30:26 3945 8192000000 9928662256 10304090112 1008481072 10.961 99 48 96 2 1036830352 16410464 11938816
08:31:31 9387 8192000000 9941151104 10323034112 990826352 10.789 97 38 76 0 1024598000 25250240 8521408
08:32:43 40346 8192000000 9938457888 10332758016 1027842464 11.148 99 29 54 1 1055111152 21702480 5566208
08:34:24 5884 8192000000 9974116272 10360688640 1050994496 11.370 100 32 58 3 1084669968 27548912 6126560
08:35:30 15377 8192000000 9966986192 10366341120 1058733488 11.444 100 21 43 1 1090618784 27400032 4485264
08:36:46 60974 8192000000 9973571520 10367950848 1067871696 11.532 100 25 49 0 1085679232 11859040 5948496
08:39:20 8185 8192000000 10055596240 10451124224 1097863760 11.817 100 54 108 1 1158362160 49434496 11063904
08:40:38 2970 8192000000 10081313472 10477260800 1118132896 12.009 100 55 107 1 1186508224 57985328 10390000
08:41:53 12932 8192000000 10139996928 10547208192 1132571104 12.146 100 50 100 0 1240536832 97618896 10346832
08:43:26 24261 8192000000 10124333968 10535170048 1146502112 12.277 100 40 80 2 1236015616 80369136 9144368
08:44:56 3633 8192000000 10197340784 10596184064 1143588240 12.249 100 70 140 4 1297878304 139362704 14927360
08:46:03 5640 8192000000 10198534672 10606645248 1157135872 12.376 100 58 117 0 1301552528 131965808 12450848
08:47:11 1877 8192000000 10184512784 10592284672 1140724896 12.222 99 82 161 0 1289471552 131930832 16815824
08:48:16 2806 8192000000 10226943008 10621509632 1143548640 12.249 100 83 165 0 1345142528 183552688 18041200
08:49:21 3841 8192000000 10210603120 10606108672 1122284832 12.049 99 61 120 1 1301412512 165710752 13416928
08:50:27 4416 8192000000 10233322720 10636607488 1136500656 12.183 100 64 125 1 1343598960 192722528 14375776
08:51:36 7912 8192000000 10275457392 10676895744 1128690848 12.109 100 55 110 1 1371889744 231282272 11916624
08:52:49 4119 8192000000 10276439280 10665283584 1102097856 11.858 99 82 162 0 1365201888 244350672 18753360
08:54:02 3922 8192000000 10305101712 10700193792 1127785424 12.100 100 69 139 2 1407617120 263843872 15987824
08:55:11 4140 8192000000 10274671536 10671394816 1124425296 12.069 100 69 137 1 1374198976 234467008 15306672
08:56:21 2677 8192000000 10335616816 10729091072 1142080480 12.235 100 83 164 3 1428093184 266627920 19384784
08:57:30 4823 8192000000 10302341024 10704957440 1147345856 12.285 100 63 122 1 1393023648 230697856 14979936
08:58:39 3714 8192000000 10303139872 10707877888 1133447280 12.154 100 45 88 1 1401517984 257377504 10693200
08:59:49 7253 8192000000 10326282752 10748133376 1139511872 12.211 99 39 78 0 1433881344 284514464 9855008
09:01:00 8617 8192000000 10404122112 10815176704 1141294464 12.228 99 49 97 1 1481500000 327855696 12349840
09:02:48 12853 8192000000 10401964512 10862620672 1142703184 12.241 100 31 62 0 1519529632 368164656 8661792
09:04:02 25484 8192000000 10385255168 10850643968 1134294096 12.162 100 23 46 1 1471711488 331025632 6391760
09:05:54 54721 8192000000 10070413600 10498904064 1105455024 11.889 98 17 34 1 1225309200 116008896 3845280
09:07:50 54606 8192000000 10172555200 10631102464 1133613584 12.155 99 13 25 2 1236505664 100028192 2863888
09:09:45 37550 8192000000 10054980032 10474012672 1108503520 11.918 98 26 49 1 1130959648 18000656 4455472
09:11:27 72992 8192000000 10117553584 10539859968 1125548816 12.079 99 44 86 1 1197456288 62082128 9825344
09:13:44 82619 8192000000 10107977760 10542915584 1136418176 12.182 99 26 50 2 1202155392 60042704 5694512
09:16:07 48086 8192000000 10167196384 10633027584 1147436304 12.285 99 23 46 0 1246516896 93801456 5279136
09:18:00 41357 8192000000 10135747664 10616717312 1152446480 12.332 100 20 40 2 1228351872 71668336 4237056
09:19:42 46742 8192000000 10123866128 10582798336 1157715648 12.382 100 18 37 0 1218877744 57061408 4100688
09:22:25 62081 8192000000 10095095280 10582982656 1136330048 12.181 98 9 18 0 1187529168 49271776 1927344
09:24:31 67630 8192000000 10100357296 10574319616 1158516176 12.389 100 13 26 0 1201766064 40644768 2605120
09:26:51 47633 8192000000 10096137072 10553397248 1168272368 12.481 100 17 34 0 1205488624 32995200 4221056
09:28:39 52680 8192000000 10122574016 10577539072 1146925168 12.281 98 14 29 0 1208438208 58565728 2947312
09:30:34 52774 8192000000 10124871264 10598858752 1126459024 12.088 98 16 32 0 1178782128 49150128 3172976
09:32:37 111612 8192000000 10080946576 10539175936 1090088800 11.744 95 16 32 0 1165531248 72188752 3253696
09:35:29 32651 8192000000 10115396960 10570182656 1112608848 11.957 98 29 58 0 1183736864 65290592 5837424
09:37:02 142204 8192000000 10045259424 10478170112 1077885920 11.627 95 26 52 0 1102448400 19086960 5475520
09:40:25 52222 8192000000 10046457520 10530533376 1100934736 11.847 98 19 37 2 1161257456 56719536 3603184
09:42:25 86316 8192000000 10089012784 10536480768 1102378848 11.860 98 16 32 1 1186864688 80649744 3836096
09:44:52 64845 8192000000 10097996928 10536554496 1108762160 11.921 99 10 20 0 1191038928 79963568 2313200
09:47:03 37587 8192000000 10105545440 10540347392 1119022288 12.018 99 22 44 0 1179271296 55577552 4671456
09:48:41 58170 8192000000 10140030208 10598211584 1105402416 11.889 97 23 46 1 1202102704 91517760 5182528
09:50:40 98870 8192000000 10079394896 10521210880 1042137600 11.285 92 11 22 0 1155773696 111053856 2582240
09:53:19 52877 8192000000 10051284128 10468421632 1080666256 11.654 97 41 80 2 1121764304 32921616 8176432
09:55:15 14123 8192000000 10088833808 10511134720 1115711552 11.986 100 51 102 0 1176119936 50492880 9915504
09:56:49 111024 8192000000 10103435232 10547703808 1109830656 11.931 99 20 41 1 1161860672 48028368 4001648
09:59:40 52520 8192000000 10066025840 10563440640 1126414928 12.088 100 14 27 1 1211525616 81339840 3770848
10:03:10 63031 8192000000 10103511536 10558500864 1131428144 12.135 100 17 35 0 1188098512 53025072 3645296
10:05:14 82728 8192000000 10038190352 10513629184 1080985408 11.657 96 14 29 0 1158221328 74083392 3152528
10:07:37 48000 8192000000 10117030528 10561298432 1120700880 12.034 98 23 46 0 1187223744 61729968 4792896
10:09:32 94315 8192000000 10114929616 10581528576 1142576688 12.240 99 16 32 0 1251334432 105386192 3371552
10:12:10 136489 8192000000 10081428336 10544398336 1096637728 11.806 96 12 24 1 1148926144 50000256 2288160
10:15:26 158188 8192000000 10096169120 10560733184 1091383904 11.756 95 27 54 0 1131979536 35261776 5333856
10:19:44 17763 8192000000 10093586448 10536382464 1146831952 12.280 100 32 64 0 1201323600 47704144 6787504
10:21:16 45900 8192000000 10098124304 10568531968 1134579248 12.165 99 8 16 0 1182056400 45749648 1727504
10:23:02 50840 8192000000 10054289648 10561638400 1112321536 11.954 98 8 16 0 1153951664 39594304 2035824
10:24:59 63861 8192000000 10045440448 10509213696 1123698640 12.062 99 8 17 0 1144936272 19913888 1323744
10:27:03 92138 8192000000 10082268784 10565971968 1118450848 12.012 98 12 24 0 1176987040 56096112 2440080
10:29:38 52160 8192000000 10066651872 10511572992 1052099840 11.381 92 27 54 0 1093288672 35480928 5707904
10:31:31 57124 8192000000 10088591392 10531262464 1079547952 11.643 96 13 26 0 1156818224 74371472 2898800
10:33:31 29359 8192000000 10075013616 10543906816 1108902608 11.922 99 34 67 1 1199892000 84212752 6776640
10:35:01 47031 8192000000 10060115552 10518364160 1062113728 11.477 95 26 52 0 1108082160 40984656 4983776
10:36:52 33157 8192000000 9075633472 9339711488 214795104 2.555 20 6 11 0 233178864 17444000 939760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-11-2021 10:38:17 to 14-11-2021 10:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 164 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2021 10:38:32 to 14-11-2021 10:38:34.

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          243027
MON$OLDEST_ACTIVE               243425
MON$OLDEST_SNAPSHOT             243425
MON$NEXT_TRANSACTION            243425
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                       2071584
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             816
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Sun Nov 14 10:37:27 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
--- end of diff output ---

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

14.11.21 10:38:35. 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
2021-11-14 10:38:37 No severe PSQL-related problems occured
14.11.21 10:38:39. End of report.