Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 07: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 16439816 kB
MemAvailable 31492716 kB
Buffers 0 kB
Cached 15234868 kB
SwapCached 2540 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-04-24 07:37:14
DTS_END                         2022-04-24 07:37:14.0770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(301:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45310
TRN_ID                          212310
ATT_ID                          658
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: 8687.03 21334 1042444
customer order: creation 164.54 506 19745
customer order: refuse 14.11 425 1693
order to supplier: creation 44.09 1434 5291
order to supplier: removal 2.98 194237 358
invoice (draft): creation 44.71 3413 5365
invoice (draft): removal 48.22 1001 5786
invoice accept: apply 39.80 26277 4776
invoice accept: cancel 43.94 1395 5273
customer reserve: creation 3657.76 249 438931
customer reserve: removal 4349.13 108 521896
realization accept: apply 78.73 288 9448
realization accept: cancel 76.15 190 9138
payment from customer: creation 55.41 178 6649
payment from customer: removal 5.63 98 675
payment to supplier: creation 46.70 188 5604
payment to supplier: removal 3.09 137 371
service: total inventory turnovers 3.93 3809 471
service: total monetary turnovers 7.13 332 856
service: aggregate perf. data 0.95 15441 114
service: refresh index statistics 0.03 176982 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-04-2022 07:38:08 to 24-04-2022 07:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11054
TEST_TIME 2 8228
TEST_TIME 3 6403
TEST_TIME 4 6495
TEST_TIME 5 6208
TEST_TIME 6 5626
TEST_TIME 7 5948
TEST_TIME 8 5905
TEST_TIME 9 5957
TEST_TIME 10 5912
TEST_TIME 11 5921
TEST_TIME 12 6201
TEST_TIME 13 6309
TEST_TIME 14 6423
TEST_TIME 15 6443
TEST_TIME 16 6495
TEST_TIME 17 6564
TEST_TIME 18 6875
TEST_TIME 19 6929
TEST_TIME 20 7184
TEST_TIME 21 7602
TEST_TIME 22 7845
TEST_TIME 23 8221
TEST_TIME 24 8243
TEST_TIME 25 8689
TEST_TIME 26 8905
TEST_TIME 27 9095
TEST_TIME 28 9305
TEST_TIME 29 9417
TEST_TIME 30 9704
TEST_TIME 31 9835
TEST_TIME 32 9909
TEST_TIME 33 9891
TEST_TIME 34 9652
TEST_TIME 35 9584
TEST_TIME 36 9557
TEST_TIME 37 9405
TEST_TIME 38 9475
TEST_TIME 39 9386
TEST_TIME 40 9512
TEST_TIME 41 9356
TEST_TIME 42 9425
TEST_TIME 43 9279
TEST_TIME 44 9191
TEST_TIME 45 9111
TEST_TIME 46 8991
TEST_TIME 47 9002
TEST_TIME 48 8883
TEST_TIME 49 8782
TEST_TIME 50 8760
TEST_TIME 51 8669
TEST_TIME 52 8679
TEST_TIME 53 8701
TEST_TIME 54 8601
TEST_TIME 55 8479
TEST_TIME 56 8368
TEST_TIME 57 8530
TEST_TIME 58 8583
TEST_TIME 59 8741
TEST_TIME 60 8666
TEST_TIME 61 8706
TEST_TIME 62 8719
TEST_TIME 63 8792
TEST_TIME 64 8839
TEST_TIME 65 8747
TEST_TIME 66 8774
TEST_TIME 67 8839
TEST_TIME 68 8778
TEST_TIME 69 8768
TEST_TIME 70 8824
TEST_TIME 71 8848
TEST_TIME 72 8834
TEST_TIME 73 8763
TEST_TIME 74 8698
TEST_TIME 75 8604
TEST_TIME 76 8589
TEST_TIME 77 8549
TEST_TIME 78 8623
TEST_TIME 79 8575
TEST_TIME 80 8550
TEST_TIME 81 8556
TEST_TIME 82 8528
TEST_TIME 83 8527
TEST_TIME 84 8515
TEST_TIME 85 8527
TEST_TIME 86 8563
TEST_TIME 87 8608
TEST_TIME 88 8579
TEST_TIME 89 8571
TEST_TIME 90 8610
TEST_TIME 91 8638
TEST_TIME 92 8625
TEST_TIME 93 8705
TEST_TIME 94 8693
TEST_TIME 95 8666
TEST_TIME 96 8597
TEST_TIME 97 8562
TEST_TIME 98 8579
TEST_TIME 99 8536
TEST_TIME 100 8492
TEST_TIME 101 8467
TEST_TIME 102 8423
TEST_TIME 103 8397
TEST_TIME 104 8387
TEST_TIME 105 8392
TEST_TIME 106 8379
TEST_TIME 107 8397
TEST_TIME 108 8378
TEST_TIME 109 8417
TEST_TIME 110 8406
TEST_TIME 111 8405
TEST_TIME 112 8378
TEST_TIME 113 8420
TEST_TIME 114 8468
TEST_TIME 115 8560
TEST_TIME 116 8677
TEST_TIME 117 8777
TEST_TIME 118 8760
TEST_TIME 119 8755
TEST_TIME 120 8712

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-04-2022 07:38:11 to 24-04-2022 07:38:15.

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 1446619 1446619 0 0.00 0 18867 13 0 0
doc_list_biud 1446600 1446600 0 0.00 0 30904 45 0 0
sp_add_invoice_to_stock 4879 4776 103 2.11 50 202366 26278 0 103
sp_cancel_adding_invoice 5273 5273 0 0.00 6 110515 1395 0 0
sp_cancel_client_order 1693 1693 0 0.00 7 9153 426 0 0
sp_cancel_customer_reserve 521896 521896 0 0.00 1 30926 108 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 1298 98 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 4 4531 138 0 0
sp_cancel_supplier_invoice 5796 5786 10 0.17 3 27912 1001 0 10
sp_cancel_supplier_order 358 358 0 0.00 36 1304029 194237 0 0
sp_cancel_write_off 9138 9138 0 0.00 2 13590 190 0 0
sp_client_order 19745 19745 0 0.00 6 20190 506 0 0
sp_customer_reserve 438931 438931 0 0.00 1 39811 250 0 0
sp_fill_shopping_cart 30386 30386 0 0.00 1 17862 85 0 0
sp_fill_shopping_cart_clo_res 438896 438896 0 0.00 0 13927 20 0 0
sp_get_clo_for_invoice 4727 4727 0 0.00 0 51532 380 0 0
sp_kill_cost_storno 15457 15457 0 0.00 0 10930 81 0 0
sp_kill_qstorno_ret_qs2qd 1059074 1059074 0 0.00 0 30880 48 0 0
sp_kill_qty_storno 558317 558317 0 0.00 0 30901 109 0 0
sp_lock_selected_doc 19765 19765 0 0.00 0 7298 7 0 0
sp_make_cost_storno 26427 26427 0 0.00 0 16728 83 0 0
sp_multiply_rows_for_pdistr 26426 26426 0 0.00 0 8709 37 0 0
sp_multiply_rows_for_qdistr 19742 19742 0 0.00 0 11914 201 0 0
sp_pay_from_customer 6649 6649 0 0.00 5 15038 178 0 0
sp_pay_to_supplier 5604 5604 0 0.00 6 9951 189 0 0
sp_payment_common 12253 12253 0 0.00 2 8937 42 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 1 9140 358 0 0
sp_qd_handle_on_invoice_upd_sts 9998 9998 0 0.00 0 19165 533 0 0
sp_qd_handle_on_reserve_upd_sts 9448 9448 0 0.00 0 14331 68 0 0
sp_reserve_write_off 9448 9448 0 0.00 8 16749 288 0 0
sp_supplier_invoice 5365 5365 0 0.00 10 83963 3414 0 0
sp_supplier_order 5291 5291 0 0.00 7 41447 1435 0 0
srv_aggregate_perf_data 115 114 1 0.86 2091 166890 15442 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 797 151634 35990 0 0
srv_find_qd_qs_mism 995831 995831 0 0.00 0 18867 10 0 0
srv_make_invnt_saldo 471 471 0 0.00 38 93802 3810 0 0
srv_make_money_saldo 856 856 0 0.00 1 9837 333 0 0
srv_recalc_idx_stat 4 4 0 0.00 64788 253978 176983 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4778 4778 0 0.00 0 17340 980 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 2 8045 495 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 2474 29 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1541 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2192 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 3613 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1632 37 0 0
t$perf-norm:sp_cancel_supplier_order 358 358 0 0.00 1 96913 7735 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 1631 23 0 0
t$perf-norm:sp_client_order 19745 19745 0 0.00 0 12139 38 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 6023 24 0 0
t$perf-norm:sp_pay_from_customer 6649 6649 0 0.00 0 6720 19 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 8606 26 0 0
t$perf-norm:sp_reserve_write_off 9449 9449 0 0.00 0 11839 27 0 0
t$perf-norm:sp_supplier_invoice 5365 5365 0 0.00 0 5554 36 0 0
t$perf-norm:sp_supplier_order 5291 5291 0 0.00 0 11613 36 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 554 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1209 21 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 6557 33 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 893 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 36 205 111 0 0
v_add_invoice_to_stock 9644 9438 206 2.13 0 8213 28 0 206
v_all_customers 35982 35982 0 0.00 0 277 4 0 0
v_all_suppliers 29670 29670 0 0.00 0 69 4 0 0
v_all_wares 39481 39481 0 0.00 0 151 4 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1409 15 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1921 9 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 541 9 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 1 1046 9 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 675 10 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 3167 28 0 20
v_cancel_supplier_order 716 716 0 0.00 0 963 12 0 0
v_cancel_write_off 1994 1994 0 0.00 0 6979 28 0 0
v_max_id_clo_ord 5285 5285 0 0.00 0 6546 7 0 0
v_max_id_clo_res 937 937 0 0.00 0 2627 13 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 5194 8 0 0
v_max_non_paid_invoice 1411 1411 0 0.00 0 407 7 0 0
v_max_non_paid_realizn 3375 3375 0 0.00 0 2143 7 0 0
v_min_id_clo_ord 5285 5285 0 0.00 0 5670 10 0 0
v_min_id_clo_res 937 937 0 0.00 0 4408 16 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 4778 11 0 0
v_min_non_paid_invoice 5603 5603 0 0.00 0 3585 14 0 0
v_min_non_paid_realizn 6649 6649 0 0.00 0 7527 16 0 0
v_our_firm 10044 10044 0 0.00 0 39 4 0 0
v_reserve_write_off 18896 18896 0 0.00 0 7971 12 0 0
x_lock_depdocs_on_canc_invoice 10656 10656 0 0.00 0 35369 133 0 0
x_lock_depdocs_on_canc_sup_ord 358 358 0 0.00 0 12504 417 0 0
x_make_qstorno_1000_1200 5291 5291 0 0.00 2 41435 1256 0 0
x_make_qstorno_1200_2000 5365 5365 0 0.00 5 83942 3179 0 0
x_make_qstorno_2100_3300 438931 438931 0 0.00 0 39786 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 07:38:16 to 24-04-2022 07:38:20.

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
05:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 31229 8192000000 9740297072 10043547648 810975360 9.007 100 83 166 0 838432576 7355152 20102064
05:09:57 13604 8192000000 9771903312 10082037760 850126864 9.401 100 88 174 1 883138544 12308480 20703200
05:11:19 15750 8192000000 9807518176 10130087936 874530432 9.645 100 81 162 0 913611680 20235952 18845296
05:12:38 14657 8192000000 9870657280 10208165888 926427312 10.159 100 84 166 0 976413776 31470512 18515952
05:13:58 3449 8192000000 9931296688 10277363712 969424880 10.581 100 87 172 1 1051179936 63950224 17804832
05:15:07 1814 8192000000 9967600400 10322055168 1000779808 10.886 100 85 165 0 1078541776 61250208 16511760
05:16:11 1271 8192000000 10009704368 10373844992 1019176928 11.064 100 91 180 0 1128437392 90547664 18712800
05:17:15 1222 8192000000 10004945024 10375852032 1041925440 11.283 100 88 167 3 1127603600 68660256 17017904
05:18:18 974 8192000000 10032106288 10407350272 1048187824 11.343 100 90 176 0 1146712272 81213952 17310496
05:19:21 1443 8192000000 10040997824 10419286016 1054529904 11.404 100 90 171 0 1159797344 87105664 18161776
05:20:25 5599 8192000000 10034820208 10414899200 1062790048 11.483 100 83 160 1 1159456448 80667584 15998816
05:21:33 1440 8192000000 10040675104 10416365568 1071430336 11.566 100 98 191 1 1159249920 68428928 19390656
05:22:35 1206 8192000000 10044093312 10427482112 1072795920 11.579 100 96 180 0 1162667312 69970544 19900848
05:23:39 1219 8192000000 10075216832 10458808320 1077726352 11.626 100 94 188 0 1197498640 100148688 19623600
05:24:42 1250 8192000000 10052059040 10437574656 1080822432 11.655 100 95 185 0 1171595424 72219712 18553280
05:25:45 1252 8192000000 10052034208 10439782400 1085170864 11.697 100 95 180 1 1175924608 73142016 17611728
05:26:48 2933 8192000000 10040693616 10433896448 1083446784 11.680 100 68 132 4 1159599664 63491936 12660944
05:27:53 3169 8192000000 10060606384 10446897152 1092574176 11.767 100 86 164 0 1177044624 69380464 15089984
05:28:58 1025 8192000000 10061413248 10449883136 1087670624 11.721 100 89 173 2 1183765472 79104528 16990320
05:30:01 1500 8192000000 10046967456 10435973120 1088874096 11.732 100 90 174 3 1170395632 62605392 18916144
05:31:05 1396 8192000000 10044099968 10427502592 1075712336 11.607 100 95 181 2 1163283584 69785280 17785968
05:32:08 1322 8192000000 10016511280 10397659136 1064448384 11.499 100 78 153 0 1137060816 57463536 15148896
05:33:11 1052 8192000000 9989611312 10366717952 1037787616 11.243 100 80 155 1 1114521840 61033584 15700640
05:34:14 1012 8192000000 10016244112 10390188032 1042419680 11.288 100 94 182 1 1138580240 76735552 19425008
05:35:16 891 8192000000 9989657248 10358095872 1025358944 11.124 100 93 181 1 1111454096 67806304 18288848
05:36:20 2110 8192000000 9984728832 10361274368 1033110896 11.198 100 81 150 0 1116161216 68887920 14162400
05:37:30 5853 8192000000 9982244912 10356838400 1047298400 11.335 100 81 147 3 1101987440 41570448 13118592
05:38:44 1151 8192000000 10043026880 10423758848 1071568784 11.567 100 94 178 1 1165170560 75902304 17699472
05:39:56 1778 8192000000 10022582048 10405441536 1078748928 11.636 100 76 139 0 1143203680 50004560 14450192
05:41:02 14929 8192000000 10095769904 10485514240 1092799840 11.769 100 59 116 0 1200265408 95543232 11922336
05:42:24 5601 8192000000 10073823632 10480332800 1114117216 11.971 100 59 116 1 1207558256 80193616 13247424
05:43:35 4389 8192000000 10140451088 10527416320 1108010864 11.914 100 84 161 0 1249981312 123985664 17984784
05:44:55 2362 8192000000 10161732400 10554216448 1120257328 12.029 100 73 141 2 1278058976 142608800 15192848
05:46:01 2665 8192000000 10203028960 10601181184 1135068336 12.169 100 91 174 2 1323100528 169140224 18891968
05:47:10 1447 8192000000 10237003536 10631585792 1145180416 12.264 100 89 175 1 1348679216 184694512 18804288
05:48:18 8276 8192000000 10261614096 10666446848 1150122368 12.311 100 77 154 0 1373463920 205933488 17408064
05:49:36 1142 8192000000 10302907584 10707021824 1164134432 12.442 100 93 185 4 1407825824 222928288 20763104
05:50:41 1036 8192000000 10306269536 10715557888 1176867600 12.561 100 92 178 0 1415941008 218557632 20515776
05:51:48 1632 8192000000 10354295392 10755764224 1176673760 12.559 100 91 177 0 1463224496 266055072 20495664
05:52:54 1820 8192000000 10392638512 10802970624 1186540880 12.651 100 93 176 0 1501631440 294559664 20530896
05:53:58 1128 8192000000 10444923248 10849005568 1180530352 12.595 100 89 176 0 1553043456 351678112 20834992
05:55:03 1159 8192000000 10466078048 10891972608 1186672832 12.652 100 99 195 0 1573088016 363026512 23388672
05:56:08 793 8192000000 10466849488 10894925824 1183368016 12.622 100 88 170 1 1572453520 368687248 20398256
05:57:13 1602 8192000000 10499037296 10939015168 1182762160 12.616 100 95 188 3 1601922304 396868816 22291328
05:58:18 1095 8192000000 10450465184 10908299264 1186096224 12.647 100 98 192 2 1555905792 347070416 22739152
05:59:24 1006 8192000000 10443823408 10906722304 1181583808 12.605 100 97 190 1 1546924512 343318736 22021968
06:00:29 2131 8192000000 10411501072 10870902784 1178036400 12.572 99 67 132 2 1516529712 322360256 16133056
06:01:37 6731 8192000000 10400282032 10854109184 1159859504 12.402 99 51 102 0 1504622880 332682480 12080896
06:02:52 14184 8192000000 10318121776 10772824064 1151175904 12.321 99 48 95 0 1425996720 263005056 11815760
06:04:07 4975 8192000000 10321758496 10753609728 1161919920 12.421 100 46 90 1 1420901856 248151360 10830576
06:05:24 27840 8192000000 10242480288 10722582528 1136328880 12.181 99 21 42 0 1340832176 199140592 5362704
06:06:54 34171 8192000000 10162748192 10666901504 1132693040 12.147 99 20 40 0 1335249360 198224192 4332128
06:08:30 17785 8192000000 10112315504 10568724480 1127509632 12.098 99 35 66 3 1216621248 82669904 6441712
06:09:50 5563 8192000000 10150096144 10567536640 1150720240 12.316 100 71 126 2 1245939136 83224816 11994080
06:10:57 75642 8192000000 10180621824 10617106432 1159490720 12.398 100 50 91 1 1295532304 126418288 9623296
06:13:17 37668 8192000000 10153426816 10614124544 1136822336 12.186 100 13 26 0 1263373088 123507568 3043184
06:15:41 49792 8192000000 10140162752 10606510080 1130659648 12.128 99 22 45 0 1225553664 89670816 5223200
06:17:48 79876 8192000000 10092825872 10570186752 1108916320 11.922 97 12 24 0 1186822960 75354192 2552448
06:20:11 89000 8192000000 10078157472 10518614016 1119573648 12.023 99 21 42 0 1153985856 30150624 4261584
06:23:03 59863 8192000000 10060829248 10489630720 1088965088 11.733 97 37 72 0 1134652784 38045680 7642016
06:25:06 62110 8192000000 10061388848 10499207168 1124280384 12.067 100 15 30 0 1164719120 37326544 3112192
06:27:18 43092 8192000000 10098689584 10533875712 1107372016 11.908 98 31 58 1 1183753456 69383680 6997760
06:29:04 87318 8192000000 10085464352 10554904576 1089744752 11.740 97 13 26 2 1190192576 97412752 3035072
06:31:33 68317 8192000000 10126484880 10583658496 1121501504 12.041 100 16 32 1 1228806736 103453376 3851856
06:34:35 70981 8192000000 10114938928 10597474304 1120675104 12.033 99 12 24 0 1227350816 103825856 2849856
06:36:48 151633 8192000000 10089685776 10575699968 1052628608 11.386 93 18 36 2 1084654832 28438128 3588096
06:40:20 90222 8192000000 10063413072 10540474368 1092621696 11.768 96 6 12 0 1141041136 47438864 980576
06:42:52 4805 8192000000 10116091520 10538655744 1137593968 12.193 100 67 129 1 1209124160 57801280 13728912
06:44:07 51519 8192000000 10078918592 10540978176 1116577168 11.995 98 28 54 4 1167113776 44926000 5610608
06:46:00 94836 8192000000 10101488496 10587475968 1126514512 12.088 98 34 69 1 1200847328 67260288 7072528
06:48:43 43174 8192000000 10099388080 10527911936 1115620928 11.986 97 27 53 2 1169638016 48615216 5401872
06:50:34 77894 8192000000 10082987376 10538901504 1128943680 12.111 99 30 58 0 1183687472 48586560 6157232
06:53:06 13776 8192000000 10122293280 10543677440 1141151328 12.226 100 51 102 0 1215386720 63859152 10376240
06:54:21 74101 8192000000 10077119888 10569302016 1103571024 11.872 97 10 20 1 1208085696 102046688 2467984
06:57:12 57854 8192000000 10086765872 10537406464 1111498432 11.947 98 14 28 0 1173707744 59420368 2788944
06:59:12 41721 8192000000 10064832656 10518945792 1109640848 11.929 98 17 32 1 1157896400 44688432 3567120
07:01:14 34364 8192000000 10079574288 10532311040 1121906176 12.045 99 24 47 0 1194497200 67560448 5030576
07:02:57 94997 8192000000 10084782048 10555359232 1086280368 11.707 96 9 18 1 1157564208 69374688 1909152
07:05:50 26026 8192000000 10106330080 10523332608 1127468560 12.097 100 47 95 0 1208189696 70912896 9808240
07:07:24 63246 8192000000 10073799456 10515152896 1129158176 12.113 99 12 24 2 1180437392 48359088 2920128
07:09:31 87137 8192000000 10098579392 10554507264 1084334256 11.689 95 34 66 2 1164510768 73732800 6443712
07:11:59 56688 8192000000 10056422032 10512543744 1094987248 11.790 98 28 55 0 1129432416 28622544 5822624
07:14:18 45031 8192000000 10059367152 10499272704 1078878960 11.637 97 33 66 1 1140076848 54255792 6942096
07:16:05 24608 8192000000 10041824064 10532069376 1102119376 11.858 100 13 26 2 1152704384 48158640 2426368
07:17:30 45100 8192000000 10039896576 10499866624 1093373728 11.775 98 10 20 1 1147363024 50670160 3319136
07:19:20 60274 8192000000 10107919200 10555232256 1117118992 12.000 99 20 40 1 1202027200 79544240 5363968
07:21:21 47502 8192000000 10106949504 10567659520 1096400416 11.803 97 26 51 1 1198320400 96072448 5847536
07:23:10 30956 8192000000 10156991424 10591989760 1141210336 12.227 100 28 55 0 1256257184 108139888 6906960
07:24:41 20292 8192000000 10166446784 10594115584 1115029312 11.980 97 38 74 2 1251708192 128413504 8265376
07:26:04 25532 8192000000 10267750560 10698330112 1156660736 12.372 100 48 96 1 1343445376 175753056 11031584
07:27:31 28787 8192000000 10237806368 10660790272 1140210112 12.218 98 50 98 0 1312153440 160161360 11781968
07:29:04 17360 8192000000 10273508304 10698387456 1161510432 12.417 100 40 78 1 1362339120 191454960 9373728
07:30:24 44282 8192000000 10303773280 10761449472 1155417120 12.360 99 32 64 2 1402927776 239012480 8498176
07:32:09 26238 8192000000 10254058768 10753019904 1119465728 12.022 98 35 70 0 1354294336 226631168 8197440
07:33:52 53690 8192000000 10106863584 10667208704 1132929600 12.149 100 6 12 0 1229527856 95353760 1244496
07:35:46 87555 8192000000 10073742928 10539208704 1102776896 11.864 97 17 34 0 1137603664 31458864 3367904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-04-2022 07:38:22 to 24-04-2022 07:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 103 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-04-2022 07:38:39 to 24-04-2022 07:38:41.

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          212720
MON$OLDEST_ACTIVE               212721
MON$OLDEST_SNAPSHOT             212721
MON$NEXT_TRANSACTION            212721
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                       2055008
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:578: 
:579: mscs-test-161.budzdorov.ru    Sun Apr 24 07:37:19 2022
:580:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:581: 
:582: 
:583: mscs-test-161.budzdorov.ru    Sun Apr 24 07:37:22 2022
:584:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:585: 
--- end of diff output ---

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

24.04.22 07:38:42. 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-04-24 07:38:44 No severe PSQL-related problems occured
24.04.22 07:38:46. End of report.