Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.06.2022 10: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 16096520 kB
MemAvailable 31262424 kB
Buffers 0 kB
Cached 15580520 kB
SwapCached 2456 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-11 10:37:20
DTS_END                         2022-06-11 10:37:20.1120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45288
TRN_ID                          210553
ATT_ID                          635
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: 8386.42 23394 1006371
customer order: creation 162.68 488 19521
customer order: refuse 14.07 466 1688
order to supplier: creation 43.03 1451 5164
order to supplier: removal 3.22 164390 386
invoice (draft): creation 43.91 3403 5269
invoice (draft): removal 46.30 1067 5556
invoice accept: apply 39.49 28478 4739
invoice accept: cancel 41.37 1427 4964
customer reserve: creation 3647.88 273 437746
customer reserve: removal 4064.03 102 487683
realization accept: apply 79.08 311 9489
realization accept: cancel 79.02 186 9482
payment from customer: creation 56.63 188 6795
payment from customer: removal 5.45 168 654
payment to supplier: creation 45.84 204 5501
payment to supplier: removal 3.24 124 389
service: total inventory turnovers 3.43 4631 412
service: total monetary turnovers 6.88 475 825
service: aggregate perf. data 0.86 14883 103
service: refresh index statistics 0.04 245155 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5694
TEST_TIME 2 5913
TEST_TIME 3 5583
TEST_TIME 4 5365
TEST_TIME 5 5251
TEST_TIME 6 5399
TEST_TIME 7 5587
TEST_TIME 8 5433
TEST_TIME 9 5749
TEST_TIME 10 6063
TEST_TIME 11 6096
TEST_TIME 12 6501
TEST_TIME 13 6474
TEST_TIME 14 6548
TEST_TIME 15 6669
TEST_TIME 16 6929
TEST_TIME 17 7273
TEST_TIME 18 7530
TEST_TIME 19 7496
TEST_TIME 20 7756
TEST_TIME 21 7929
TEST_TIME 22 8223
TEST_TIME 23 8254
TEST_TIME 24 8385
TEST_TIME 25 8441
TEST_TIME 26 8410
TEST_TIME 27 8541
TEST_TIME 28 8594
TEST_TIME 29 8678
TEST_TIME 30 8703
TEST_TIME 31 8809
TEST_TIME 32 8958
TEST_TIME 33 9354
TEST_TIME 34 9408
TEST_TIME 35 9839
TEST_TIME 36 9768
TEST_TIME 37 9905
TEST_TIME 38 9786
TEST_TIME 39 9854
TEST_TIME 40 9762
TEST_TIME 41 9792
TEST_TIME 42 9719
TEST_TIME 43 9614
TEST_TIME 44 9446
TEST_TIME 45 9318
TEST_TIME 46 9336
TEST_TIME 47 9332
TEST_TIME 48 9189
TEST_TIME 49 9146
TEST_TIME 50 9158
TEST_TIME 51 9108
TEST_TIME 52 9075
TEST_TIME 53 8976
TEST_TIME 54 8879
TEST_TIME 55 8815
TEST_TIME 56 8731
TEST_TIME 57 8738
TEST_TIME 58 8700
TEST_TIME 59 8608
TEST_TIME 60 8595
TEST_TIME 61 8518
TEST_TIME 62 8480
TEST_TIME 63 8458
TEST_TIME 64 8509
TEST_TIME 65 8488
TEST_TIME 66 8450
TEST_TIME 67 8387
TEST_TIME 68 8482
TEST_TIME 69 8513
TEST_TIME 70 8580
TEST_TIME 71 8608
TEST_TIME 72 8633
TEST_TIME 73 8629
TEST_TIME 74 8712
TEST_TIME 75 8725
TEST_TIME 76 8707
TEST_TIME 77 8683
TEST_TIME 78 8727
TEST_TIME 79 8779
TEST_TIME 80 8732
TEST_TIME 81 8647
TEST_TIME 82 8614
TEST_TIME 83 8544
TEST_TIME 84 8555
TEST_TIME 85 8591
TEST_TIME 86 8550
TEST_TIME 87 8504
TEST_TIME 88 8447
TEST_TIME 89 8395
TEST_TIME 90 8512
TEST_TIME 91 8500
TEST_TIME 92 8499
TEST_TIME 93 8487
TEST_TIME 94 8502
TEST_TIME 95 8508
TEST_TIME 96 8603
TEST_TIME 97 8595
TEST_TIME 98 8591
TEST_TIME 99 8653
TEST_TIME 100 8630
TEST_TIME 101 8612
TEST_TIME 102 8620
TEST_TIME 103 8676
TEST_TIME 104 8594
TEST_TIME 105 8556
TEST_TIME 106 8585
TEST_TIME 107 8551
TEST_TIME 108 8505
TEST_TIME 109 8465
TEST_TIME 110 8436
TEST_TIME 111 8433
TEST_TIME 112 8397
TEST_TIME 113 8419
TEST_TIME 114 8446
TEST_TIME 115 8423
TEST_TIME 116 8434
TEST_TIME 117 8490
TEST_TIME 118 8455
TEST_TIME 119 8446
TEST_TIME 120 8408

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-06-2022 10:38:30 to 11-06-2022 10:38:33.

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 1409939 1409939 0 0.00 0 26221 14 0 0
doc_list_biud 1409921 1409921 0 0.00 0 38346 42 0 0
sp_add_invoice_to_stock 4836 4739 97 2.00 40 224709 28478 0 97
sp_cancel_adding_invoice 4964 4964 0 0.00 6 103761 1428 0 0
sp_cancel_client_order 1688 1688 0 0.00 6 13406 467 0 0
sp_cancel_customer_reserve 487683 487683 0 0.00 1 36249 103 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 17808 169 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 2187 125 0 0
sp_cancel_supplier_invoice 5561 5556 5 0.08 3 38358 1068 0 5
sp_cancel_supplier_order 386 386 0 0.00 8 1194929 164390 0 0
sp_cancel_write_off 9482 9482 0 0.00 2 23163 186 0 0
sp_client_order 19521 19521 0 0.00 5 56298 489 0 0
sp_customer_reserve 437746 437746 0 0.00 1 37920 273 0 0
sp_fill_shopping_cart 29935 29935 0 0.00 1 26571 96 0 0
sp_fill_shopping_cart_clo_res 437716 437716 0 0.00 0 10812 19 0 0
sp_get_clo_for_invoice 4700 4700 0 0.00 0 22987 362 0 0
sp_kill_cost_storno 15489 15489 0 0.00 0 19305 89 0 0
sp_kill_qstorno_ret_qs2qd 990790 990790 0 0.00 0 38344 46 0 0
sp_kill_qty_storno 523945 523945 0 0.00 0 38345 106 0 0
sp_lock_selected_doc 19709 19709 0 0.00 0 9905 9 0 0
sp_make_cost_storno 26485 26485 0 0.00 0 18866 88 0 0
sp_multiply_rows_for_pdistr 26482 26482 0 0.00 0 18911 41 0 0
sp_multiply_rows_for_qdistr 19519 19519 0 0.00 0 56281 181 0 0
sp_pay_from_customer 6795 6795 0 0.00 6 18856 189 0 0
sp_pay_to_supplier 5501 5501 0 0.00 6 29772 204 0 0
sp_payment_common 12293 12293 0 0.00 2 18716 54 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 1 9589 392 0 0
sp_qd_handle_on_invoice_upd_sts 9662 9662 0 0.00 0 27073 562 0 0
sp_qd_handle_on_reserve_upd_sts 9488 9488 0 0.00 0 23672 75 0 0
sp_reserve_write_off 9489 9489 0 0.00 9 29088 311 0 0
sp_supplier_invoice 5269 5269 0 0.00 11 108052 3403 0 0
sp_supplier_order 5164 5164 0 0.00 7 29477 1451 0 0
srv_aggregate_perf_data 104 103 1 0.96 2589 152430 14884 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 836 151683 34184 0 0
srv_find_qd_qs_mism 960285 960285 0 0.00 0 26218 11 0 0
srv_make_invnt_saldo 412 412 0 0.00 86 88786 4631 0 0
srv_make_money_saldo 825 825 0 0.00 1 16743 476 0 0
srv_recalc_idx_stat 5 5 0 0.00 52632 414667 245156 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 4742 4742 0 0.00 1 11375 991 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 6548 540 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 3160 34 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2703 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 886 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 624 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 913 41 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 108674 7501 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 1278 22 0 0
t$perf-norm:sp_client_order 19521 19521 0 0.00 0 8112 38 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 3060 19 0 0
t$perf-norm:sp_pay_from_customer 6795 6795 0 0.00 0 8621 24 0 0
t$perf-norm:sp_pay_to_supplier 5501 5501 0 0.00 0 5325 21 0 0
t$perf-norm:sp_reserve_write_off 9489 9489 0 0.00 0 12043 27 0 0
t$perf-norm:sp_supplier_invoice 5269 5269 0 0.00 0 8190 35 0 0
t$perf-norm:sp_supplier_order 5164 5164 0 0.00 0 5700 38 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 645 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 48 5 0 0
t$perf-norm:srv_make_invnt_saldo 412 412 0 0.00 0 8184 39 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 5574 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 21 156 71 0 0
v_add_invoice_to_stock 9576 9382 194 2.02 0 7102 22 0 194
v_all_customers 35994 35994 0 0.00 0 55 4 0 0
v_all_suppliers 29087 29087 0 0.00 0 66 4 0 0
v_all_wares 39034 39034 0 0.00 0 236 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 6110 23 0 0
v_cancel_client_order 3376 3376 0 0.00 0 1603 9 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 2308 12 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1784 12 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 355 11 0 0
v_cancel_supplier_invoice 774 764 10 1.29 0 1725 24 0 10
v_cancel_supplier_order 772 772 0 0.00 0 1274 15 0 0
v_cancel_write_off 1926 1926 0 0.00 0 3444 25 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 742 5 0 0
v_max_id_clo_res 956 956 0 0.00 0 3352 18 0 0
v_max_id_ord_sup 5262 5262 0 0.00 0 4554 10 0 0
v_max_non_paid_invoice 1372 1372 0 0.00 0 93 7 0 0
v_max_non_paid_realizn 3466 3466 0 0.00 0 1443 7 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 3231 9 0 0
v_min_id_clo_res 956 956 0 0.00 0 1015 9 0 0
v_min_id_ord_sup 5262 5262 0 0.00 0 8033 11 0 0
v_min_non_paid_invoice 5500 5500 0 0.00 0 10966 24 0 0
v_min_non_paid_realizn 6792 6792 0 0.00 0 18702 22 0 0
v_our_firm 9688 9688 0 0.00 0 57 5 0 0
v_reserve_write_off 18975 18975 0 0.00 0 3631 10 0 0
x_lock_depdocs_on_canc_invoice 10138 10138 0 0.00 0 25940 141 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 4586 411 0 0
x_make_qstorno_1000_1200 5164 5164 0 0.00 3 29405 1266 0 0
x_make_qstorno_1200_2000 5269 5269 0 0.00 5 96870 3119 0 0
x_make_qstorno_2100_3300 437744 437744 0 0.00 0 34336 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-06-2022 10:38:34 to 11-06-2022 10:38:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:23 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 23850 8192000000 9718549648 10018664448 800658432 8.903 100 84 169 1 828604208 7697104 20248672
08:09:50 16885 8192000000 9756208320 10062979072 832991296 9.229 100 89 178 0 866124640 11858336 21275008
08:11:10 20328 8192000000 9801509664 10121330688 870345456 9.603 100 88 176 0 909559728 18804272 20410000
08:12:37 17782 8192000000 9843076752 10179346432 909356112 9.991 100 76 152 1 956924624 30363904 17204608
08:14:00 4656 8192000000 9905318720 10247938048 948426512 10.376 100 81 162 0 1021995344 56204384 17364448
08:15:11 2494 8192000000 9952472880 10301530112 983081088 10.714 100 89 172 1 1065367776 64643808 17642880
08:16:15 1947 8192000000 9995839632 10355564544 1016000400 11.033 100 94 186 0 1117572672 82737488 18834784
08:17:20 1134 8192000000 10003376352 10372460544 1034740784 11.214 100 96 182 0 1122484304 69551360 18192160
08:18:23 978 8192000000 10027221328 10399277056 1052558592 11.385 100 95 187 0 1146001840 75380416 18062832
08:19:28 1249 8192000000 10031488336 10405826560 1062679600 11.482 100 96 185 0 1156471312 74642256 19149456
08:20:31 906 8192000000 10025846704 10403188736 1067723872 11.530 100 92 180 0 1146600448 60864928 18011648
08:21:32 1395 8192000000 10056316656 10441330688 1075348000 11.603 100 94 181 1 1177361632 84596432 17417200
08:22:35 1020 8192000000 10060297648 10442297344 1082117280 11.668 100 95 177 1 1183196432 82662224 18416928
08:23:37 1424 8192000000 10049791952 10434150400 1092323488 11.765 100 86 165 0 1172376304 62911632 17141184
08:24:40 1018 8192000000 10069532352 10457587712 1093674624 11.778 100 96 191 5 1190651392 77593984 19382784
08:25:42 2500 8192000000 10035574784 10431213568 1099917840 11.837 100 71 127 1 1161474224 47196128 14360256
08:26:45 8481 8192000000 10048135904 10453254144 1105339808 11.888 100 48 94 1 1175592592 60472768 9780016
08:27:54 1215 8192000000 10042943568 10429792256 1089942304 11.742 100 90 171 0 1164349248 57789808 16617136
08:28:56 1353 8192000000 10061964768 10456195072 1104485024 11.880 100 82 151 1 1187686096 67735264 15465808
08:29:58 1133 8192000000 10063184048 10457235456 1096291600 11.802 100 89 170 1 1185194080 70137520 18764960
08:30:59 1047 8192000000 10049068960 10429480960 1071019504 11.562 100 95 181 2 1170604976 81378128 18207344
08:32:02 1273 8192000000 10048455232 10432483328 1077765264 11.626 100 93 177 2 1173077776 76369920 18942592
08:33:04 1037 8192000000 10011138576 10386538496 1039521216 11.260 99 88 170 0 1134918224 74653008 20744000
08:34:06 2065 8192000000 10011239312 10394759168 1062211872 11.478 100 68 126 1 1131988976 57315200 12461904
08:35:08 4549 8192000000 10023860768 10408292352 1043369440 11.297 99 63 120 0 1134764800 79471968 11923392
08:36:16 957 8192000000 9994496672 10373427200 1048069744 11.342 100 93 180 3 1114736032 47592912 19073376
08:37:18 1151 8192000000 9981529792 10358595584 1043895792 11.302 100 80 153 1 1105232224 45258464 16077968
08:38:26 22874 8192000000 10031204064 10413760512 1071084800 11.562 100 67 120 0 1153863680 71318992 11459888
08:39:54 967 8192000000 10064862496 10442125312 1083184240 11.678 100 98 179 0 1181923472 81880176 16859056
08:40:59 5666 8192000000 10071540432 10455306240 1085010688 11.695 100 62 120 1 1183385184 86021136 12353360
08:42:08 2595 8192000000 10103019984 10491797504 1104328224 11.879 100 72 142 1 1218797776 99833136 14636416
08:43:21 5954 8192000000 10133710864 10536108032 1115167216 11.981 100 77 149 0 1250288544 119307776 15813552
08:44:36 10558 8192000000 10161775664 10560208896 1114730096 11.977 99 76 148 0 1269179504 138619248 15830160
08:46:13 4345 8192000000 10203240912 10606796800 1132281168 12.143 100 65 127 0 1314480640 167865632 14333840
08:47:22 1933 8192000000 10223496672 10624561152 1146542096 12.277 100 84 161 1 1344529424 179178528 18808800
08:48:28 3292 8192000000 10227911104 10635776000 1152400176 12.332 100 81 157 0 1343396688 174167200 16829312
08:49:40 826 8192000000 10264698448 10674421760 1159998288 12.403 100 95 185 0 1375901216 195693504 20209424
08:50:46 1993 8192000000 10275636848 10687000576 1166254336 12.462 100 90 167 1 1383123552 197971328 18897888
08:51:52 1440 8192000000 10322556416 10724958208 1184346608 12.631 100 92 176 1 1431882592 226963376 20572608
08:52:58 1396 8192000000 10331957472 10753449984 1188658736 12.671 100 97 192 2 1447099824 236977024 21464064
08:54:03 1159 8192000000 10331580384 10765471744 1189333984 12.677 100 88 166 0 1438198032 230604528 18259520
08:55:06 1234 8192000000 10339134672 10774806528 1188411280 12.669 100 97 192 0 1442840880 232819344 21610256
08:56:10 1105 8192000000 10371120672 10781868032 1202201376 12.797 100 95 186 3 1477404880 255213056 19990448
08:57:14 1318 8192000000 10381225840 10829283328 1189433088 12.678 100 96 189 2 1485789360 273745872 22610400
08:58:19 1211 8192000000 10389332240 10805489664 1193003184 12.711 100 90 178 1 1484637312 271644528 19989600
08:59:26 3765 8192000000 10323772992 10774827008 1176415968 12.557 100 68 132 0 1435062656 243120896 15525792
09:00:38 9835 8192000000 10312299120 10768498688 1173867728 12.533 100 62 120 1 1431284320 243508128 13908464
09:01:55 7704 8192000000 10333021472 10759016448 1161862704 12.421 99 60 112 3 1431243824 255923168 13457952
09:03:08 12133 8192000000 10336275376 10781872128 1158081744 12.385 100 48 93 1 1439424608 269567456 11775408
09:04:22 4991 8192000000 10344419920 10780700672 1142797648 12.242 99 59 115 0 1444259424 287209728 14252048
09:05:38 6723 8192000000 10368856416 10790723584 1146090144 12.273 98 61 120 3 1458704032 299114112 13499776
09:06:50 3429 8192000000 10358461296 10802823168 1159205440 12.396 100 66 132 1 1464186288 289829360 15151488
09:08:00 5212 8192000000 10381395408 10839736320 1161278960 12.415 100 65 128 2 1483240432 306636672 15324800
09:09:14 4916 8192000000 10407386208 10849521664 1143124368 12.245 98 60 118 1 1489786864 332224880 14437616
09:10:20 22518 8192000000 10337279120 10772561920 1161343328 12.416 100 32 64 0 1461092576 291132112 8617136
09:11:44 29691 8192000000 10237765520 10730524672 1137938672 12.196 99 36 70 2 1381509248 235025472 8545104
09:13:16 75247 8192000000 10204764864 10636550144 1113165456 11.962 98 33 62 2 1290558832 170085584 7307792
09:15:33 23402 8192000000 10133684016 10587607040 1131864576 12.139 100 56 105 0 1257040288 114128576 11047136
09:16:58 56771 8192000000 10139691552 10600652800 1123235552 12.058 99 21 41 2 1237226384 109651872 4338960
09:19:20 84340 8192000000 10102481056 10541027328 1099661504 11.834 97 22 44 0 1157044448 52459888 4923056
09:22:03 27000 8192000000 10110530496 10526679040 1125679456 12.081 100 72 141 7 1210367536 69528016 15160064
09:23:31 53809 8192000000 10091446480 10544709632 1135906800 12.177 100 25 50 1 1207443840 66334992 5202048
09:25:35 46277 8192000000 10121282032 10560966656 1138905504 12.205 100 46 87 0 1208219344 60310864 9002976
09:27:30 46831 8192000000 10072123728 10554302464 1127623952 12.099 99 13 25 2 1197187136 67261344 2301840
09:29:28 52736 8192000000 10090100240 10575556608 1139369888 12.210 100 13 26 0 1180810768 38721120 2719760
09:31:22 100315 8192000000 10095932656 10575634432 1126636480 12.090 98 16 32 1 1189212352 59484640 3091232
09:34:46 99007 8192000000 10063514560 10519019520 1120135168 12.028 98 20 40 2 1167757808 43602912 4019728
09:37:25 75716 8192000000 10103204896 10558709760 1123191616 12.057 98 24 47 1 1162771568 34950672 4629280
09:39:50 85171 8192000000 10075050336 10572042240 1140937952 12.224 100 11 22 0 1222852640 79595456 2319232
09:42:17 29541 8192000000 10083568480 10525401088 1109799840 11.931 97 17 34 1 1169134704 56368880 2965984
09:43:49 23170 8192000000 10129939024 10569703424 1135922752 12.177 99 30 60 1 1221273360 78513600 6837008
09:45:13 53663 8192000000 10143744784 10599858176 1146924944 12.281 100 30 60 1 1271936144 118786368 6224832
09:47:07 37877 8192000000 10144522976 10608140288 1132355200 12.144 100 38 75 2 1233607248 92866256 8385792
09:48:46 27689 8192000000 10096481056 10549682176 1121440576 12.041 99 37 74 0 1207144960 77790496 7913888
09:50:15 9814 8192000000 10167316112 10586923008 1140722832 12.222 100 71 140 0 1242265360 87476336 14066192
09:51:32 28968 8192000000 10104882592 10570190848 1115720624 11.987 98 39 78 0 1202640960 79252096 7668240
09:53:03 14205 8192000000 10097020544 10517884928 1111928816 11.951 98 45 86 0 1171881584 51359632 8593136
09:54:18 7554 8192000000 10105511584 10516692992 1122147104 12.047 100 87 163 3 1199007952 59739104 17121744
09:55:36 34219 8192000000 10082691280 10544267264 1103625552 11.872 98 26 52 0 1181261776 72380512 5255712
09:57:11 93558 8192000000 10044522000 10486247424 1104879488 11.884 98 19 36 0 1145761472 36827680 4054304
09:59:45 14984 8192000000 10094464208 10512232448 1122481488 12.050 99 67 130 1 1180473616 44723920 13268208
10:01:01 5125 8192000000 10133282816 10550751232 1132935392 12.149 100 63 122 1 1240657568 94879792 12842384
10:02:07 60961 8192000000 10063410016 10529521664 1101250144 11.850 98 25 50 1 1181319056 74304080 5764832
10:04:09 67948 8192000000 10108510992 10560757760 1124900416 12.073 99 17 33 0 1206301408 77811136 3589856
10:06:22 54035 8192000000 10102615440 10608164864 1119491312 12.022 99 13 26 0 1229503568 106933264 3078992
10:09:05 44561 8192000000 10049348896 10500980736 1050267664 11.363 95 21 42 0 1114664784 60054384 4342736
10:10:54 61357 8192000000 10108010208 10571354112 1093752992 11.778 98 25 50 0 1203450272 104082976 5614304
10:12:56 71029 8192000000 10060602928 10539110400 1090414608 11.747 98 8 16 1 1185849872 93763584 1671680
10:15:17 109865 8192000000 10066431568 10526445568 1095662256 11.796 97 19 38 2 1168673472 69014688 3996528
10:18:11 9113 8192000000 10102707024 10515439616 1131316064 12.134 100 59 121 1 1190154800 46245920 12592816
10:19:23 17865 8192000000 10147373632 10572492800 1115057488 11.980 99 49 96 0 1225986416 100858496 10070432
10:20:43 96923 8192000000 10086075856 10542391296 1110341152 11.936 99 17 34 0 1204018304 89641312 4035840
10:23:22 66795 8192000000 10060907264 10541264896 1102639264 11.863 98 16 32 2 1156236064 50269616 3327184
10:25:31 53499 8192000000 10086350048 10542985216 1126387360 12.087 100 16 32 0 1187095456 56836304 3871792
10:27:25 55466 8192000000 10077609488 10569973760 1133592224 12.155 100 12 23 0 1227499552 91165216 2742112
10:29:28 151661 8192000000 10108360352 10589265920 1109524624 11.928 97 15 30 0 1168368592 55626032 3217936
10:33:00 100525 8192000000 10090408320 10583961600 1128448624 12.107 98 12 24 1 1208134608 77275472 2410512
10:35:41 37460 8192000000 10120899456 10564632576 1128621360 12.108 98 33 66 2 1195034160 59403664 7009136
10:37:19 14064 8192000000 9069264944 9345040384 209460176 2.493 19 3 3 1 233645968 23751936 433856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-06-2022 10:38:40 to 11-06-2022 10:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 97 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-06-2022 10:38:58 to 11-06-2022 10:39:00.

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          212212
MON$OLDEST_ACTIVE               212213
MON$OLDEST_SNAPSHOT             212213
MON$NEXT_TRANSACTION            212213
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                       2060640
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:59: 
:60: mscs-test-161.budzdorov.ru    Sat Jun 11 10:37:35 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Jun 11 10:37:48 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

11.06.22 10:39:01. 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-11 10:39:03 No severe PSQL-related problems occured
11.06.22 10:39:04. End of report.