Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 07: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 16332680 kB
MemAvailable 31578756 kB
Buffers 0 kB
Cached 15344528 kB
SwapCached 2196 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-03-29 07:37:12
DTS_END                         2022-03-29 07:37:12.2640
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/56006
TRN_ID                          211359
ATT_ID                          643
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: 8630.28 29188 1035634
customer order: creation 167.42 507 20090
customer order: refuse 14.17 477 1700
order to supplier: creation 45.11 1450 5413
order to supplier: removal 3.16 187232 379
invoice (draft): creation 46.45 3626 5574
invoice (draft): removal 45.08 1210 5410
invoice accept: apply 42.33 29612 5079
invoice accept: cancel 41.08 1615 4929
customer reserve: creation 3917.48 280 470097
customer reserve: removal 4036.56 114 484387
realization accept: apply 79.98 303 9598
realization accept: cancel 66.22 204 7947
payment from customer: creation 57.20 198 6864
payment from customer: removal 5.04 176 605
payment to supplier: creation 47.25 225 5670
payment to supplier: removal 3.14 150 377
service: total inventory turnovers 3.96 4516 475
service: total monetary turnovers 7.77 402 932
service: aggregate perf. data 0.86 17730 103
service: refresh index statistics 0.04 333736 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6561
TEST_TIME 2 5685
TEST_TIME 3 6757
TEST_TIME 4 6742
TEST_TIME 5 7031
TEST_TIME 6 6869
TEST_TIME 7 7077
TEST_TIME 8 7284
TEST_TIME 9 7398
TEST_TIME 10 7571
TEST_TIME 11 7666
TEST_TIME 12 7410
TEST_TIME 13 7515
TEST_TIME 14 7805
TEST_TIME 15 7768
TEST_TIME 16 7778
TEST_TIME 17 8029
TEST_TIME 18 7906
TEST_TIME 19 7962
TEST_TIME 20 7978
TEST_TIME 21 8144
TEST_TIME 22 8101
TEST_TIME 23 8165
TEST_TIME 24 8198
TEST_TIME 25 8294
TEST_TIME 26 8181
TEST_TIME 27 8171
TEST_TIME 28 8151
TEST_TIME 29 8238
TEST_TIME 30 8286
TEST_TIME 31 8397
TEST_TIME 32 8871
TEST_TIME 33 9093
TEST_TIME 34 9188
TEST_TIME 35 9368
TEST_TIME 36 9545
TEST_TIME 37 9826
TEST_TIME 38 10000
TEST_TIME 39 10626
TEST_TIME 40 10523
TEST_TIME 41 10386
TEST_TIME 42 10281
TEST_TIME 43 10124
TEST_TIME 44 9979
TEST_TIME 45 9992
TEST_TIME 46 10043
TEST_TIME 47 9902
TEST_TIME 48 9823
TEST_TIME 49 9753
TEST_TIME 50 9661
TEST_TIME 51 9762
TEST_TIME 52 9717
TEST_TIME 53 9627
TEST_TIME 54 9555
TEST_TIME 55 9508
TEST_TIME 56 9428
TEST_TIME 57 9360
TEST_TIME 58 9303
TEST_TIME 59 9346
TEST_TIME 60 9315
TEST_TIME 61 9251
TEST_TIME 62 9272
TEST_TIME 63 9347
TEST_TIME 64 9315
TEST_TIME 65 9331
TEST_TIME 66 9363
TEST_TIME 67 9326
TEST_TIME 68 9353
TEST_TIME 69 9310
TEST_TIME 70 9363
TEST_TIME 71 9394
TEST_TIME 72 9350
TEST_TIME 73 9406
TEST_TIME 74 9348
TEST_TIME 75 9254
TEST_TIME 76 9272
TEST_TIME 77 9225
TEST_TIME 78 9182
TEST_TIME 79 9100
TEST_TIME 80 9065
TEST_TIME 81 9015
TEST_TIME 82 9067
TEST_TIME 83 9059
TEST_TIME 84 9069
TEST_TIME 85 9016
TEST_TIME 86 9014
TEST_TIME 87 8963
TEST_TIME 88 8968
TEST_TIME 89 9030
TEST_TIME 90 9072
TEST_TIME 91 9039
TEST_TIME 92 9056
TEST_TIME 93 9027
TEST_TIME 94 8958
TEST_TIME 95 8975
TEST_TIME 96 8907
TEST_TIME 97 8874
TEST_TIME 98 8837
TEST_TIME 99 8850
TEST_TIME 100 8826
TEST_TIME 101 8862
TEST_TIME 102 8844
TEST_TIME 103 8876
TEST_TIME 104 8839
TEST_TIME 105 8852
TEST_TIME 106 8857
TEST_TIME 107 8872
TEST_TIME 108 8899
TEST_TIME 109 8971
TEST_TIME 110 8945
TEST_TIME 111 8903
TEST_TIME 112 8840
TEST_TIME 113 8820
TEST_TIME 114 8806
TEST_TIME 115 8765
TEST_TIME 116 8725
TEST_TIME 117 8716
TEST_TIME 118 8722
TEST_TIME 119 8709
TEST_TIME 120 8661

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-03-2022 07:38:30 to 29-03-2022 07: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 1467032 1467032 0 0.00 0 21945 15 0 0
doc_list_biud 1467013 1467013 0 0.00 0 39111 44 0 0
sp_add_invoice_to_stock 5152 5079 73 1.41 68 193773 29612 0 73
sp_cancel_adding_invoice 4929 4929 0 0.00 7 100041 1616 0 0
sp_cancel_client_order 1700 1700 0 0.00 6 12138 478 0 0
sp_cancel_customer_reserve 484387 484387 0 0.00 1 39112 115 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 4 9010 176 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 4896 151 0 0
sp_cancel_supplier_invoice 5414 5410 4 0.07 3 17727 1210 0 4
sp_cancel_supplier_order 379 379 0 0.00 9 1612291 187232 0 0
sp_cancel_write_off 7947 7947 0 0.00 2 7354 204 0 0
sp_client_order 20090 20090 0 0.00 6 18788 507 0 0
sp_customer_reserve 470097 470097 0 0.00 1 90860 281 0 0
sp_fill_shopping_cart 31069 31069 0 0.00 2 20616 90 0 0
sp_fill_shopping_cart_clo_res 470065 470065 0 0.00 0 22407 22 0 0
sp_get_clo_for_invoice 5039 5039 0 0.00 0 33900 308 0 0
sp_kill_cost_storno 13858 13858 0 0.00 0 14430 93 0 0
sp_kill_qstorno_ret_qs2qd 982510 982510 0 0.00 0 39107 50 0 0
sp_kill_qty_storno 519385 519385 0 0.00 0 39111 115 0 0
sp_lock_selected_doc 20166 20166 0 0.00 0 7258 8 0 0
sp_make_cost_storno 27171 27171 0 0.00 0 14426 91 0 0
sp_multiply_rows_for_pdistr 27167 27167 0 0.00 0 8004 43 0 0
sp_multiply_rows_for_qdistr 20090 20090 0 0.00 0 12817 186 0 0
sp_pay_from_customer 6864 6864 0 0.00 5 15023 198 0 0
sp_pay_to_supplier 5670 5670 0 0.00 6 18376 226 0 0
sp_payment_common 12532 12532 0 0.00 2 9373 46 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 12124 394 0 0
sp_qd_handle_on_invoice_upd_sts 9965 9965 0 0.00 0 15267 600 0 0
sp_qd_handle_on_reserve_upd_sts 9597 9597 0 0.00 0 11145 66 0 0
sp_reserve_write_off 9598 9598 0 0.00 8 18721 304 0 0
sp_supplier_invoice 5574 5574 0 0.00 10 83419 3626 0 0
sp_supplier_order 5413 5413 0 0.00 8 41443 1450 0 0
srv_aggregate_perf_data 103 103 0 0.00 2304 164277 17730 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 679 174636 32973 0 0
srv_find_qd_qs_mism 986460 986460 0 0.00 0 13131 12 0 0
srv_make_invnt_saldo 475 475 0 0.00 54 55718 4517 0 0
srv_make_money_saldo 932 932 0 0.00 1 13303 402 0 0
srv_recalc_idx_stat 5 5 0 0.00 233760 431204 333736 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5085 5085 0 0.00 1 16181 1192 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 8219 590 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 1642 34 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 3039 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 1997 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2983 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1939 53 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 88292 8823 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 3003 24 0 0
t$perf-norm:sp_client_order 20090 20090 0 0.00 0 13724 47 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1059 21 0 0
t$perf-norm:sp_pay_from_customer 6864 6864 0 0.00 0 7333 24 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 6478 26 0 0
t$perf-norm:sp_reserve_write_off 9598 9598 0 0.00 0 6724 27 0 0
t$perf-norm:sp_supplier_invoice 5574 5574 0 0.00 0 3368 39 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 6921 36 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 112 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 194 12 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 4659 29 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 2822 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 213 75 0 0
v_add_invoice_to_stock 10206 10060 146 1.43 0 11200 26 0 146
v_all_customers 36698 36698 0 0.00 0 207 4 0 0
v_all_suppliers 30464 30464 0 0.00 0 118 5 0 0
v_all_wares 40173 40173 0 0.00 0 207 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 5297 21 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2329 11 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 8942 29 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 2603 15 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 1401 16 0 0
v_cancel_supplier_invoice 788 780 8 1.01 0 5298 39 0 8
v_cancel_supplier_order 758 758 0 0.00 0 3915 22 0 0
v_cancel_write_off 1960 1960 0 0.00 0 1800 24 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 282 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 3163 17 0 0
v_max_id_ord_sup 5567 5567 0 0.00 0 2311 7 0 0
v_max_non_paid_invoice 1415 1415 0 0.00 0 185 8 0 0
v_max_non_paid_realizn 3463 3463 0 0.00 0 599 8 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 2297 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 1189 11 0 0
v_min_id_ord_sup 5567 5567 0 0.00 0 5354 12 0 0
v_min_non_paid_invoice 5669 5669 0 0.00 0 7784 17 0 0
v_min_non_paid_realizn 6863 6863 0 0.00 0 9355 16 0 0
v_our_firm 10274 10274 0 0.00 0 60 5 0 0
v_reserve_write_off 19194 19194 0 0.00 0 11207 16 0 0
x_lock_depdocs_on_canc_invoice 9949 9949 0 0.00 0 19692 148 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 10578 419 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 3 41434 1258 0 0
x_make_qstorno_1200_2000 5574 5574 0 0.00 4 83407 3380 0 0
x_make_qstorno_2100_3300 470097 470097 0 0.00 0 90846 244 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-03-2022 07:38:34 to 29-03-2022 07:38:39.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:18 27068 8192000000 9730467472 10028777472 803289296 8.930 100 89 178 0 832239200 7687968 21261936
05:09:51 28891 8192000000 9767310576 10077794304 842503920 9.325 100 89 178 0 876316928 13259408 20553600
05:11:35 15362 8192000000 9821276464 10138669056 877254192 9.672 100 86 172 0 929202080 32864784 19083104
05:12:54 10068 8192000000 9873760944 10206937088 923776704 10.133 100 85 171 1 992366784 49626928 18963152
05:14:11 6828 8192000000 9916259296 10262642688 958406848 10.473 100 80 160 0 1031802496 56853184 16542464
05:15:20 3926 8192000000 9953690512 10306744320 991270032 10.794 100 91 176 1 1070041168 61218304 17552832
05:16:26 1906 8192000000 10010435856 10371616768 1016160800 11.035 100 90 179 0 1126177104 91205392 18810912
05:17:30 1691 8192000000 10012323648 10383773696 1042457808 11.288 100 88 172 2 1134022096 75020608 16543680
05:18:34 981 8192000000 10038372240 10412634112 1055286784 11.411 100 96 188 0 1159124016 85211696 18625536
05:19:38 1147 8192000000 10034268720 10408734720 1063411328 11.489 100 96 185 1 1151571600 70489968 17670304
05:20:42 1237 8192000000 10038405776 10416844800 1072304896 11.574 100 96 185 1 1163583936 73527776 17751264
05:21:46 5296 8192000000 10037574000 10417127424 1074952112 11.599 100 78 151 2 1166581584 76458240 15171232
05:22:52 6255 8192000000 10029919088 10413228032 1082902736 11.675 100 91 167 1 1157302016 57156848 17242432
05:23:59 1192 8192000000 10046589504 10432610304 1084929904 11.694 100 92 174 0 1171539936 68858032 17752000
05:25:01 1173 8192000000 10055544272 10446766080 1085988160 11.704 100 90 168 2 1179719424 76361824 17369440
05:26:03 1422 8192000000 10062404016 10452873216 1091086896 11.753 100 85 162 0 1181905280 73687264 17131120
05:27:09 805 8192000000 10060600224 10450407424 1099349904 11.831 100 97 186 1 1188484992 69514000 19621088
05:28:11 1036 8192000000 10045595088 10435272704 1097049872 11.810 100 85 167 1 1168284288 53440496 17793920
05:29:13 1260 8192000000 10054704304 10443628544 1098263728 11.821 100 97 186 2 1177918192 60933328 18721136
05:30:16 1146 8192000000 10047358336 10437062656 1095651632 11.796 100 97 179 2 1165698832 51297296 18749904
05:31:17 1029 8192000000 10063718240 10457382912 1100911760 11.846 100 80 155 1 1179582160 64509040 14161360
05:32:20 1177 8192000000 10042896320 10425597952 1084887440 11.694 100 97 184 1 1164857840 61364800 18605600
05:33:23 6534 8192000000 10014315488 10396618752 1059198512 11.449 99 71 137 0 1139344896 66716432 13429952
05:34:31 1337 8192000000 10013687648 10390736896 1062258400 11.478 100 81 151 2 1127186112 49589856 15337856
05:35:34 1142 8192000000 9978834080 10347909120 1038324624 11.249 98 93 177 0 1101761184 45776784 17659776
05:36:38 1410 8192000000 9997542848 10375729152 1044453424 11.307 100 86 160 3 1118274176 56491696 17329056
05:37:46 13686 8192000000 9988539216 10373181440 1051741072 11.377 100 58 112 1 1115346992 52631808 10974112
05:39:07 2003 8192000000 10022211072 10401988608 1058710400 11.444 100 83 159 2 1145649280 71155808 15783072
05:40:15 1182 8192000000 10054258080 10432770048 1063289824 11.488 100 96 184 1 1169122160 86237920 19594416
05:41:21 2941 8192000000 10058003952 10431414272 1064426960 11.499 99 84 161 1 1172381440 91433536 16520944
05:42:26 2729 8192000000 10076315600 10466910208 1081875728 11.665 100 84 165 1 1194427024 95224272 17327024
05:43:34 17780 8192000000 10084013152 10476449792 1081896144 11.666 99 67 128 0 1197431888 102176112 13359632
05:44:56 3812 8192000000 10112490656 10503708672 1104674112 11.882 100 78 144 0 1226701504 106840480 15186912
05:46:02 5919 8192000000 10128792864 10524876800 1114693696 11.977 100 86 161 3 1243479248 111227504 17558048
05:47:13 982 8192000000 10153906416 10539372544 1120051872 12.027 100 90 169 1 1264837872 127984192 16801808
05:48:16 1025 8192000000 10179561424 10569367552 1138031248 12.197 100 93 181 1 1291013952 133815568 19167136
05:49:21 3117 8192000000 10231926752 10624598016 1143394160 12.247 100 83 159 1 1337643104 177769488 16479456
05:50:28 1011 8192000000 10217093712 10641829888 1152302256 12.331 100 96 189 1 1331619392 157693536 21623600
05:51:34 1410 8192000000 10246766208 10648559616 1151260016 12.321 100 93 177 1 1359234160 188796896 19177248
05:52:39 2242 8192000000 10259878992 10660192256 1160303536 12.406 100 93 176 0 1370936160 191463664 19168960
05:53:47 1217 8192000000 10293139200 10691035136 1166903872 12.468 100 92 184 1 1401642928 213466288 21272768
05:54:52 752 8192000000 10293673968 10691444736 1171129504 12.507 100 89 167 1 1401037392 211281552 18626336
05:55:57 1764 8192000000 10353658640 10766753792 1174980880 12.543 100 95 187 1 1454895520 258793216 21121424
05:57:03 2982 8192000000 10353698928 10768007168 1177951728 12.571 100 85 163 3 1458593248 261379296 19262224
05:58:14 1256 8192000000 10374339664 10806120448 1183410560 12.622 100 93 178 0 1478250096 273625008 21214528
05:59:20 954 8192000000 10386572944 10790207488 1183097136 12.619 100 98 192 0 1494794736 287141472 24556128
06:00:25 1474 8192000000 10409473280 10837004288 1195174768 12.731 100 94 185 1 1509469216 292768768 21525680
06:01:30 1015 8192000000 10412857232 10826907648 1187252144 12.658 100 93 181 1 1519603328 309540928 22810256
06:02:35 832 8192000000 10462081504 10877448192 1192046720 12.702 100 98 196 0 1565695856 350326144 23322992
06:03:40 670 8192000000 10527864560 10934091776 1204924608 12.822 100 97 192 0 1630640176 403458448 22257120
06:04:47 860 8192000000 10539364720 10957201408 1196254048 12.742 100 98 194 1 1639773936 420137520 23382368
06:05:52 3183 8192000000 10552618528 10976698368 1184480656 12.632 100 93 181 0 1649255408 442697344 22077408
06:06:59 876 8192000000 10585433200 11010342912 1185241584 12.639 100 86 167 1 1687875936 482232256 20402096
06:08:07 4443 8192000000 10577001072 11023474688 1168338608 12.481 100 75 147 2 1682398832 494922928 19137296
06:09:23 2407 8192000000 10513464272 10996699136 1170732880 12.504 100 70 136 0 1615798800 426927120 18138800
06:10:32 6267 8192000000 10479019120 10945617920 1163079120 12.432 100 50 95 3 1578287264 403503360 11704784
06:11:44 12301 8192000000 10438721296 10870337536 1169791760 12.495 100 38 76 1 1539118400 359336096 9990544
06:12:58 14273 8192000000 10409910560 10853732352 1161384784 12.416 100 29 58 0 1511360080 341735472 8239824
06:14:37 60396 8192000000 10269905728 10766852096 1128509184 12.107 98 20 38 2 1382058368 248656432 4892752
06:16:39 63646 8192000000 10060545152 10500857856 1101683616 11.854 97 10 20 1 1145696752 41310912 2702224
06:18:43 21528 8192000000 10109502960 10534273024 1134967232 12.168 99 58 109 1 1195210720 49698160 10545328
06:20:09 109992 8192000000 10121968448 10570280960 1121705152 12.043 98 35 69 1 1225166496 95665360 7795984
06:23:01 174635 8192000000 10090013152 10529411072 1109465216 11.927 97 34 69 0 1146138672 29987680 6685776
06:26:59 28784 8192000000 10118728864 10570960896 1149656144 12.306 100 46 86 1 1221958688 62523776 9778768
06:28:46 49991 8192000000 10075803664 10546577408 1130294576 12.124 98 13 24 1 1173101008 39605904 3200528
06:30:39 79180 8192000000 10077984544 10527793152 1067539568 11.529 94 23 45 0 1108453840 35898448 5015824
06:33:07 44189 8192000000 10102636240 10543628288 1103579344 11.872 98 26 50 1 1171300592 61851456 5869792
06:34:51 62397 8192000000 10076992784 10553364480 1099819904 11.836 98 19 38 2 1190072016 85640944 4611168
06:36:58 50096 8192000000 10058139600 10527256576 1117375904 12.002 99 18 36 0 1175149312 54006960 3766448
06:38:49 39184 8192000000 10108677872 10565697536 1111205792 11.944 98 28 56 1 1185916800 68150064 6560944
06:40:31 32363 8192000000 10070833504 10533527552 1130987888 12.131 100 34 66 1 1213855216 74706864 8160464
06:42:14 92626 8192000000 10081488000 10528997376 1113158224 11.962 98 25 49 1 1167690128 49911120 4620784
06:44:47 42129 8192000000 10088833184 10551181312 1126121216 12.085 99 30 60 0 1201006864 68882048 6003600
06:46:30 15664 8192000000 10160854480 10594185216 1137891248 12.196 100 48 96 0 1236621296 89428672 9301376
06:47:47 66632 8192000000 10133755280 10609258496 1128783776 12.110 99 19 38 1 1225112352 92453728 3874848
06:49:54 89608 8192000000 10068631312 10594324480 1114133808 11.972 98 28 55 0 1201981616 82096144 5751664
06:52:30 19911 8192000000 10087196320 10507247616 1123114272 12.056 100 45 88 1 1184026480 52060528 8851680
06:53:52 36419 8192000000 10075868976 10552385536 1123697584 12.062 99 22 44 0 1187836272 59843680 4295008
06:55:42 40758 8192000000 10092078912 10569031680 1141009312 12.225 100 10 20 0 1201640064 58698272 1932480
06:57:23 78816 8192000000 10079580672 10551881728 1091738432 11.759 96 7 14 0 1141032704 48019328 1274944
07:00:31 51137 8192000000 10089459888 10518528000 1122843808 12.054 99 36 73 0 1173620624 42945488 7831328
07:02:25 59960 8192000000 10091159008 10533744640 1099662960 11.834 96 33 64 1 1167449456 60689568 7096928
07:04:30 31560 8192000000 10127426992 10554089472 1115175376 11.981 99 40 80 0 1206219600 82708224 8336000
07:06:03 32588 8192000000 10093932576 10554204160 1104454976 11.880 98 46 92 2 1222201760 108214864 9531920
07:07:38 36060 8192000000 10071853664 10514620416 1136840032 12.186 100 25 48 0 1182661376 41422768 4398576
07:09:20 29931 8192000000 10096674992 10522415104 1106032640 11.895 98 50 98 0 1174224784 58280384 9911760
07:10:50 92166 8192000000 10111737088 10562617344 1115533360 11.985 99 48 93 0 1212152976 87097872 9521744
07:13:25 45662 8192000000 10084804880 10561261568 1115947392 11.989 99 9 18 0 1189944512 72165152 1831968
07:15:11 65052 8192000000 10080501616 10544021504 1116495056 11.994 99 10 20 0 1170844400 52102688 2246656
07:17:33 53054 8192000000 10080066288 10556628992 1121562000 12.042 99 33 66 0 1197434976 68062640 7810336
07:19:30 56376 8192000000 10045059968 10524639232 1097924912 11.818 97 22 43 5 1173290448 70273776 5091760
07:21:41 24309 8192000000 10106532128 10536665088 1122821904 12.054 99 47 94 0 1196872752 63337456 10713392
07:23:06 56358 8192000000 10125407344 10566483968 1116511008 11.994 98 32 64 2 1204307664 80736672 7059984
07:25:06 44784 8192000000 10093362432 10545352704 1124804032 12.072 99 12 24 2 1193270688 66247760 2218896
07:26:56 42087 8192000000 10073539184 10499026944 1119784528 12.025 98 31 59 1 1161314608 35082288 6447792
07:28:41 87887 8192000000 10107481168 10530414592 1126279200 12.086 99 64 117 1 1206374464 67609536 12485728
07:31:10 36619 8192000000 10056341600 10533732352 1125367264 12.078 99 6 13 0 1165843504 39297616 1178624
07:32:49 87383 8192000000 10066805120 10515881984 1095010080 11.790 96 7 14 0 1150414992 53776096 1628816
07:35:20 57463 8192000000 10063843712 10530541568 1100282928 11.840 97 10 19 1 1133399440 31054704 2061808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 29-03-2022 07:38:40 to 29-03-2022 07:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-03-2022 07:38:58 to 29-03-2022 07: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          211358
MON$OLDEST_ACTIVE               214361
MON$OLDEST_SNAPSHOT             214361
MON$NEXT_TRANSACTION            214361
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                       2082144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Tue Mar 29 07:37:13 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Tue Mar 29 07:37:19 2022
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Tue Mar 29 07:37:20 2022
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Tue Mar 29 07:37:20 2022
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Tue Mar 29 07:37:21 2022
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:169: 
--- end of diff output ---

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

29.03.22 07: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-03-29 07:39:03 No severe PSQL-related problems occured
29.03.22 07:39:04. End of report.