Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.06.2022 04: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 16083776 kB
MemAvailable 31302180 kB
Buffers 20 kB
Cached 15591348 kB
SwapCached 2448 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-08 04:37:18
DTS_END                         2022-06-08 04:37:18.3000
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/52066
TRN_ID                          218740
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: 8935.64 24806 1072277
customer order: creation 172.55 471 20706
customer order: refuse 14.57 453 1748
order to supplier: creation 44.93 1388 5391
order to supplier: removal 3.33 182735 399
invoice (draft): creation 44.73 3289 5367
invoice (draft): removal 49.01 1085 5881
invoice accept: apply 40.73 28516 4887
invoice accept: cancel 44.69 1235 5363
customer reserve: creation 3854.97 267 462596
customer reserve: removal 4379.30 107 525516
realization accept: apply 83.02 301 9962
realization accept: cancel 80.23 186 9628
payment from customer: creation 55.95 192 6714
payment from customer: removal 5.77 103 692
payment to supplier: creation 46.69 210 5603
payment to supplier: removal 2.96 193 355
service: total inventory turnovers 3.89 4169 467
service: total monetary turnovers 7.34 377 881
service: aggregate perf. data 0.97 14919 116
service: refresh index statistics 0.04 255920 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-06-2022 04:38:41 to 08-06-2022 04:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4537
TEST_TIME 2 4738
TEST_TIME 3 5015
TEST_TIME 4 4815
TEST_TIME 5 4936
TEST_TIME 6 4640
TEST_TIME 7 4988
TEST_TIME 8 4961
TEST_TIME 9 4999
TEST_TIME 10 5140
TEST_TIME 11 5441
TEST_TIME 12 5618
TEST_TIME 13 5784
TEST_TIME 14 6004
TEST_TIME 15 6064
TEST_TIME 16 6001
TEST_TIME 17 6508
TEST_TIME 18 6689
TEST_TIME 19 6856
TEST_TIME 20 6948
TEST_TIME 21 7058
TEST_TIME 22 7361
TEST_TIME 23 7417
TEST_TIME 24 7582
TEST_TIME 25 7668
TEST_TIME 26 8060
TEST_TIME 27 8159
TEST_TIME 28 8271
TEST_TIME 29 8309
TEST_TIME 30 8661
TEST_TIME 31 9253
TEST_TIME 32 9504
TEST_TIME 33 9519
TEST_TIME 34 9837
TEST_TIME 35 10082
TEST_TIME 36 10727
TEST_TIME 37 10701
TEST_TIME 38 10693
TEST_TIME 39 10597
TEST_TIME 40 10485
TEST_TIME 41 10383
TEST_TIME 42 10205
TEST_TIME 43 10081
TEST_TIME 44 10149
TEST_TIME 45 10003
TEST_TIME 46 9888
TEST_TIME 47 9866
TEST_TIME 48 9800
TEST_TIME 49 9715
TEST_TIME 50 9839
TEST_TIME 51 9827
TEST_TIME 52 9756
TEST_TIME 53 9697
TEST_TIME 54 9575
TEST_TIME 55 9651
TEST_TIME 56 9763
TEST_TIME 57 9786
TEST_TIME 58 9730
TEST_TIME 59 9766
TEST_TIME 60 9739
TEST_TIME 61 9833
TEST_TIME 62 9953
TEST_TIME 63 9961
TEST_TIME 64 9934
TEST_TIME 65 9973
TEST_TIME 66 9898
TEST_TIME 67 9920
TEST_TIME 68 9911
TEST_TIME 69 9808
TEST_TIME 70 9675
TEST_TIME 71 9663
TEST_TIME 72 9554
TEST_TIME 73 9553
TEST_TIME 74 9567
TEST_TIME 75 9524
TEST_TIME 76 9483
TEST_TIME 77 9421
TEST_TIME 78 9344
TEST_TIME 79 9291
TEST_TIME 80 9288
TEST_TIME 81 9288
TEST_TIME 82 9301
TEST_TIME 83 9287
TEST_TIME 84 9267
TEST_TIME 85 9311
TEST_TIME 86 9270
TEST_TIME 87 9296
TEST_TIME 88 9270
TEST_TIME 89 9350
TEST_TIME 90 9311
TEST_TIME 91 9314
TEST_TIME 92 9318
TEST_TIME 93 9446
TEST_TIME 94 9371
TEST_TIME 95 9300
TEST_TIME 96 9269
TEST_TIME 97 9259
TEST_TIME 98 9214
TEST_TIME 99 9205
TEST_TIME 100 9208
TEST_TIME 101 9202
TEST_TIME 102 9214
TEST_TIME 103 9234
TEST_TIME 104 9207
TEST_TIME 105 9222
TEST_TIME 106 9198
TEST_TIME 107 9197
TEST_TIME 108 9220
TEST_TIME 109 9240
TEST_TIME 110 9267
TEST_TIME 111 9309
TEST_TIME 112 9288
TEST_TIME 113 9222
TEST_TIME 114 9190
TEST_TIME 115 9155
TEST_TIME 116 9106
TEST_TIME 117 9066
TEST_TIME 118 9039
TEST_TIME 119 9016
TEST_TIME 120 8963

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-06-2022 04:38:45 to 08-06-2022 04:38:49.

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 1497188 1497188 0 0.00 0 34587 15 0 0
doc_list_biud 1497179 1497179 0 0.00 0 32967 44 0 0
sp_add_invoice_to_stock 5041 4887 154 3.05 39 220819 28517 0 154
sp_cancel_adding_invoice 5363 5363 0 0.00 8 88415 1236 0 0
sp_cancel_client_order 1748 1748 0 0.00 5 10058 454 0 0
sp_cancel_customer_reserve 525516 525516 0 0.00 1 32969 108 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 1378 103 0 0
sp_cancel_pay_to_supplier 355 355 0 0.00 4 9496 194 0 0
sp_cancel_supplier_invoice 5894 5881 13 0.22 4 22720 1086 0 13
sp_cancel_supplier_order 399 399 0 0.00 11 1719476 182735 0 0
sp_cancel_write_off 9628 9628 0 0.00 2 19937 187 0 0
sp_client_order 20706 20706 0 0.00 6 23636 471 0 0
sp_customer_reserve 462596 462596 0 0.00 1 113079 268 0 0
sp_fill_shopping_cart 31450 31450 0 0.00 1 8512 79 0 0
sp_fill_shopping_cart_clo_res 462564 462564 0 0.00 0 26383 21 0 0
sp_get_clo_for_invoice 4847 4847 0 0.00 0 50779 319 0 0
sp_kill_cost_storno 16038 16038 0 0.00 0 31110 83 0 0
sp_kill_qstorno_ret_qs2qd 1066940 1066940 0 0.00 0 32966 47 0 0
sp_kill_qty_storno 563338 563338 0 0.00 0 32967 107 0 0
sp_lock_selected_doc 20443 20443 0 0.00 0 9602 10 0 0
sp_make_cost_storno 27126 27126 0 0.00 0 34569 91 0 0
sp_multiply_rows_for_pdistr 27120 27120 0 0.00 0 6810 43 0 0
sp_multiply_rows_for_qdistr 20706 20706 0 0.00 0 11269 182 0 0
sp_pay_from_customer 6714 6714 0 0.00 5 10831 193 0 0
sp_pay_to_supplier 5603 5603 0 0.00 6 10250 211 0 0
sp_payment_common 12317 12317 0 0.00 2 10772 52 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 1 10029 369 0 0
sp_qd_handle_on_invoice_upd_sts 10207 10207 0 0.00 0 26875 557 0 0
sp_qd_handle_on_reserve_upd_sts 9959 9959 0 0.00 0 13829 72 0 0
sp_reserve_write_off 9962 9962 0 0.00 9 15714 301 0 0
sp_supplier_invoice 5367 5367 0 0.00 10 82915 3290 0 0
sp_supplier_order 5391 5391 0 0.00 8 52691 1389 0 0
srv_aggregate_perf_data 117 116 1 0.85 2240 178606 14920 0 1
srv_fill_mon_cache_memory 73 73 0 0.00 808 112123 31489 0 0
srv_find_qd_qs_mism 1023588 1023588 0 0.00 0 20028 11 0 0
srv_make_invnt_saldo 467 467 0 0.00 54 92711 4170 0 0
srv_make_money_saldo 881 881 0 0.00 1 17950 378 0 0
srv_recalc_idx_stat 5 5 0 0.00 73950 414531 255920 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 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 4895 4895 0 0.00 1 14211 947 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 12321 464 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 2361 32 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1144 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 1308 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 355 355 0 0.00 0 4871 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 1027 37 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 99590 7625 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2998 29 0 0
t$perf-norm:sp_client_order 20707 20707 0 0.00 0 6652 40 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 547 14 0 0
t$perf-norm:sp_pay_from_customer 6714 6714 0 0.00 0 6448 21 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 5905 20 0 0
t$perf-norm:sp_reserve_write_off 9964 9964 0 0.00 0 7152 26 0 0
t$perf-norm:sp_supplier_invoice 5367 5367 0 0.00 0 5227 33 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 12253 34 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 798 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1179 50 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 2663 19 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 928 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 427 106 0 0
v_add_invoice_to_stock 9994 9686 308 3.08 0 4330 24 0 308
v_all_customers 37270 37270 0 0.00 0 153 4 0 0
v_all_suppliers 30240 30240 0 0.00 0 151 4 0 0
v_all_wares 41402 41402 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 1358 17 0 0
v_cancel_client_order 3496 3496 0 0.00 0 3342 13 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 1111 10 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 1849 12 0 0
v_cancel_payment_to_supplier 710 710 0 0.00 0 5836 24 0 0
v_cancel_supplier_invoice 738 712 26 3.52 0 1174 24 0 26
v_cancel_supplier_order 798 798 0 0.00 0 2306 17 0 0
v_cancel_write_off 2022 2022 0 0.00 0 1760 22 0 0
v_max_id_clo_ord 5388 5388 0 0.00 0 122 5 0 0
v_max_id_clo_res 990 990 0 0.00 0 1040 14 0 0
v_max_id_ord_sup 5360 5360 0 0.00 0 5036 8 0 0
v_max_non_paid_invoice 1231 1231 0 0.00 0 96 7 0 0
v_max_non_paid_realizn 3527 3527 0 0.00 0 3803 10 0 0
v_min_id_clo_ord 5388 5388 0 0.00 0 1813 7 0 0
v_min_id_clo_res 990 990 0 0.00 0 1985 14 0 0
v_min_id_ord_sup 5360 5360 0 0.00 0 5967 13 0 0
v_min_non_paid_invoice 5603 5603 0 0.00 0 5345 16 0 0
v_min_non_paid_realizn 6714 6714 0 0.00 0 7840 27 0 0
v_our_firm 10506 10506 0 0.00 0 158 5 0 0
v_reserve_write_off 19918 19918 0 0.00 0 2425 9 0 0
x_lock_depdocs_on_canc_invoice 10888 10888 0 0.00 0 17040 129 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 4468 386 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 3 52682 1218 0 0
x_make_qstorno_1200_2000 5367 5367 0 0.00 5 76506 3076 0 0
x_make_qstorno_2100_3300 462589 462589 0 0.00 0 113039 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-06-2022 04:38:50 to 08-06-2022 04:38:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 22114 8192000000 9731781184 10034638848 808894256 8.986 100 82 165 2 836148432 7160640 20093536
02:09:50 31155 8192000000 9778561216 10090070016 846942272 9.369 100 88 176 0 880034768 12392960 20699536
02:11:39 18464 8192000000 9819693152 10145722368 885043200 9.750 100 84 167 2 930840032 27041360 18755472
02:13:01 9877 8192000000 9867572176 10204860416 927258768 10.168 100 82 163 0 984437328 39592144 17586416
02:14:15 4388 8192000000 9918280944 10262392832 957719968 10.467 100 91 180 0 1035591856 59114720 18757168
02:15:21 3332 8192000000 9953693136 10306465792 994403040 10.824 100 82 164 1 1068150944 56854352 16893552
02:16:27 3040 8192000000 9999184560 10362871808 1019703728 11.069 100 84 160 2 1121385072 85334112 16347232
02:17:32 1020 8192000000 10009318944 10377908224 1049220480 11.353 100 93 175 4 1129360464 62154224 17985760
02:18:35 991 8192000000 10040464656 10415292416 1060608112 11.462 100 90 167 2 1161104480 83529664 16966704
02:19:39 945 8192000000 10042685712 10416996352 1076561776 11.615 100 95 190 2 1160579328 64065680 19951872
02:20:41 916 8192000000 10042346688 10421731328 1074914592 11.599 100 93 177 0 1161703360 68391808 18396960
02:21:44 2633 8192000000 10055614848 10442190848 1084603696 11.691 100 69 138 1 1178066832 79730560 13732576
02:22:48 1018 8192000000 10069888976 10455420928 1092757104 11.769 100 95 174 1 1186571552 74624208 19190240
02:23:50 1016 8192000000 10067495616 10453168128 1097855904 11.817 100 94 184 0 1184376848 68623808 17897136
02:24:53 997 8192000000 10078568336 10472398848 1103944256 11.875 100 91 178 0 1199932640 77945376 18043008
02:25:55 1052 8192000000 10072921104 10466209792 1105637696 11.891 100 88 170 0 1190048080 67050928 17359456
02:26:57 1213 8192000000 10067819280 10454732800 1104688944 11.882 100 95 183 1 1191157840 67803840 18665056
02:27:59 1216 8192000000 10073610832 10461429760 1101883600 11.856 100 93 182 3 1192849088 73828976 17136512
02:29:01 1097 8192000000 10077385648 10469793792 1104755936 11.883 100 88 172 1 1197895296 75692608 17446752
02:30:03 3189 8192000000 10042483680 10432872448 1090471824 11.747 100 74 142 1 1160574704 55569728 14533152
02:31:07 1529 8192000000 10038439056 10419089408 1068293344 11.536 99 90 170 3 1160626096 74403520 17929232
02:32:11 1172 8192000000 10017028384 10391662592 1060111744 11.458 100 96 187 3 1135261200 57148528 18000928
02:33:13 1252 8192000000 10023225696 10396184576 1045982640 11.322 100 98 187 0 1146081808 81127952 18971216
02:34:16 964 8192000000 10021290656 10398818304 1054849408 11.407 100 89 165 0 1139282640 68592160 15841072
02:35:17 1575 8192000000 9971662800 10343976960 1028038816 11.150 99 79 150 1 1101141840 57847792 15255232
02:36:20 1342 8192000000 9956003520 10330095616 1027410272 11.143 99 79 150 3 1080158720 36205424 16543024
02:37:23 829 8192000000 10002240112 10377592832 1066947952 11.523 100 92 167 1 1126997312 42463680 17585680
02:38:32 33925 8192000000 10059353616 10451128320 1074855616 11.598 100 59 118 0 1151099584 64388928 11855040
02:40:17 3658 8192000000 10066024896 10453368832 1093669392 11.778 100 91 166 2 1182925664 72581680 16674592
02:41:25 6421 8192000000 10104640112 10502176768 1102770032 11.864 100 56 100 3 1226641664 112087248 11784384
02:42:36 5487 8192000000 10144368448 10534748160 1112009936 11.951 100 69 134 1 1258532864 132032560 14490368
02:43:44 3624 8192000000 10190503616 10584694784 1122704672 12.053 100 72 134 1 1310441568 172604944 15131952
02:44:55 1924 8192000000 10227770768 10616164352 1132201312 12.142 100 92 178 1 1338483888 187667936 18614640
02:46:02 811 8192000000 10249631392 10643705856 1134559776 12.164 100 93 179 1 1362747552 208259552 19928224
02:47:06 1310 8192000000 10283360768 10677362688 1147686944 12.288 100 92 179 0 1397255200 229808608 19759648
02:48:13 941 8192000000 10300904784 10714071040 1161104816 12.414 100 98 194 2 1417518592 235154720 21259056
02:49:17 910 8192000000 10356333392 10752036864 1168512192 12.483 100 96 186 0 1463048160 274311856 20224112
02:50:22 1514 8192000000 10363938112 10760953856 1165275008 12.453 99 96 185 1 1474719344 288633088 20811248
02:51:28 906 8192000000 10402739664 10809892864 1175544656 12.549 100 95 184 1 1512405328 315858592 21002080
02:52:33 1412 8192000000 10444345408 10857738240 1160545648 12.408 99 90 179 1 1550381168 369407360 20428160
02:53:40 1876 8192000000 10425559728 10857594880 1172794768 12.523 100 97 187 0 1533533824 337770880 22968176
02:54:49 1066 8192000000 10424839520 10864541696 1183309680 12.621 100 91 176 0 1531279168 327332240 20637248
02:55:55 1346 8192000000 10421627232 10848923648 1174351168 12.537 100 96 188 0 1525498784 328503984 22643632
02:57:02 1305 8192000000 10453141808 10874798080 1186344400 12.649 100 95 186 0 1556501408 348594688 21562320
02:58:08 900 8192000000 10469198336 10903191552 1189458304 12.678 100 97 187 0 1574316432 361391248 23466880
02:59:13 806 8192000000 10478435584 10922422272 1187622480 12.661 100 93 183 1 1579229632 368419136 23188016
03:00:18 1365 8192000000 10494684880 10899304448 1165935504 12.459 100 92 180 1 1597385424 409747104 21702816
03:01:23 803 8192000000 10519177888 10952122368 1179812176 12.588 100 88 176 0 1624391296 423846224 20732896
03:02:28 2950 8192000000 10536877632 10984738816 1189967232 12.683 100 74 146 1 1642811200 434629568 18214400
03:03:36 15425 8192000000 10525225664 11003944960 1192667008 12.708 100 53 106 0 1671880336 465057968 14155360
03:04:57 27787 8192000000 10516588832 10978344960 1151199056 12.321 99 37 74 0 1637053152 475541552 10312544
03:06:33 5272 8192000000 10475393888 10971914240 1148599936 12.296 100 41 82 0 1623601968 463503040 11498992
03:07:44 8402 8192000000 10421320864 10980294656 1150390368 12.313 99 44 86 0 1553949360 392715936 10843056
03:08:55 18021 8192000000 10382992512 10897469440 1158528304 12.389 100 33 66 0 1494763856 327960016 8275536
03:10:14 25591 8192000000 10362468848 10818326528 1153910560 12.346 100 29 58 1 1471956256 311071168 6974528
03:11:41 34865 8192000000 10261806848 10772799488 1126308912 12.087 98 19 38 0 1379119696 248043488 4767296
03:13:19 62907 8192000000 10071729888 10579042304 1126071408 12.084 99 8 16 2 1204580656 76797328 1711920
03:15:34 40345 8192000000 10071225232 10535870464 1136873952 12.186 100 11 22 0 1175774960 36765104 2135904
03:18:00 108151 8192000000 10082425376 10521468928 1125850224 12.082 99 23 46 0 1174372624 43806400 4716000
03:20:51 60627 8192000000 10079894880 10557747200 1127456896 12.097 99 39 77 1 1200717504 64658480 8602128
03:22:58 49268 8192000000 10085814640 10506895360 1093094816 11.772 96 35 68 2 1144148752 44715376 6338560
03:24:50 6881 8192000000 10109244080 10542178304 1129045744 12.112 100 72 139 1 1216914240 71974560 15893936
03:26:03 8495 8192000000 10160136656 10592362496 1147864000 12.289 100 83 151 2 1262670624 98619152 16187472
03:27:17 8065 8192000000 10176439696 10620633088 1122513328 12.051 99 61 116 2 1281028272 146008400 12506544
03:28:32 58821 8192000000 10107154256 10569494528 1123686672 12.062 99 20 40 1 1231901248 103870592 4343984
03:30:33 44809 8192000000 10155814656 10607427584 1137165744 12.189 100 17 32 2 1255458304 114540368 3752192
03:32:19 5745 8192000000 10179115264 10622648320 1123792368 12.063 99 52 90 3 1281759440 147216432 10750640
03:33:34 79839 8192000000 10104525904 10554482688 1116954816 11.998 98 14 28 0 1184124064 64003408 3165840
03:35:55 42367 8192000000 10165432864 10625400832 1130578208 12.127 99 24 48 1 1260382752 124257600 5546944
03:38:08 46001 8192000000 10166200656 10667855872 1130482992 12.126 99 22 44 2 1270878464 135099408 5296064
03:39:58 24530 8192000000 10055604144 10501922816 1106183696 11.896 98 60 115 2 1140263824 20971728 13108400
03:41:23 17724 8192000000 10082334976 10517876736 1092648240 11.768 97 32 64 4 1159166112 60625328 5892544
03:42:44 65707 8192000000 10105554624 10553585664 1109538016 11.928 99 34 64 2 1194213120 77455760 7219344
03:44:54 112123 8192000000 10087846048 10521427968 1115106880 11.981 98 25 50 1 1158636144 38972448 4556816
03:47:54 61216 8192000000 10088121728 10538364928 1118574512 12.014 99 40 74 2 1188736848 61308320 8854016
03:49:59 31954 8192000000 10074392288 10529632256 1110150544 11.934 100 40 75 2 1180258528 61676736 8431248
03:51:33 81928 8192000000 10074914752 10541375488 1094058608 11.781 97 21 42 2 1165136224 66821504 4256112
03:54:04 43871 8192000000 10080722272 10555551744 1118849648 12.016 99 14 28 1 1182047728 60172576 3025504
03:55:55 57720 8192000000 10099245120 10568753152 1133890000 12.158 100 13 26 2 1205245072 68545392 2809680
03:57:53 72829 8192000000 10074022400 10526470144 1111306768 11.945 98 17 34 0 1164017376 48113648 4596960
04:00:07 13858 8192000000 10062539664 10502463488 1114923280 11.979 99 39 76 0 1147431872 25069568 7439024
04:01:22 9024 8192000000 10108929904 10533629952 1130645616 12.127 100 57 109 1 1206613392 64855008 11112768
04:02:34 100365 8192000000 10092081872 10580848640 1116058144 11.990 98 27 54 0 1182831808 61246400 5527264
04:05:16 55806 8192000000 10141634816 10586939392 1140134128 12.217 99 45 90 0 1222583008 72948528 9500352
04:07:12 29322 8192000000 10112949456 10553647104 1150553952 12.315 100 32 65 1 1201368656 44947952 5866752
04:08:43 15175 8192000000 10161643056 10590814208 1150706176 12.316 100 50 98 3 1248000704 87647584 9646944
04:10:03 83618 8192000000 10077106176 10520969216 1093366208 11.775 95 11 22 0 1143418944 48088768 1963968
04:12:30 55178 8192000000 10067010208 10519605248 1083484288 11.681 95 42 84 0 1148679680 56886288 8309104
04:14:38 49725 8192000000 10099004896 10561708032 1140342912 12.219 100 25 50 1 1213423264 68217520 4862832
04:18:32 22536 8192000000 10116826512 10558513152 1154366256 12.350 100 50 100 3 1200577456 35858320 10352880
04:20:03 77029 8192000000 10096465376 10575167488 1114789776 11.978 97 19 38 0 1190094096 71364976 3939344
04:22:42 36839 8192000000 10112568784 10561630208 1131577232 12.136 99 34 68 0 1212791200 74232608 6981360
04:24:23 69104 8192000000 10100702000 10588655616 1110067856 11.933 97 17 34 2 1219146064 105284864 3793344
04:26:53 46831 8192000000 10177223536 10627616768 1127124384 12.094 99 37 74 0 1222928320 88070480 7733456
04:28:56 20607 8192000000 10095046144 10525282304 1126227824 12.086 99 33 66 1 1166769232 33726272 6815136
04:30:20 85773 8192000000 10091456448 10552438784 1128429920 12.107 99 39 74 1 1186980512 51648112 6902480
04:32:54 73392 8192000000 10066905536 10517430272 1089552064 11.738 96 11 22 0 1134550096 42772560 2225472
04:35:08 43677 8192000000 10025794496 10496815104 1113996032 11.970 100 8 16 0 1152064240 36375024 1693184
04:37:16 7220 8192000000 8964048512 9197330432 131047552 1.574 12 1 2 0 134652736 3569776 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-06-2022 04:38:56 to 08-06-2022 04:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 154 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 08-06-2022 04:39:13 to 08-06-2022 04:39:15.

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          219248
MON$OLDEST_ACTIVE               219249
MON$OLDEST_SNAPSHOT             219249
MON$NEXT_TRANSACTION            219249
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                       2057184
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.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
--- end of diff output ---

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

08.06.22 04:39:16. 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-08 04:39:18 No severe PSQL-related problems occured
08.06.22 04:39:19. End of report.