Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2023 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 15401252 kB
MemAvailable 30419072 kB
Buffers 0 kB
Cached 16308420 kB
SwapCached 1648 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 2023-01-21 10:37:23
DTS_END                         2023-01-21 10:37:23.1530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49982
TRN_ID                          222768
ATT_ID                          629
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: 8681.85 20607 1041822
customer order: creation 173.56 457 20827
customer order: refuse 15.11 384 1813
order to supplier: creation 44.74 1285 5369
order to supplier: removal 3.25 162054 390
invoice (draft): creation 45.43 3139 5451
invoice (draft): removal 46.55 937 5586
invoice accept: apply 41.85 26475 5022
invoice accept: cancel 42.23 1068 5067
customer reserve: creation 3854.78 256 462574
customer reserve: removal 4127.13 101 495255
realization accept: apply 83.83 251 10059
realization accept: cancel 75.75 152 9090
payment from customer: creation 57.73 161 6928
payment from customer: removal 5.56 154 667
payment to supplier: creation 48.31 175 5797
payment to supplier: removal 3.73 124 448
service: total inventory turnovers 4.33 3150 519
service: total monetary turnovers 7.01 316 841
service: aggregate perf. data 0.95 13372 114
service: refresh index statistics 0.04 198121 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2023 10:38:48 to 21-01-2023 10:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5222
TEST_TIME 2 4657
TEST_TIME 3 5471
TEST_TIME 4 5282
TEST_TIME 5 5214
TEST_TIME 6 5298
TEST_TIME 7 5215
TEST_TIME 8 5342
TEST_TIME 9 5421
TEST_TIME 10 5503
TEST_TIME 11 6193
TEST_TIME 12 6642
TEST_TIME 13 6715
TEST_TIME 14 6840
TEST_TIME 15 7142
TEST_TIME 16 7454
TEST_TIME 17 7473
TEST_TIME 18 7504
TEST_TIME 19 7679
TEST_TIME 20 7800
TEST_TIME 21 8276
TEST_TIME 22 8154
TEST_TIME 23 8376
TEST_TIME 24 8306
TEST_TIME 25 8405
TEST_TIME 26 9119
TEST_TIME 27 9579
TEST_TIME 28 9993
TEST_TIME 29 10459
TEST_TIME 30 10946
TEST_TIME 31 10976
TEST_TIME 32 10654
TEST_TIME 33 10384
TEST_TIME 34 10228
TEST_TIME 35 9998
TEST_TIME 36 10062
TEST_TIME 37 10236
TEST_TIME 38 10229
TEST_TIME 39 10042
TEST_TIME 40 9917
TEST_TIME 41 9808
TEST_TIME 42 9740
TEST_TIME 43 9710
TEST_TIME 44 9559
TEST_TIME 45 9506
TEST_TIME 46 9518
TEST_TIME 47 9413
TEST_TIME 48 9316
TEST_TIME 49 9276
TEST_TIME 50 9151
TEST_TIME 51 9064
TEST_TIME 52 9085
TEST_TIME 53 9038
TEST_TIME 54 8964
TEST_TIME 55 8941
TEST_TIME 56 9014
TEST_TIME 57 9030
TEST_TIME 58 9049
TEST_TIME 59 9048
TEST_TIME 60 9111
TEST_TIME 61 9138
TEST_TIME 62 9107
TEST_TIME 63 9098
TEST_TIME 64 9174
TEST_TIME 65 9248
TEST_TIME 66 9160
TEST_TIME 67 9041
TEST_TIME 68 9074
TEST_TIME 69 9026
TEST_TIME 70 8978
TEST_TIME 71 8901
TEST_TIME 72 8913
TEST_TIME 73 8864
TEST_TIME 74 8857
TEST_TIME 75 8922
TEST_TIME 76 8911
TEST_TIME 77 8881
TEST_TIME 78 8865
TEST_TIME 79 8854
TEST_TIME 80 8951
TEST_TIME 81 8949
TEST_TIME 82 8930
TEST_TIME 83 8944
TEST_TIME 84 8852
TEST_TIME 85 8899
TEST_TIME 86 8862
TEST_TIME 87 8815
TEST_TIME 88 8776
TEST_TIME 89 8737
TEST_TIME 90 8712
TEST_TIME 91 8735
TEST_TIME 92 8719
TEST_TIME 93 8713
TEST_TIME 94 8723
TEST_TIME 95 8730
TEST_TIME 96 8707
TEST_TIME 97 8789
TEST_TIME 98 8774
TEST_TIME 99 8815
TEST_TIME 100 8918
TEST_TIME 101 8937
TEST_TIME 102 8930
TEST_TIME 103 8906
TEST_TIME 104 8901
TEST_TIME 105 8852
TEST_TIME 106 8814
TEST_TIME 107 8797
TEST_TIME 108 8762
TEST_TIME 109 8714
TEST_TIME 110 8713
TEST_TIME 111 8716
TEST_TIME 112 8736
TEST_TIME 113 8731
TEST_TIME 114 8712
TEST_TIME 115 8705
TEST_TIME 116 8713
TEST_TIME 117 8692
TEST_TIME 118 8705
TEST_TIME 119 8713
TEST_TIME 120 8703

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-01-2023 10:38:52 to 21-01-2023 10:38:55.

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 1467041 1467041 0 0.00 0 18690 13 0 0
doc_list_biud 1467033 1467033 0 0.00 0 29967 40 0 0
sp_add_invoice_to_stock 5162 5022 140 2.71 49 193395 26476 0 140
sp_cancel_adding_invoice 5067 5067 0 0.00 5 77845 1068 0 0
sp_cancel_client_order 1813 1813 0 0.00 6 12864 385 0 0
sp_cancel_customer_reserve 495255 495255 0 0.00 1 29970 102 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 12702 154 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 4131 125 0 0
sp_cancel_supplier_invoice 5594 5586 8 0.14 2 17771 937 0 8
sp_cancel_supplier_order 390 390 0 0.00 13 1492651 162054 0 0
sp_cancel_write_off 9090 9090 0 0.00 2 20029 153 0 0
sp_client_order 20827 20827 0 0.00 5 37701 458 0 0
sp_customer_reserve 462574 462574 0 0.00 1 91345 257 0 0
sp_fill_shopping_cart 31630 31630 0 0.00 1 14976 83 0 0
sp_fill_shopping_cart_clo_res 462549 462549 0 0.00 0 22739 22 0 0
sp_get_clo_for_invoice 4990 4990 0 0.00 0 41536 336 0 0
sp_kill_cost_storno 15272 15272 0 0.00 0 8361 65 0 0
sp_kill_qstorno_ret_qs2qd 1005576 1005576 0 0.00 0 29929 45 0 0
sp_kill_qty_storno 532248 532248 0 0.00 0 29967 102 0 0
sp_lock_selected_doc 20723 20723 0 0.00 0 6121 6 0 0
sp_make_cost_storno 27774 27774 0 0.00 0 18682 74 0 0
sp_multiply_rows_for_pdistr 27773 27773 0 0.00 0 7413 34 0 0
sp_multiply_rows_for_qdistr 20827 20827 0 0.00 0 10397 202 0 0
sp_pay_from_customer 6928 6928 0 0.00 5 11675 161 0 0
sp_pay_to_supplier 5797 5797 0 0.00 6 14715 176 0 0
sp_payment_common 12724 12724 0 0.00 2 8038 42 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 12851 313 0 0
sp_qd_handle_on_invoice_upd_sts 10057 10057 0 0.00 0 16598 483 0 0
sp_qd_handle_on_reserve_upd_sts 10057 10057 0 0.00 0 8985 53 0 0
sp_reserve_write_off 10059 10059 0 0.00 8 13498 251 0 0
sp_supplier_invoice 5451 5451 0 0.00 8 100816 3139 0 0
sp_supplier_order 5369 5369 0 0.00 7 49628 1286 0 0
srv_aggregate_perf_data 114 114 0 0.00 2524 92078 13372 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 886 116622 37398 0 0
srv_find_qd_qs_mism 992735 992735 0 0.00 0 9863 11 0 0
srv_make_invnt_saldo 519 519 0 0.00 68 68273 3150 0 0
srv_make_money_saldo 841 841 0 0.00 1 10205 317 0 0
srv_recalc_idx_stat 5 5 0 0.00 72622 470689 198122 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5025 5025 0 0.00 1 17760 815 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 2 7219 330 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 3580 23 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1897 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 607 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 1589 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 702 28 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 80952 6160 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 8303 25 0 0
t$perf-norm:sp_client_order 20828 20828 0 0.00 0 32271 36 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 646 12 0 0
t$perf-norm:sp_pay_from_customer 6928 6928 0 0.00 0 16311 21 0 0
t$perf-norm:sp_pay_to_supplier 5797 5797 0 0.00 0 6111 18 0 0
t$perf-norm:sp_reserve_write_off 10061 10061 0 0.00 0 6632 23 0 0
t$perf-norm:sp_supplier_invoice 5451 5451 0 0.00 0 8109 26 0 0
t$perf-norm:sp_supplier_order 5369 5369 0 0.00 0 2901 28 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 518 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 21 3 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 1881 17 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 780 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 116 46 0 0
v_add_invoice_to_stock 10253 9973 280 2.73 0 5986 26 0 280
v_all_customers 37872 37872 0 0.00 0 75 3 0 0
v_all_suppliers 29758 29758 0 0.00 0 358 4 0 0
v_all_wares 41642 41642 0 0.00 0 148 4 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 1731 15 0 0
v_cancel_client_order 3626 3626 0 0.00 0 2767 10 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 6871 28 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1776 14 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 773 10 0 0
v_cancel_supplier_invoice 806 790 16 1.98 0 905 26 0 16
v_cancel_supplier_order 780 780 0 0.00 0 706 10 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4162 24 0 0
v_max_id_clo_ord 5363 5363 0 0.00 0 746 5 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 1192 13 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 5921 8 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 820 7 0 0
v_max_non_paid_realizn 3626 3626 0 0.00 0 4788 8 0 0
v_min_id_clo_ord 5363 5363 0 0.00 0 3469 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 2529 11 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 5954 9 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 5188 13 0 0
v_min_non_paid_realizn 6927 6927 0 0.00 0 5133 16 0 0
v_our_firm 10372 10372 0 0.00 0 70 4 0 0
v_reserve_write_off 20112 20112 0 0.00 0 3043 10 0 0
x_lock_depdocs_on_canc_invoice 10258 10258 0 0.00 0 20414 118 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 6094 300 0 0
x_make_qstorno_1000_1200 5369 5369 0 0.00 3 49413 1139 0 0
x_make_qstorno_1200_2000 5451 5451 0 0.00 3 100792 2884 0 0
x_make_qstorno_2100_3300 462574 462574 0 0.00 0 91343 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 10:38:56 to 21-01-2023 10:39:00.

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:27 3 8192000000 8800541312 8812548096 5565840 0.067 3 1 2 0 5667040 65424 35776
08:08:28 21963 8192000000 9708416288 10004107264 781566208 8.709 100 82 164 0 809618192 7462144 20589840
08:09:57 19578 8192000000 9774451920 10083336192 845963616 9.360 100 85 170 0 877392144 11163872 20264656
08:11:19 17327 8192000000 9812509952 10131042304 880936592 9.709 100 85 170 0 919798144 18920224 19941328
08:12:42 8443 8192000000 9861179088 10197819392 926032800 10.156 100 80 154 1 976460144 33101072 17326272
08:13:53 5602 8192000000 9933794400 10280796160 963853312 10.527 100 84 165 0 1045499696 64452688 17193696
08:15:00 4755 8192000000 9954095520 10307444736 994547824 10.826 100 87 166 0 1074747728 63437600 16762304
08:16:09 1145 8192000000 9994304880 10358972416 1018094832 11.054 100 90 180 0 1114109616 77723072 18291712
08:17:12 1199 8192000000 10027598384 10401120256 1047724720 11.339 100 92 181 2 1151077760 84956064 18396976
08:18:15 1372 8192000000 10021108928 10399240192 1059218496 11.449 100 86 167 1 1147506848 70847648 17440704
08:19:18 1003 8192000000 10052452512 10428141568 1069592160 11.548 100 97 189 0 1177373264 88893792 18887312
08:20:22 3846 8192000000 10041269952 10416943104 1073223408 11.583 100 81 152 4 1165134240 76763280 15147552
08:21:26 1028 8192000000 10038130064 10422874112 1080689168 11.654 100 91 174 0 1157883072 59218256 17975648
08:22:29 1034 8192000000 10053293232 10437529600 1084788256 11.693 100 92 172 2 1176509248 73974624 17746368
08:23:31 1978 8192000000 10066070160 10452529152 1090355120 11.746 100 87 157 1 1185672992 78413024 16904848
08:24:34 926 8192000000 10050294048 10438770688 1095374272 11.794 100 71 134 1 1172350832 63627008 13349552
08:25:36 956 8192000000 10053605216 10445209600 1103672672 11.872 100 85 159 0 1176592464 56110448 16809344
08:26:38 1001 8192000000 10067716000 10456936448 1108395152 11.917 100 88 165 0 1191355152 65706944 17253056
08:27:40 1460 8192000000 10064152192 10453426176 1102084032 11.857 100 82 152 1 1185688576 67358304 16246240
08:28:43 1079 8192000000 10059837584 10444718080 1085089552 11.696 99 97 182 2 1181533568 78203200 18240816
08:29:45 2158 8192000000 10026055584 10423296000 1084034864 11.686 100 60 116 2 1161294608 65727840 11531904
08:30:48 1175 8192000000 10049136672 10430717952 1078762272 11.636 100 99 193 1 1171168144 72282112 20123760
08:31:50 1253 8192000000 9985146032 10362101760 1044644368 11.309 99 84 147 3 1109883328 49417472 15821488
08:32:53 1182 8192000000 9995084400 10366885888 1041463280 11.279 99 96 183 4 1125146880 64607504 19076096
08:33:55 1180 8192000000 10030152032 10412957696 1052569056 11.385 100 94 185 1 1154033008 82377104 19086848
08:34:57 1359 8192000000 9989304448 10364817408 1035993072 11.226 100 97 182 0 1114559168 59687520 18878576
08:36:00 1212 8192000000 9981081808 10348748800 1025480064 11.125 99 95 168 1 1098534048 55625296 17428688
08:37:02 1282 8192000000 10005530352 10379579392 1038497040 11.250 100 94 176 2 1120825936 65258336 17070560
08:38:09 24330 8192000000 9985703040 10377080832 1056954336 11.427 100 50 92 0 1114170208 47985792 9230080
08:39:41 3823 8192000000 10036132048 10418294784 1082617600 11.672 100 76 138 5 1153955168 58486848 12850720
08:40:48 877 8192000000 10089787504 10469126144 1091623568 11.758 100 94 174 2 1205960448 96785024 17551856
08:41:53 7322 8192000000 10139836480 10534526976 1109184992 11.925 100 75 145 0 1250134320 125489408 15459920
08:43:07 2328 8192000000 10151824208 10543501312 1117335392 12.002 100 72 145 0 1269839136 136656752 15846992
08:44:19 1652 8192000000 10187458384 10578014208 1126275312 12.086 100 78 152 2 1300113504 158044736 15793456
08:45:24 1906 8192000000 10228820048 10623635456 1138026880 12.197 100 84 162 0 1337942288 180816848 19098560
08:46:30 1505 8192000000 10235407952 10631053312 1146253344 12.274 100 82 147 2 1352056352 188935760 16867248
08:47:35 3524 8192000000 10276943552 10675224576 1152400192 12.332 100 82 151 3 1393421760 223802016 17219552
08:48:46 1782 8192000000 10243044032 10682462208 1159374400 12.397 100 90 170 2 1356985104 178147120 19463584
08:49:57 2443 8192000000 10295849648 10698440704 1162573536 12.427 100 77 150 1 1405814256 225895856 17344864
08:51:03 1308 8192000000 10340525760 10747428864 1171569632 12.511 100 90 180 0 1449226320 257328496 20328192
08:52:09 2593 8192000000 10323213504 10758078464 1165665152 12.456 99 84 163 2 1432295504 247432336 19198016
08:53:15 1019 8192000000 10362843936 10781409280 1178456672 12.576 100 95 182 1 1473009344 272857344 21695328
08:54:19 1425 8192000000 10389506896 10794201088 1180288640 12.593 100 89 175 0 1498299664 297584400 20426624
08:55:23 1037 8192000000 10420878896 10843168768 1190719456 12.690 100 95 186 1 1528396736 315993856 21683424
08:56:28 1225 8192000000 10441099472 10862108672 1182291248 12.612 100 95 185 0 1544730736 341317584 21121904
08:57:32 1325 8192000000 10440433040 10885062656 1181514464 12.604 100 81 158 0 1546585584 346508480 18562640
08:58:38 5360 8192000000 10450352880 10879574016 1182050400 12.609 100 56 112 0 1568193824 372331952 13811472
08:59:48 12353 8192000000 10476058256 10918576128 1168944400 12.487 99 56 111 1 1581956352 399458704 13553248
09:01:04 6392 8192000000 10487461264 10922471424 1169443456 12.492 99 56 112 0 1583418912 399912752 14062704
09:02:12 4037 8192000000 10502476000 10949386240 1144691200 12.260 99 60 120 1 1603182832 443061008 15430624
09:03:21 8352 8192000000 10459302224 10979078144 1154837936 12.355 100 49 97 0 1590077392 422287408 12952048
09:04:37 12657 8192000000 10322952096 10867056640 1155769136 12.364 100 35 70 0 1452035984 286324432 9942416
09:05:50 18850 8192000000 10242033360 10723627008 1148081936 12.291 100 16 32 0 1355492144 202968224 4441984
09:07:58 116235 8192000000 10109354368 10534023168 1109807216 11.931 97 36 70 2 1179367120 61443200 8116704
09:11:02 95477 8192000000 10163692400 10604113920 1138752336 12.204 99 38 71 1 1236346960 89726144 7868480
09:13:40 87623 8192000000 10130823312 10648199168 1126090832 12.084 98 16 32 0 1261246576 131418736 3737008
09:16:09 41942 8192000000 10090025088 10543644672 1119151872 12.019 98 19 37 0 1161805168 39115664 3537632
09:17:51 116609 8192000000 10098708480 10571247616 1106291360 11.897 98 26 50 1 1169193280 57715376 5186544
09:20:48 76646 8192000000 10073722144 10528145408 1087613488 11.720 97 24 48 0 1157034608 63810592 5610528
09:23:18 28765 8192000000 10050674912 10556338176 1120142496 12.028 100 22 45 0 1164993504 39413664 5437344
09:24:48 49557 8192000000 10061963696 10515939328 1095110592 11.791 98 21 42 0 1163893904 64281808 4501504
09:27:01 92358 8192000000 10083157024 10523541504 1110704448 11.939 99 23 47 0 1168445376 53324896 4416032
09:29:36 97010 8192000000 10032712736 10488213504 1103855152 11.874 98 17 34 0 1157755888 50520816 3379920
09:32:14 9558 8192000000 10108155952 10540498944 1135449072 12.173 100 50 97 1 1203015760 57123712 10442976
09:33:31 67522 8192000000 10122173104 10574700544 1125921824 12.083 99 21 40 0 1213010096 83046400 4041872
09:35:39 20297 8192000000 10082301680 10520231936 1115166176 11.981 98 22 38 1 1155266960 36286976 3813808
09:37:01 99257 8192000000 10082201632 10563051520 1096973952 11.809 96 31 62 0 1184455008 81126992 6354064
09:39:47 22562 8192000000 10131169312 10566553600 1150391120 12.313 100 43 86 1 1214403664 55408864 8603680
09:41:24 83251 8192000000 10083925936 10550251520 1112295280 11.954 97 25 49 0 1215161200 97612944 5252976
09:43:50 100796 8192000000 10123386336 10567254016 1127231488 12.095 100 45 87 1 1215630400 79527392 8871520
09:46:31 33043 8192000000 10055583248 10515488768 1125960608 12.083 100 14 26 0 1176981888 47775456 3245824
09:48:11 70393 8192000000 10064929200 10532327424 1140521536 12.220 100 11 21 1 1176798416 34102176 2174704
09:50:23 14438 8192000000 10085291856 10514018304 1128888912 12.111 99 54 107 0 1180168416 38620736 12658768
09:51:38 29840 8192000000 10073198640 10519212032 1109050160 11.923 99 14 26 1 1176818736 64963120 2805456
09:53:09 43117 8192000000 10053234176 10491756544 1082768992 11.674 97 34 68 1 1118568416 29147280 6652144
09:54:54 33739 8192000000 10086241040 10535841792 1117795072 12.006 100 36 72 0 1182437232 56978224 7663936
09:56:28 34409 8192000000 10148397168 10585952256 1121981248 12.046 100 51 102 0 1229789984 97554368 10254368
09:58:02 44731 8192000000 10070612560 10502692864 1120961360 12.036 99 38 70 3 1164138400 35456224 7720816
09:59:59 62159 8192000000 10115830880 10585133056 1120285888 12.030 98 34 68 0 1226828544 99519376 7023280
10:02:03 50328 8192000000 10062551664 10560974848 1118494640 12.013 99 28 55 0 1209406672 85458176 5453856
10:03:58 53318 8192000000 10058694176 10548871168 1109660528 11.929 98 4 8 0 1157945760 47438288 846944
10:06:13 67932 8192000000 10065422464 10506727424 1070203632 11.554 96 19 32 0 1128880048 54647872 4028544
10:08:22 17998 8192000000 10064974640 10496823296 1107785872 11.911 99 37 72 0 1152748960 36586384 8376704
10:09:40 41643 8192000000 10120252976 10553835520 1096223632 11.802 96 28 56 0 1175652368 73295408 6133328
10:11:22 45491 8192000000 10153772576 10603454464 1091286704 11.755 96 41 78 0 1227180992 127006224 8888064
10:13:15 24348 8192000000 10197455760 10633990144 1128282144 12.105 99 39 77 0 1273415968 136546528 8587296
10:14:42 47414 8192000000 10137890272 10569916416 1113058384 11.961 98 39 77 2 1213106864 91838832 8209648
10:16:32 37516 8192000000 10127979552 10625228800 1110486960 11.937 99 38 76 0 1207965104 89736224 7741920
10:18:14 95927 8192000000 10094566976 10526425088 1127566736 12.098 99 22 42 1 1170524800 38395248 4562816
10:20:51 52165 8192000000 10121893408 10577756160 1131314256 12.134 100 61 120 0 1217203216 72987376 12901584
10:23:28 46616 8192000000 10071473168 10530385920 1096472608 11.804 97 31 62 0 1155300704 52319824 6508272
10:25:20 58557 8192000000 10081075104 10554617856 1116335200 11.992 99 21 42 0 1188625424 67829552 4460672
10:27:35 86252 8192000000 10053536576 10535276544 1098384480 11.822 98 15 30 0 1178204736 76721616 3098640
10:30:03 41184 8192000000 10074271648 10522689536 1099145792 11.830 98 25 48 0 1164811952 60541648 5124512
10:31:45 38153 8192000000 10131363424 10573012992 1122433152 12.050 98 34 66 0 1228703696 98715344 7555200
10:33:27 52672 8192000000 10141374320 10589642752 1106963728 11.904 97 21 41 0 1220014448 108211904 4838816
10:35:25 19911 8192000000 10214899488 10645524480 1133677024 12.156 100 45 90 0 1298818736 155109632 10032080
10:36:52 39386 8192000000 9728764576 10167758848 797856144 8.875 70 22 44 0 912829232 109786464 5186624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-01-2023 10:39:02 to 21-01-2023 10:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2023 10:39:19 to 21-01-2023 10:39:21.

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          223173
MON$OLDEST_ACTIVE               223174
MON$OLDEST_SNAPSHOT             223174
MON$NEXT_TRANSACTION            223174
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                       2025760
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:929: 
:930: mscs-test-161.budzdorov.ru    Sat Jan 21 10:38:14 2023
:931:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:932: 
:933: 
:934: mscs-test-161.budzdorov.ru    Sat Jan 21 10:38:18 2023
:935:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:936: 
--- end of diff output ---

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

21.01.23 10:39:23. 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
2023-01-21 10:39:25 No severe PSQL-related problems occured
21.01.23 10:39:27. End of report.