Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2021 13:38
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 16643744 kB
MemAvailable 31554252 kB
Buffers 0 kB
Cached 15081284 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-12-04 13:37:09
DTS_END                         2021-12-04 13:37:09.9690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47028
TRN_ID                          220653
ATT_ID                          666
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: 8576.19 27218 1029143
customer order: creation 153.53 422 18424
customer order: refuse 12.67 363 1520
order to supplier: creation 42.11 1548 5053
order to supplier: removal 3.18 136816 382
invoice (draft): creation 42.42 4285 5090
invoice (draft): removal 50.62 1203 6074
invoice accept: apply 37.20 18720 4464
invoice accept: cancel 45.27 995 5432
customer reserve: creation 3444.52 182 413342
customer reserve: removal 4475.00 74 537000
realization accept: apply 75.93 199 9111
realization accept: cancel 79.00 135 9480
payment from customer: creation 53.64 142 6437
payment from customer: removal 5.36 109 643
payment to supplier: creation 43.23 138 5188
payment to supplier: removal 3.08 139 369
service: total inventory turnovers 3.43 3937 412
service: total monetary turnovers 5.32 572 638
service: aggregate perf. data 0.67 22577 80
service: refresh index statistics 0.03 351800 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-12-2021 13:39:07 to 04-12-2021 13:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2643
TEST_TIME 2 2543
TEST_TIME 3 3483
TEST_TIME 4 3340
TEST_TIME 5 3680
TEST_TIME 6 3935
TEST_TIME 7 4254
TEST_TIME 8 4492
TEST_TIME 9 4890
TEST_TIME 10 5011
TEST_TIME 11 5487
TEST_TIME 12 5734
TEST_TIME 13 6130
TEST_TIME 14 6374
TEST_TIME 15 6613
TEST_TIME 16 6898
TEST_TIME 17 7124
TEST_TIME 18 7319
TEST_TIME 19 7366
TEST_TIME 20 7590
TEST_TIME 21 8209
TEST_TIME 22 8217
TEST_TIME 23 8175
TEST_TIME 24 8095
TEST_TIME 25 8379
TEST_TIME 26 8424
TEST_TIME 27 8477
TEST_TIME 28 9082
TEST_TIME 29 10478
TEST_TIME 30 10467
TEST_TIME 31 10284
TEST_TIME 32 10396
TEST_TIME 33 10143
TEST_TIME 34 9921
TEST_TIME 35 9787
TEST_TIME 36 9799
TEST_TIME 37 9672
TEST_TIME 38 9678
TEST_TIME 39 9610
TEST_TIME 40 9399
TEST_TIME 41 9353
TEST_TIME 42 9583
TEST_TIME 43 9458
TEST_TIME 44 9282
TEST_TIME 45 9231
TEST_TIME 46 9170
TEST_TIME 47 9108
TEST_TIME 48 9115
TEST_TIME 49 9000
TEST_TIME 50 8876
TEST_TIME 51 8795
TEST_TIME 52 8809
TEST_TIME 53 8706
TEST_TIME 54 8707
TEST_TIME 55 8672
TEST_TIME 56 8687
TEST_TIME 57 8616
TEST_TIME 58 8565
TEST_TIME 59 8525
TEST_TIME 60 8656
TEST_TIME 61 8785
TEST_TIME 62 8781
TEST_TIME 63 8834
TEST_TIME 64 8896
TEST_TIME 65 8878
TEST_TIME 66 8914
TEST_TIME 67 8950
TEST_TIME 68 9146
TEST_TIME 69 9069
TEST_TIME 70 8968
TEST_TIME 71 8981
TEST_TIME 72 8898
TEST_TIME 73 8915
TEST_TIME 74 8862
TEST_TIME 75 8808
TEST_TIME 76 8753
TEST_TIME 77 8704
TEST_TIME 78 8671
TEST_TIME 79 8630
TEST_TIME 80 8639
TEST_TIME 81 8641
TEST_TIME 82 8687
TEST_TIME 83 8670
TEST_TIME 84 8691
TEST_TIME 85 8700
TEST_TIME 86 8675
TEST_TIME 87 8693
TEST_TIME 88 8814
TEST_TIME 89 8799
TEST_TIME 90 8790
TEST_TIME 91 8864
TEST_TIME 92 8817
TEST_TIME 93 8895
TEST_TIME 94 8829
TEST_TIME 95 8750
TEST_TIME 96 8728
TEST_TIME 97 8734
TEST_TIME 98 8686
TEST_TIME 99 8635
TEST_TIME 100 8644
TEST_TIME 101 8624
TEST_TIME 102 8600
TEST_TIME 103 8592
TEST_TIME 104 8596
TEST_TIME 105 8615
TEST_TIME 106 8603
TEST_TIME 107 8581
TEST_TIME 108 8611
TEST_TIME 109 8719
TEST_TIME 110 8698
TEST_TIME 111 8787
TEST_TIME 112 8799
TEST_TIME 113 8801
TEST_TIME 114 8757
TEST_TIME 115 8701
TEST_TIME 116 8672
TEST_TIME 117 8688
TEST_TIME 118 8685
TEST_TIME 119 8645
TEST_TIME 120 8582

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2021 13:39:11 to 04-12-2021 13:39:14.

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 1411810 1411810 0 0.00 0 86814 7 0 0
doc_list_biud 1411807 1411807 0 0.00 0 92978 36 0 0
sp_add_invoice_to_stock 4565 4464 101 2.21 35 153983 18720 0 101
sp_cancel_adding_invoice 5432 5432 0 0.00 5 99583 995 0 0
sp_cancel_client_order 1520 1520 0 0.00 5 10084 364 0 0
sp_cancel_customer_reserve 537000 537000 0 0.00 1 41883 74 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 2 3675 110 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 7162 140 0 0
sp_cancel_supplier_invoice 6085 6074 11 0.18 3 92982 1203 0 11
sp_cancel_supplier_order 382 382 0 0.00 9 1016319 136816 0 0
sp_cancel_write_off 9480 9480 0 0.00 1 15377 136 0 0
sp_client_order 18424 18424 0 0.00 4 21639 422 0 0
sp_customer_reserve 413342 413342 0 0.00 0 96787 182 0 0
sp_fill_shopping_cart 28562 28562 0 0.00 1 37882 57 0 0
sp_fill_shopping_cart_clo_res 413332 413332 0 0.00 0 20092 18 0 0
sp_get_clo_for_invoice 4454 4454 0 0.00 0 29717 441 0 0
sp_kill_cost_storno 15924 15924 0 0.00 0 21146 76 0 0
sp_kill_qstorno_ret_qs2qd 1089936 1089936 0 0.00 0 92976 41 0 0
sp_kill_qty_storno 573451 573451 0 0.00 0 92978 88 0 0
sp_lock_selected_doc 18734 18734 0 0.00 0 6957 4 0 0
sp_make_cost_storno 25190 25190 0 0.00 0 86805 64 0 0
sp_multiply_rows_for_pdistr 25189 25189 0 0.00 0 7626 37 0 0
sp_multiply_rows_for_qdistr 18424 18424 0 0.00 0 15054 203 0 0
sp_pay_from_customer 6437 6437 0 0.00 4 86822 143 0 0
sp_pay_to_supplier 5188 5188 0 0.00 5 23440 138 0 0
sp_payment_common 11624 11624 0 0.00 1 8014 27 0 0
sp_qd_handle_on_cancel_clo 1520 1520 0 0.00 0 10075 340 0 0
sp_qd_handle_on_invoice_upd_sts 9885 9885 0 0.00 0 28398 424 0 0
sp_qd_handle_on_reserve_upd_sts 9110 9110 0 0.00 0 11552 64 0 0
sp_reserve_write_off 9111 9111 0 0.00 7 18108 199 0 0
sp_supplier_invoice 5090 5090 0 0.00 7 98904 4286 0 0
sp_supplier_order 5053 5053 0 0.00 5 91960 1549 0 0
srv_aggregate_perf_data 81 80 1 1.23 2767 375744 22578 0 1
srv_fill_mon_cache_memory 58 58 0 0.00 1396 195323 52902 0 0
srv_find_qd_qs_mism 986318 986318 0 0.00 0 16748 5 0 0
srv_make_invnt_saldo 412 412 0 0.00 35 98334 3938 0 0
srv_make_money_saldo 638 638 0 0.00 1 11987 572 0 0
srv_recalc_idx_stat 4 4 0 0.00 131548 611752 351801 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4464 4464 0 0.00 0 38630 194 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 0 12487 178 0 0
t$perf-norm:sp_cancel_client_order 1520 1520 0 0.00 0 4279 18 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 1280 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1741 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 664 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 923 15 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 29694 1893 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 4247 9 0 0
t$perf-norm:sp_client_order 18424 18424 0 0.00 0 7231 22 0 0
t$perf-norm:sp_customer_reserve 892 892 0 0.00 0 532 6 0 0
t$perf-norm:sp_pay_from_customer 6437 6437 0 0.00 0 10446 11 0 0
t$perf-norm:sp_pay_to_supplier 5188 5188 0 0.00 0 10270 11 0 0
t$perf-norm:sp_reserve_write_off 9111 9111 0 0.00 0 10449 14 0 0
t$perf-norm:sp_supplier_invoice 5090 5090 0 0.00 0 8317 18 0 0
t$perf-norm:sp_supplier_order 5053 5053 0 0.00 0 7301 19 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 174 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 120 3 0 0
t$perf-norm:srv_make_invnt_saldo 412 412 0 0.00 0 2178 28 0 0
t$perf-norm:srv_make_money_saldo 638 638 0 0.00 0 819 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 8 6 0 0
v_add_invoice_to_stock 9108 8906 202 2.21 0 18079 19 0 202
v_all_customers 33838 33838 0 0.00 0 19 1 0 0
v_all_suppliers 27828 27828 0 0.00 0 17 1 0 0
v_all_wares 36848 36848 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 458 6 0 0
v_cancel_client_order 3040 3040 0 0.00 0 3737 4 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1290 6 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 0 2207 8 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 1630 9 0 0
v_cancel_supplier_invoice 762 740 22 2.88 0 448 12 0 22
v_cancel_supplier_order 764 764 0 0.00 0 18087 28 0 0
v_cancel_write_off 1834 1834 0 0.00 0 15226 27 0 0
v_max_id_clo_ord 5053 5053 0 0.00 0 375 1 0 0
v_max_id_clo_res 892 892 0 0.00 0 3415 18 0 0
v_max_id_ord_sup 5084 5084 0 0.00 0 14582 8 0 0
v_max_non_paid_invoice 1410 1410 0 0.00 0 3619 5 0 0
v_max_non_paid_realizn 3394 3394 0 0.00 0 3913 5 0 0
v_min_id_clo_ord 5053 5053 0 0.00 0 19362 12 0 0
v_min_id_clo_res 892 892 0 0.00 0 5015 17 0 0
v_min_id_ord_sup 5084 5084 0 0.00 0 7069 10 0 0
v_min_non_paid_invoice 5187 5187 0 0.00 0 4670 15 0 0
v_min_non_paid_realizn 6437 6437 0 0.00 0 7366 23 0 0
v_our_firm 9096 9096 0 0.00 0 21 1 0 0
v_reserve_write_off 18220 18220 0 0.00 0 15236 5 0 0
x_lock_depdocs_on_canc_invoice 11136 11136 0 0.00 0 12839 129 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 7370 385 0 0
x_make_qstorno_1000_1200 5053 5053 0 0.00 2 91952 1455 0 0
x_make_qstorno_1200_2000 5090 5090 0 0.00 4 98886 4062 0 0
x_make_qstorno_2100_3300 413342 413342 0 0.00 0 96785 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 13:39:15 to 04-12-2021 13:39:19.

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
11:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 35616 8192000000 9728377664 10026287104 805570032 8.953 100 89 178 0 835609520 8238016 21801472
11:09:54 19178 8192000000 9767438352 10073886720 842498160 9.325 100 88 176 0 876306912 13229120 20579632
11:11:16 20539 8192000000 9807518320 10124926976 867078720 9.571 100 82 164 1 914488208 28739280 18670208
11:12:38 11333 8192000000 9869367872 10199343104 911738448 10.014 100 78 156 0 979223168 50640480 16844240
11:13:53 8944 8192000000 9917202128 10258829312 954065184 10.431 100 74 148 0 1029578528 59593904 15919440
11:15:04 5058 8192000000 9960224368 10314149888 999449680 10.873 100 82 163 0 1075481616 59574272 16457664
11:16:11 6130 8192000000 10008817904 10370244608 1021623184 11.088 100 88 176 0 1122336816 83302832 17410800
11:17:20 2913 8192000000 10042482976 10406404096 1043825280 11.301 100 88 175 1 1151960288 89565840 18569168
11:18:24 1721 8192000000 10031196960 10404229120 1068263056 11.535 100 94 187 1 1152289232 65487232 18538944
11:19:27 2985 8192000000 10016761968 10397585408 1074843936 11.598 100 82 164 0 1130928400 37431680 18652784
11:20:31 7314 8192000000 10033522512 10439405568 1084614064 11.691 100 35 70 0 1152249120 60643696 6991360
11:21:42 7797 8192000000 9998275056 10393980928 1090190784 11.744 100 41 81 1 1122762976 24035584 8536608
11:22:53 44244 8192000000 9998788384 10413850624 1089704208 11.740 100 21 42 0 1111761872 17354720 4702944
11:24:50 7274 8192000000 10021227648 10428665856 1098415584 11.823 100 38 75 3 1129893696 22729296 8748816
11:26:01 8237 8192000000 10010405168 10429370368 1098378784 11.822 100 32 65 1 1129479920 23443664 7657472
11:27:10 5777 8192000000 9997392496 10394107904 1088052352 11.724 100 40 78 1 1111974544 15438752 8483440
11:28:16 40208 8192000000 9998466384 10406084608 1050593104 11.366 97 26 52 1 1092488768 35731264 6164400
11:30:00 2134 8192000000 9985294272 10364166144 1035337632 11.220 98 61 123 1 1092096960 44567632 12191696
11:31:05 7030 8192000000 9937154608 10315419648 994153328 10.822 97 38 76 1 1023836688 21141888 8541472
11:32:13 8351 8192000000 9966352176 10358788096 1028872912 11.158 100 40 78 0 1063391888 26110544 8408432
11:33:24 32635 8192000000 9957007056 10349416448 1010710160 10.982 97 21 40 1 1043365104 28416288 4238656
11:34:57 3690 8192000000 9961770576 10344288256 1049319728 11.354 100 59 119 1 1077413856 13949488 14144640
11:36:05 12960 8192000000 9968329472 10368000000 1060951120 11.466 100 28 56 0 1089226752 22059328 6216304
11:37:21 143032 8192000000 10043055168 10450624512 1087257152 11.717 100 33 65 0 1116355456 22168912 6929392
11:40:46 14966 8192000000 10069000784 10469199872 1105526192 11.890 100 41 77 2 1177493744 63387104 8580448
11:42:05 10942 8192000000 10129860176 10528034816 1120394800 12.031 100 61 121 1 1230371216 96779280 13197136
11:43:33 27199 8192000000 10159409616 10579980288 1136823872 12.186 100 54 108 1 1270240304 121239968 12176464
11:45:12 17286 8192000000 10220212464 10628091904 1146036320 12.272 100 50 98 0 1323042080 166311024 10694736
11:46:35 32015 8192000000 10239466976 10666258432 1164841536 12.449 100 46 92 1 1345927248 170209600 10876112
11:48:18 7614 8192000000 10234073072 10658177024 1161577856 12.418 100 51 102 0 1343042048 169372480 12091712
11:49:28 2755 8192000000 10254761200 10663358464 1160591072 12.409 100 63 125 0 1362096224 187660640 13844512
11:50:35 5114 8192000000 10306906624 10705215488 1174490576 12.539 100 82 164 0 1406553088 214106576 17955936
11:51:44 2853 8192000000 10307306336 10713206784 1188174160 12.666 100 78 155 0 1408199184 202357152 17667872
11:52:51 2980 8192000000 10375181648 10778820608 1185260688 12.639 100 92 183 0 1470603984 264438576 20904720
11:53:59 2880 8192000000 10363065312 10771562496 1177389040 12.566 100 79 159 0 1465019328 269452640 18177648
11:55:07 5827 8192000000 10341870192 10749652992 1148886208 12.299 99 69 138 0 1438204000 272743312 16574480
11:56:20 1396 8192000000 10394440672 10794233856 1159720096 12.401 100 88 178 0 1492281120 312071792 20489232
11:57:28 1979 8192000000 10443027168 10871136256 1166425904 12.463 100 82 164 1 1536685952 350006960 20253088
11:58:35 14444 8192000000 10393034912 10823532544 1163369344 12.435 100 38 76 0 1486709552 313585744 9754464
12:00:33 6037 8192000000 10460348320 10883997696 1188650128 12.671 100 47 94 0 1555984016 356015104 11318784
12:01:41 8067 8192000000 10498446240 10944532480 1164532976 12.446 99 42 84 0 1581476800 406672240 10271584
12:03:00 5216 8192000000 10463077168 10881945600 1137978112 12.197 98 53 107 0 1548980384 398134416 12867856
12:04:08 24876 8192000000 10473887456 10936569856 1112615408 11.957 99 36 72 0 1562494640 439567664 10311568
12:05:34 14534 8192000000 10392946784 10864431104 1107720272 11.911 99 27 54 0 1507248176 391913728 7614176
12:06:52 29715 8192000000 10090394352 10534780928 1093831536 11.779 98 26 53 1 1162769664 63123280 5814848
12:08:22 53956 8192000000 10105109712 10522415104 1057740224 11.435 94 23 45 1 1159728096 96792368 5195504
12:10:18 96352 8192000000 10090077984 10528481280 1099628496 11.834 98 21 40 2 1161111312 56331056 5151760
12:14:26 63753 8192000000 10125879600 10576027648 1093855920 11.779 97 20 40 0 1196312960 98134144 4322896
12:16:39 195302 8192000000 10039739856 10529251328 1082287808 11.669 97 21 41 2 1177239376 90459824 4491744
12:20:55 46568 8192000000 10086041616 10513395712 1131426608 12.135 100 34 67 0 1171709168 33790000 6492560
12:22:47 63794 8192000000 10084816832 10513616896 1118154208 12.010 99 19 38 0 1182465264 60524736 3786320
12:24:55 136566 8192000000 10084196256 10529189888 1117181344 12.000 98 18 35 1 1214630096 93522320 3926432
12:28:19 78253 8192000000 10085968368 10555842560 1135210608 12.170 100 25 51 0 1187335312 46921072 5203632
12:30:38 47295 8192000000 10052963664 10518634496 1136247760 12.180 100 10 20 0 1173740640 35187840 2305040
12:32:28 83803 8192000000 10055253776 10528210944 1135172784 12.170 99 19 38 0 1175776480 36542736 4060960
12:34:55 31050 8192000000 10118511632 10559090688 1144695840 12.260 99 25 50 0 1209065152 58963776 5405536
12:36:39 51356 8192000000 10163145504 10630733824 1150167504 12.311 100 27 54 0 1285690336 129028272 6494560
12:39:21 34439 8192000000 10092533248 10543054848 1128734160 12.109 98 15 30 1 1165450560 34010752 2705648
12:40:59 94825 8192000000 10068821616 10541309952 1060902672 11.465 92 10 20 0 1109727392 47022448 1802272
12:43:34 88548 8192000000 10140783712 10630942720 1119974384 12.027 98 12 24 0 1267787968 144816304 2997280
12:46:04 163428 8192000000 10093800240 10547531776 1098930112 11.827 96 20 39 0 1149226544 46539360 3757072
12:50:22 53323 8192000000 10026434576 10501382144 1068734208 11.540 95 24 49 1 1112509248 38554352 5220688
12:52:20 65753 8192000000 10073241376 10521874432 1124730832 12.072 100 14 28 0 1166722304 38737568 3253904
12:54:26 70760 8192000000 10096511456 10566463488 1127627072 12.099 99 19 38 2 1207042480 74769136 4646272
12:57:06 105042 8192000000 10070685344 10554363904 1109658032 11.929 98 14 28 0 1197197536 84238544 3300960
12:59:52 46552 8192000000 10073460672 10516455424 1081049776 11.657 96 15 29 3 1132278928 48137360 3091792
13:01:39 71154 8192000000 10086304448 10550190080 1088210560 11.726 97 30 60 0 1150093504 54914832 6968112
13:03:54 70257 8192000000 10124451904 10582409216 1083673792 11.682 97 34 66 0 1206602528 115778496 7150240
13:06:13 32628 8192000000 10043832784 10475196416 1094525520 11.786 98 25 49 1 1135393696 35963312 4904864
13:07:50 27368 8192000000 10119812288 10549645312 1117825920 12.006 99 56 112 2 1186097008 57144688 11126400
13:09:17 54583 8192000000 10089709808 10533081088 1094950416 11.790 97 30 60 1 1154358240 53517056 5890768
13:11:14 118808 8192000000 10032802864 10488561664 1092477424 11.766 98 14 28 0 1128838400 33090208 3270768
13:14:28 54852 8192000000 10049720960 10523299840 1118029184 12.008 100 10 20 0 1157720080 37238368 2452528
13:18:02 83907 8192000000 10078427360 10536726528 1105185184 11.887 98 19 37 2 1187835552 78167024 4483344
13:20:34 57913 8192000000 10102741088 10560815104 1130897488 12.130 99 17 33 1 1197454560 62758144 3798928
13:22:33 71408 8192000000 10111317728 10574172160 1136305264 12.181 100 15 30 0 1228932000 89222352 3404384
13:24:44 37579 8192000000 10180292512 10623094784 1148256048 12.293 100 21 41 1 1264557296 111701936 4599312
13:26:26 72253 8192000000 10117130944 10550919168 1113634384 11.967 97 22 44 0 1174675328 56274944 4766000
13:28:38 69603 8192000000 10068258720 10529943552 1116016816 11.989 98 24 46 2 1197456784 76193056 5246912
13:30:55 153136 8192000000 10089463136 10526068736 1046393648 11.326 92 31 62 0 1111493104 58754816 6344640
13:34:29 40345 8192000000 10063384752 10508759040 1108087392 11.914 98 16 32 1 1156422096 45128320 3206384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-12-2021 13:39:21 to 04-12-2021 13:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 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 04-12-2021 13:39:36 to 04-12-2021 13:39:38.

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          219732
MON$OLDEST_ACTIVE               222361
MON$OLDEST_SNAPSHOT             222361
MON$NEXT_TRANSACTION            222361
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                       2051616
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Dec  4 13:37:15 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Dec  4 13:37:33 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

04.12.21 13:39:39. 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-12-04 13:39:41 No severe PSQL-related problems occured
04.12.21 13:39:43. End of report.