Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2022 13: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 14692204 kB
MemAvailable 30420748 kB
Buffers 0 kB
Cached 16981860 kB
SwapCached 1984 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.3
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-10-26 13:37:24
DTS_END                         2022-10-26 13:37:24.1010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38118
TRN_ID                          220133
ATT_ID                          672
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: 8897.80 23510 1067736
customer order: creation 171.77 479 20612
customer order: refuse 15.30 476 1836
order to supplier: creation 46.22 1405 5546
order to supplier: removal 3.15 188180 378
invoice (draft): creation 46.17 3052 5540
invoice (draft): removal 49.58 1042 5950
invoice accept: apply 42.38 27431 5085
invoice accept: cancel 44.49 1355 5339
customer reserve: creation 3887.10 262 466452
customer reserve: removal 4295.87 108 515504
realization accept: apply 83.99 279 10079
realization accept: cancel 79.88 181 9586
payment from customer: creation 59.76 185 7171
payment from customer: removal 5.99 150 719
payment to supplier: creation 49.71 197 5965
payment to supplier: removal 3.56 165 427
service: total inventory turnovers 3.98 4650 477
service: total monetary turnovers 8.02 394 962
service: aggregate perf. data 0.86 16569 103
service: refresh index statistics 0.04 223635 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2022 13:38:08 to 26-10-2022 13:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6140
TEST_TIME 2 4529
TEST_TIME 3 4704
TEST_TIME 4 5101
TEST_TIME 5 5470
TEST_TIME 6 5492
TEST_TIME 7 5779
TEST_TIME 8 5638
TEST_TIME 9 5864
TEST_TIME 10 6047
TEST_TIME 11 6256
TEST_TIME 12 6588
TEST_TIME 13 6574
TEST_TIME 14 6606
TEST_TIME 15 6798
TEST_TIME 16 6907
TEST_TIME 17 6986
TEST_TIME 18 7002
TEST_TIME 19 7217
TEST_TIME 20 7330
TEST_TIME 21 7309
TEST_TIME 22 7295
TEST_TIME 23 7516
TEST_TIME 24 7700
TEST_TIME 25 7818
TEST_TIME 26 7924
TEST_TIME 27 8325
TEST_TIME 28 8445
TEST_TIME 29 8716
TEST_TIME 30 8820
TEST_TIME 31 8801
TEST_TIME 32 8854
TEST_TIME 33 8992
TEST_TIME 34 9477
TEST_TIME 35 9903
TEST_TIME 36 9866
TEST_TIME 37 9907
TEST_TIME 38 10045
TEST_TIME 39 10437
TEST_TIME 40 10812
TEST_TIME 41 10664
TEST_TIME 42 10516
TEST_TIME 43 10656
TEST_TIME 44 10491
TEST_TIME 45 10358
TEST_TIME 46 10163
TEST_TIME 47 10219
TEST_TIME 48 10095
TEST_TIME 49 10001
TEST_TIME 50 9963
TEST_TIME 51 9878
TEST_TIME 52 9820
TEST_TIME 53 9746
TEST_TIME 54 9675
TEST_TIME 55 9614
TEST_TIME 56 9568
TEST_TIME 57 9521
TEST_TIME 58 9548
TEST_TIME 59 9440
TEST_TIME 60 9461
TEST_TIME 61 9431
TEST_TIME 62 9370
TEST_TIME 63 9435
TEST_TIME 64 9384
TEST_TIME 65 9419
TEST_TIME 66 9434
TEST_TIME 67 9414
TEST_TIME 68 9430
TEST_TIME 69 9352
TEST_TIME 70 9386
TEST_TIME 71 9408
TEST_TIME 72 9396
TEST_TIME 73 9442
TEST_TIME 74 9455
TEST_TIME 75 9519
TEST_TIME 76 9436
TEST_TIME 77 9328
TEST_TIME 78 9339
TEST_TIME 79 9262
TEST_TIME 80 9236
TEST_TIME 81 9209
TEST_TIME 82 9152
TEST_TIME 83 9093
TEST_TIME 84 9078
TEST_TIME 85 9097
TEST_TIME 86 9063
TEST_TIME 87 9052
TEST_TIME 88 9056
TEST_TIME 89 9008
TEST_TIME 90 8974
TEST_TIME 91 9024
TEST_TIME 92 9056
TEST_TIME 93 9083
TEST_TIME 94 9075
TEST_TIME 95 9116
TEST_TIME 96 9087
TEST_TIME 97 9119
TEST_TIME 98 9134
TEST_TIME 99 9162
TEST_TIME 100 9123
TEST_TIME 101 9054
TEST_TIME 102 9045
TEST_TIME 103 9033
TEST_TIME 104 8996
TEST_TIME 105 8950
TEST_TIME 106 8954
TEST_TIME 107 8903
TEST_TIME 108 8905
TEST_TIME 109 8885
TEST_TIME 110 8863
TEST_TIME 111 8843
TEST_TIME 112 8833
TEST_TIME 113 8847
TEST_TIME 114 8842
TEST_TIME 115 8895
TEST_TIME 116 8897
TEST_TIME 117 8890
TEST_TIME 118 8989
TEST_TIME 119 8982
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2022 13:38:12 to 26-10-2022 13: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 1497016 1497016 0 0.00 0 19218 15 0 0
doc_list_biud 1497003 1497003 0 0.00 0 25633 44 0 0
sp_add_invoice_to_stock 5236 5085 151 2.88 81 194115 27431 0 151
sp_cancel_adding_invoice 5339 5339 0 0.00 6 72486 1356 0 0
sp_cancel_client_order 1836 1836 0 0.00 5 12240 477 0 0
sp_cancel_customer_reserve 515504 515504 0 0.00 1 21174 108 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 4 7543 150 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 4 4586 166 0 0
sp_cancel_supplier_invoice 5968 5950 18 0.30 3 25722 1042 0 18
sp_cancel_supplier_order 378 378 0 0.00 13 1588933 188180 0 0
sp_cancel_write_off 9586 9586 0 0.00 2 10598 181 0 0
sp_client_order 20612 20612 0 0.00 6 26943 480 0 0
sp_customer_reserve 466452 466452 0 0.00 0 76833 263 0 0
sp_fill_shopping_cart 31686 31686 0 0.00 1 11409 91 0 0
sp_fill_shopping_cart_clo_res 466417 466417 0 0.00 0 17817 19 0 0
sp_get_clo_for_invoice 5040 5040 0 0.00 0 24271 280 0 0
sp_kill_cost_storno 16071 16071 0 0.00 0 19207 81 0 0
sp_kill_qstorno_ret_qs2qd 1046922 1046922 0 0.00 0 25621 46 0 0
sp_kill_qty_storno 553708 553708 0 0.00 0 25632 108 0 0
sp_lock_selected_doc 20992 20992 0 0.00 0 7433 7 0 0
sp_make_cost_storno 28253 28253 0 0.00 0 19062 79 0 0
sp_multiply_rows_for_pdistr 28251 28251 0 0.00 0 5994 38 0 0
sp_multiply_rows_for_qdistr 20611 20611 0 0.00 0 10586 172 0 0
sp_pay_from_customer 7171 7171 0 0.00 5 15390 186 0 0
sp_pay_to_supplier 5965 5965 0 0.00 6 19071 198 0 0
sp_payment_common 13136 13136 0 0.00 2 15299 47 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 12203 400 0 0
sp_qd_handle_on_invoice_upd_sts 10375 10375 0 0.00 1 19745 582 0 0
sp_qd_handle_on_reserve_upd_sts 10079 10079 0 0.00 0 9422 64 0 0
sp_reserve_write_off 10079 10079 0 0.00 7 12839 280 0 0
sp_supplier_invoice 5540 5540 0 0.00 12 119464 3052 0 0
sp_supplier_order 5546 5546 0 0.00 7 116280 1405 0 0
srv_aggregate_perf_data 103 103 0 0.00 2274 175672 16569 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 832 149827 31645 0 0
srv_find_qd_qs_mism 1018231 1018231 0 0.00 0 10561 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 61 182571 4650 0 0
srv_make_money_saldo 962 962 0 0.00 1 20112 394 0 0
srv_recalc_idx_stat 5 5 0 0.00 72264 368887 223635 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5088 5088 0 0.00 0 34865 1078 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 5197 512 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 4587 34 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1966 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1006 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 336 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 4534 46 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 113404 8522 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 5728 25 0 0
t$perf-norm:sp_client_order 20612 20612 0 0.00 0 24080 41 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 5290 23 0 0
t$perf-norm:sp_pay_from_customer 7171 7171 0 0.00 0 10806 27 0 0
t$perf-norm:sp_pay_to_supplier 5965 5965 0 0.00 0 4103 23 0 0
t$perf-norm:sp_reserve_write_off 10079 10079 0 0.00 0 21917 30 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 12543 35 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 5605 34 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 266 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 299 7 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 578 14 0 0
t$perf-norm:srv_make_money_saldo 962 962 0 0.00 0 5282 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 38 215 122 0 0
v_add_invoice_to_stock 10372 10070 302 2.91 0 6782 27 0 302
v_all_customers 38002 38002 0 0.00 0 134 4 0 0
v_all_suppliers 30888 30888 0 0.00 0 105 5 0 0
v_all_wares 41216 41216 0 0.00 0 70 4 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1849 17 0 0
v_cancel_client_order 3672 3672 0 0.00 0 1718 12 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 3717 20 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1169 14 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 4575 18 0 0
v_cancel_supplier_invoice 878 842 36 4.10 0 3976 30 0 36
v_cancel_supplier_order 756 756 0 0.00 0 966 11 0 0
v_cancel_write_off 2002 2002 0 0.00 0 1246 25 0 0
v_max_id_clo_ord 5542 5542 0 0.00 0 5003 7 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1436 13 0 0
v_max_id_ord_sup 5534 5534 0 0.00 0 5842 8 0 0
v_max_non_paid_invoice 1597 1597 0 0.00 0 113 9 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 328 8 0 0
v_min_id_clo_ord 5542 5542 0 0.00 0 2143 9 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7956 18 0 0
v_min_id_ord_sup 5534 5534 0 0.00 0 5504 11 0 0
v_min_non_paid_invoice 5965 5965 0 0.00 0 6159 13 0 0
v_min_non_paid_realizn 7171 7171 0 0.00 0 15270 18 0 0
v_our_firm 10344 10344 0 0.00 0 71 5 0 0
v_reserve_write_off 20158 20158 0 0.00 0 5732 12 0 0
x_lock_depdocs_on_canc_invoice 10868 10868 0 0.00 0 13119 138 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 4540 401 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 2 116229 1203 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 6 119421 2823 0 0
x_make_qstorno_2100_3300 466449 466449 0 0.00 0 76830 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2022 13:38:16 to 26-10-2022 13: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
11:07:28 6 8192000000 8804832896 8818384896 7824480 0.095 3 1 2 0 7952256 92000 35776
11:08:29 23917 8192000000 9709828160 10005430272 785800448 8.752 100 89 177 0 815276480 7355776 22120256
11:10:03 18602 8192000000 9750954864 10054418432 822172656 9.120 100 90 180 0 856521376 12408672 21940048
11:11:26 10632 8192000000 9785422928 10101391360 858002224 9.480 100 88 174 0 896501952 17660304 20839424
11:12:39 9035 8192000000 9849615904 10183888896 910014096 9.997 100 77 154 0 957193584 30215040 16964448
11:13:56 7336 8192000000 9912594704 10255605760 953498144 10.425 100 80 160 1 1024611472 54052640 17060688
11:15:06 3398 8192000000 9959600848 10308861952 990137200 10.783 100 89 176 0 1072686976 63737376 18812400
11:16:13 2240 8192000000 9997435680 10359521280 1019177920 11.064 100 86 168 0 1122114736 86182208 16754608
11:17:19 1065 8192000000 10013846576 10380816384 1035995120 11.226 100 89 170 1 1133972384 81008160 16969104
11:18:21 896 8192000000 10017278720 10391281664 1052571136 11.385 100 96 185 3 1138249824 65975760 19702928
11:19:24 1140 8192000000 10041059424 10415403008 1070711008 11.559 100 97 186 2 1160207552 70751520 18745024
11:20:26 800 8192000000 10043713760 10427543552 1072832016 11.579 100 94 184 0 1165900032 74615664 18452352
11:21:29 3192 8192000000 10033738464 10411114496 1075666832 11.606 100 90 167 2 1155822752 63253488 16902432
11:22:33 5917 8192000000 10026423600 10414563328 1080220256 11.650 100 65 122 1 1153294912 60771328 12303328
11:23:40 994 8192000000 10031842912 10422374400 1087041888 11.715 100 86 168 0 1156360800 51980528 17338384
11:24:42 960 8192000000 10058527648 10449551360 1093738144 11.778 100 90 164 1 1183534944 72164208 17632592
11:25:44 1268 8192000000 10094933936 10484912128 1100495968 11.842 100 96 194 0 1219963808 100269168 19198672
11:26:46 1205 8192000000 10081629424 10470543360 1101319264 11.850 100 96 181 1 1200674528 81109264 18246000
11:27:49 959 8192000000 10061181008 10452516864 1099957472 11.837 100 92 175 1 1186039872 67809760 18272640
11:28:52 879 8192000000 10062993888 10462658560 1104356496 11.879 100 88 162 2 1189209936 67555664 17297776
11:29:53 1008 8192000000 10038323696 10423808000 1090901552 11.751 100 93 175 0 1156098768 47028640 18168576
11:30:55 2011 8192000000 10026513088 10413707264 1063094960 11.486 98 73 139 3 1144832544 68173008 13564576
11:31:58 1496 8192000000 10023578272 10409127936 1069100656 11.543 100 83 156 2 1146848864 61737264 16010944
11:33:00 1143 8192000000 10007216864 10385334272 1044803920 11.311 100 90 170 1 1131520432 68638400 18078112
11:34:02 1185 8192000000 9991235120 10363076608 1031210112 11.180 99 92 181 0 1113307040 63515968 18580960
11:35:04 1210 8192000000 9969745856 10338582528 1019712496 11.069 100 86 167 4 1092782400 57054608 16015296
11:36:06 2357 8192000000 9997219824 10373775360 1036689520 11.233 100 82 156 2 1111822640 59443504 15689616
11:37:09 1627 8192000000 9968696288 10343952384 1043106288 11.295 100 82 152 1 1093383840 34137536 16140016
11:38:18 1778 8192000000 10020792256 10397122560 1059898272 11.456 100 74 141 2 1136172576 62067136 14207168
11:39:27 6519 8192000000 10037703200 10417393664 1070428384 11.556 100 87 161 4 1158986128 73020000 15537744
11:40:37 5088 8192000000 10057213664 10446901248 1078354576 11.632 99 64 128 0 1170614432 79396400 12863456
11:41:46 2273 8192000000 10102802528 10483871744 1104277200 11.878 100 78 143 1 1214500080 95791952 14430928
11:42:53 4547 8192000000 10097738848 10484879360 1091426032 11.756 99 68 133 0 1213452288 107527440 14498816
11:44:06 2738 8192000000 10154296768 10544771072 1117356832 12.002 100 83 155 0 1277034592 141046096 18631664
11:45:13 1004 8192000000 10187055584 10577788928 1131265760 12.133 100 92 181 0 1304200832 152618400 20316672
11:46:18 3313 8192000000 10246033792 10642063360 1145457792 12.267 100 86 164 1 1353566688 190099296 18009600
11:47:25 3611 8192000000 10255643232 10656972800 1151732320 12.326 100 78 150 1 1364555056 196786928 16035808
11:48:34 945 8192000000 10243463472 10642063360 1146495120 12.277 100 89 175 0 1357926192 192192560 19238512
11:49:40 2804 8192000000 10294241264 10702974976 1166123456 12.461 100 83 161 2 1407029984 223854576 17051952
11:50:47 4818 8192000000 10306429040 10718670848 1171393472 12.510 100 80 156 1 1424352320 236028224 16930624
11:51:55 1515 8192000000 10367151792 10778796032 1183120176 12.619 100 85 168 0 1478746112 276542480 19083456
11:53:00 1274 8192000000 10401777696 10811863040 1192769296 12.709 100 97 187 2 1507389424 293275600 21344528
11:54:04 883 8192000000 10423011952 10830442496 1194778448 12.728 100 97 186 0 1530282800 314090112 21414240
11:55:10 2064 8192000000 10447344576 10866102272 1179456800 12.585 100 96 187 0 1552127120 350076032 22594288
11:56:15 1048 8192000000 10452754816 10858868736 1187325056 12.658 100 93 186 0 1562566336 354787680 20453600
11:57:24 858 8192000000 10472592944 10894938112 1186304768 12.649 99 93 186 0 1581153712 372143472 22705472
11:58:28 1088 8192000000 10541609232 10951106560 1199179232 12.769 100 96 190 4 1650394736 429869376 21346128
11:59:34 820 8192000000 10529707808 10974097408 1185315792 12.640 100 96 190 0 1637460464 429500592 22644080
12:00:38 1144 8192000000 10549560640 10980954112 1180705568 12.597 100 92 179 2 1659676688 457842560 21128560
12:01:41 1223 8192000000 10538902256 10985496576 1162045120 12.422 99 93 185 1 1644137584 460562096 21530368
12:02:47 1252 8192000000 10535548160 10990989312 1160948640 12.412 100 90 177 1 1639521056 456848336 21724080
12:03:51 854 8192000000 10566515408 11029983232 1177346304 12.565 100 99 193 1 1671687040 471031472 23309264
12:04:58 3559 8192000000 10517614960 11014987776 1187371776 12.659 100 59 118 0 1622148864 420316528 14460560
12:06:05 4593 8192000000 10517954560 11013550080 1189270416 12.677 100 57 112 1 1630854432 428148128 13435888
12:07:17 14911 8192000000 10502903360 10989883392 1172228208 12.518 100 39 78 0 1605417456 423108960 10080288
12:08:50 12642 8192000000 10542666688 10975748096 1172014944 12.516 100 45 90 0 1641976720 458645008 11316768
12:10:09 12179 8192000000 10476362624 10988834816 1159993488 12.403 100 47 94 0 1608043648 435955872 12094288
12:11:25 5449 8192000000 10430727056 10924961792 1152842016 12.336 100 57 110 1 1529062624 362673872 13546736
12:12:35 24844 8192000000 10274787456 10723000320 1099468224 11.833 95 33 66 0 1340392160 232953456 7970480
12:14:03 20120 8192000000 10338893904 10789347328 1157996960 12.384 100 29 56 0 1455576704 290637072 6942672
12:16:07 22588 8192000000 10223370592 10752720896 1143564736 12.249 100 15 30 0 1397594608 250286000 3743872
12:17:39 109651 8192000000 10173459968 10625040384 1102368704 11.860 96 26 50 0 1237897104 129903488 5624912
12:20:35 100761 8192000000 10131365744 10576338944 1125421312 12.078 99 46 91 0 1235795456 101193856 9180288
12:23:20 10335 8192000000 10119102880 10553376768 1132848656 12.148 100 69 130 0 1206740400 59822272 14069472
12:24:34 97404 8192000000 10076247680 10555138048 1127270192 12.096 99 28 56 0 1183126736 50229184 5627360
12:27:13 50388 8192000000 10113120192 10567172096 1134710224 12.166 99 27 54 0 1213164512 72961488 5492800
12:29:14 57875 8192000000 10126556416 10578763776 1151622016 12.325 100 20 38 0 1228081856 72034144 4425696
12:31:12 28817 8192000000 10133329584 10596495360 1155150784 12.358 100 18 34 1 1245831328 86997024 3683520
12:33:11 82307 8192000000 10151600544 10605035520 1108016784 11.914 96 15 30 0 1213148640 101808480 3323376
12:35:35 57458 8192000000 10124423168 10645938176 1142260944 12.237 100 13 26 0 1230600624 85241152 3098528
12:38:29 47281 8192000000 10115197376 10578857984 1142713680 12.241 99 16 31 0 1217890160 72073440 3103040
12:41:05 40510 8192000000 10082619760 10530889728 1089307008 11.736 95 30 58 0 1142396736 46828160 6261568
12:42:47 32378 8192000000 10076786096 10534232064 1131397040 12.135 100 38 72 0 1168473488 29571792 7504656
12:44:22 26654 8192000000 10107039808 10552848384 1130133456 12.123 99 46 89 1 1218864416 79675248 9055712
12:45:50 50160 8192000000 10105833712 10539286528 1097223744 11.811 97 34 69 0 1167162704 63087504 6851456
12:47:40 56025 8192000000 10081333776 10544799744 1125603184 12.080 100 22 43 0 1210040720 79913536 4524000
12:49:39 24693 8192000000 10140841600 10595090432 1123762832 12.063 100 46 90 0 1225710640 92823920 9123888
12:51:07 45578 8192000000 10108790416 10548436992 1135320688 12.171 100 32 60 1 1200042576 59014128 5707760
12:54:02 149827 8192000000 10045324832 10491150336 1059902368 11.456 94 21 42 1 1113745632 49871888 3971376
12:57:33 21967 8192000000 10089995296 10540457984 1125970736 12.083 100 38 74 0 1204210144 70936752 7302656
12:58:58 53726 8192000000 10087650480 10539499520 1112416304 11.955 98 18 35 0 1163886256 47530672 3939280
13:00:54 58472 8192000000 10094860080 10554347520 1110699408 11.939 98 23 44 0 1178720416 62445024 5575984
13:02:55 23257 8192000000 10088933792 10596691968 1132614720 12.146 100 11 22 0 1196244704 61317024 2312960
13:04:32 89020 8192000000 10071300448 10506788864 1082373600 11.670 95 28 52 1 1131927376 42738000 6815776
13:07:01 25873 8192000000 10142855248 10590474240 1133570432 12.155 99 32 63 0 1230369472 89429488 7369552
13:08:32 47157 8192000000 10117154160 10566156288 1109551536 11.928 98 23 44 0 1225503600 110228816 5723248
13:10:20 50161 8192000000 10116618208 10561609728 1119972384 12.027 99 37 75 1 1217718704 90174880 7571440
13:12:13 24962 8192000000 10091686192 10569011200 1123997696 12.065 99 33 66 1 1187881760 57453888 6430176
13:13:51 34306 8192000000 10104278592 10572046336 1113604272 11.967 98 33 66 0 1201851072 81338608 6908192
13:15:40 61407 8192000000 10104124336 10591858688 1131380416 12.134 100 29 56 0 1184000400 47016928 5603056
13:17:50 105295 8192000000 10074181264 10551283712 1126132272 12.085 99 18 36 0 1201963024 72019808 3810944
13:20:36 6884 8192000000 10087115248 10558783488 1137607312 12.193 100 45 85 2 1192873424 46512160 8753952
13:21:44 78963 8192000000 10087158800 10543616000 1120245904 12.029 98 11 22 0 1169649952 46953184 2450864
13:24:03 75640 8192000000 10066546064 10549874688 1126842944 12.092 99 20 40 0 1184925280 53347168 4735168
13:26:27 96348 8192000000 10094978208 10553843712 1126300704 12.086 98 8 15 0 1163526608 36021520 1204384
13:29:05 24337 8192000000 10140750368 10600329216 1152826688 12.336 100 27 50 0 1234327824 75479072 6022064
13:30:51 36308 8192000000 10133033728 10579484672 1130656144 12.128 98 30 60 0 1243637936 106560224 6421568
13:32:29 27952 8192000000 10143739504 10618408960 1140905472 12.224 100 24 46 0 1257737632 111008768 5823392
13:33:57 41096 8192000000 10179322496 10613764096 1110720128 11.939 97 30 59 2 1243096256 126208064 6168064
13:35:47 52582 8192000000 10068600656 10545020928 1126214176 12.086 100 21 42 0 1191160672 60474912 4471584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-10-2022 13:38:22 to 26-10-2022 13:38:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2022 13:38:40 to 26-10-2022 13:38:42.

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          218848
MON$OLDEST_ACTIVE               220301
MON$OLDEST_SNAPSHOT             220301
MON$NEXT_TRANSACTION            220301
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                       2113536
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2863 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2863 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2863 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2863 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Oct 26 13:37:24 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Oct 26 13:37:33 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

26.10.22 13:38:43. 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-10-26 13:38:46 No severe PSQL-related problems occured
26.10.22 13:38:47. End of report.