Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2022 07:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16473396 kB
MemAvailable 31596320 kB
Buffers 0 kB
Cached 15206636 kB
SwapCached 2128 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-28 07:37:09
DTS_END                         2022-03-28 07:37:09.6140
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/40714
TRN_ID                          215875
ATT_ID                          645
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: 9192.70 22479 1103124
customer order: creation 166.58 517 19990
customer order: refuse 14.13 415 1695
order to supplier: creation 42.67 1455 5120
order to supplier: removal 3.26 174286 391
invoice (draft): creation 44.55 3098 5346
invoice (draft): removal 51.28 995 6153
invoice accept: apply 41.95 25423 5034
invoice accept: cancel 46.07 1292 5528
customer reserve: creation 3900.80 240 468096
customer reserve: removal 4599.93 98 551992
realization accept: apply 81.63 272 9795
realization accept: cancel 74.32 165 8918
payment from customer: creation 57.97 173 6956
payment from customer: removal 5.42 155 650
payment to supplier: creation 47.12 182 5654
payment to supplier: removal 3.12 145 374
service: total inventory turnovers 3.63 4564 436
service: total monetary turnovers 7.48 326 897
service: aggregate perf. data 0.79 17531 95
service: refresh index statistics 0.03 218255 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-03-2022 07:38:20 to 28-03-2022 07:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8233
TEST_TIME 2 6433
TEST_TIME 3 7301
TEST_TIME 4 7244
TEST_TIME 5 6860
TEST_TIME 6 6730
TEST_TIME 7 6923
TEST_TIME 8 6634
TEST_TIME 9 6846
TEST_TIME 10 7112
TEST_TIME 11 6968
TEST_TIME 12 7156
TEST_TIME 13 7407
TEST_TIME 14 7539
TEST_TIME 15 7518
TEST_TIME 16 7542
TEST_TIME 17 7793
TEST_TIME 18 7946
TEST_TIME 19 7877
TEST_TIME 20 7866
TEST_TIME 21 8138
TEST_TIME 22 8327
TEST_TIME 23 8568
TEST_TIME 24 8398
TEST_TIME 25 8506
TEST_TIME 26 8748
TEST_TIME 27 9132
TEST_TIME 28 9510
TEST_TIME 29 9912
TEST_TIME 30 9970
TEST_TIME 31 10313
TEST_TIME 32 10675
TEST_TIME 33 10994
TEST_TIME 34 11412
TEST_TIME 35 11215
TEST_TIME 36 11075
TEST_TIME 37 10879
TEST_TIME 38 10653
TEST_TIME 39 10667
TEST_TIME 40 10760
TEST_TIME 41 10638
TEST_TIME 42 10515
TEST_TIME 43 10404
TEST_TIME 44 10339
TEST_TIME 45 10264
TEST_TIME 46 10261
TEST_TIME 47 10219
TEST_TIME 48 10114
TEST_TIME 49 10060
TEST_TIME 50 9981
TEST_TIME 51 10057
TEST_TIME 52 10046
TEST_TIME 53 10070
TEST_TIME 54 9983
TEST_TIME 55 9964
TEST_TIME 56 9933
TEST_TIME 57 10149
TEST_TIME 58 10192
TEST_TIME 59 10132
TEST_TIME 60 10184
TEST_TIME 61 10168
TEST_TIME 62 10105
TEST_TIME 63 9981
TEST_TIME 64 10001
TEST_TIME 65 9934
TEST_TIME 66 9885
TEST_TIME 67 9839
TEST_TIME 68 9782
TEST_TIME 69 9695
TEST_TIME 70 9654
TEST_TIME 71 9605
TEST_TIME 72 9633
TEST_TIME 73 9669
TEST_TIME 74 9663
TEST_TIME 75 9610
TEST_TIME 76 9571
TEST_TIME 77 9579
TEST_TIME 78 9569
TEST_TIME 79 9576
TEST_TIME 80 9686
TEST_TIME 81 9782
TEST_TIME 82 9765
TEST_TIME 83 9736
TEST_TIME 84 9747
TEST_TIME 85 9681
TEST_TIME 86 9589
TEST_TIME 87 9556
TEST_TIME 88 9568
TEST_TIME 89 9546
TEST_TIME 90 9483
TEST_TIME 91 9443
TEST_TIME 92 9416
TEST_TIME 93 9369
TEST_TIME 94 9398
TEST_TIME 95 9385
TEST_TIME 96 9412
TEST_TIME 97 9389
TEST_TIME 98 9346
TEST_TIME 99 9377
TEST_TIME 100 9448
TEST_TIME 101 9419
TEST_TIME 102 9444
TEST_TIME 103 9497
TEST_TIME 104 9581
TEST_TIME 105 9556
TEST_TIME 106 9483
TEST_TIME 107 9414
TEST_TIME 108 9439
TEST_TIME 109 9439
TEST_TIME 110 9388
TEST_TIME 111 9356
TEST_TIME 112 9324
TEST_TIME 113 9292
TEST_TIME 114 9236
TEST_TIME 115 9252
TEST_TIME 116 9270
TEST_TIME 117 9268
TEST_TIME 118 9244
TEST_TIME 119 9284
TEST_TIME 120 9213

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-03-2022 07:38:24 to 28-03-2022 07:38:27.

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 1533489 1533489 0 0.00 0 41738 13 0 0
doc_list_biud 1533489 1533489 0 0.00 0 56040 41 0 0
sp_add_invoice_to_stock 5134 5034 100 1.94 46 190303 25423 0 100
sp_cancel_adding_invoice 5528 5528 0 0.00 6 75788 1292 0 0
sp_cancel_client_order 1695 1695 0 0.00 6 9437 415 0 0
sp_cancel_customer_reserve 551992 551992 0 0.00 1 34398 99 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 4 12185 156 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 4 4179 145 0 0
sp_cancel_supplier_invoice 6159 6153 6 0.09 2 56042 995 0 6
sp_cancel_supplier_order 391 391 0 0.00 6 1413708 174287 0 0
sp_cancel_write_off 8918 8918 0 0.00 2 24535 165 0 0
sp_client_order 19990 19990 0 0.00 6 38141 518 0 0
sp_customer_reserve 468096 468096 0 0.00 0 110589 240 0 0
sp_fill_shopping_cart 30445 30445 0 0.00 1 15479 83 0 0
sp_fill_shopping_cart_clo_res 468089 468089 0 0.00 0 15214 23 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 28851 355 0 0
sp_kill_cost_storno 15470 15470 0 0.00 0 8968 66 0 0
sp_kill_qstorno_ret_qs2qd 1119446 1119446 0 0.00 0 56039 43 0 0
sp_kill_qty_storno 589466 589466 0 0.00 0 56040 99 0 0
sp_lock_selected_doc 20296 20296 0 0.00 0 9023 7 0 0
sp_make_cost_storno 27399 27399 0 0.00 0 22370 75 0 0
sp_multiply_rows_for_pdistr 27399 27399 0 0.00 0 6509 35 0 0
sp_multiply_rows_for_qdistr 19990 19990 0 0.00 0 16423 233 0 0
sp_pay_from_customer 6956 6956 0 0.00 5 10928 174 0 0
sp_pay_to_supplier 5654 5654 0 0.00 6 9607 183 0 0
sp_payment_common 12610 12610 0 0.00 2 10911 45 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 1 8732 342 0 0
sp_qd_handle_on_invoice_upd_sts 10522 10522 0 0.00 0 30942 516 0 0
sp_qd_handle_on_reserve_upd_sts 9795 9795 0 0.00 0 20998 68 0 0
sp_reserve_write_off 9795 9795 0 0.00 9 21190 272 0 0
sp_supplier_invoice 5346 5346 0 0.00 10 90802 3098 0 0
sp_supplier_order 5120 5120 0 0.00 7 37604 1456 0 0
srv_aggregate_perf_data 95 95 0 0.00 2324 159318 17532 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 824 128715 36466 0 0
srv_find_qd_qs_mism 1054669 1054669 0 0.00 0 41736 10 0 0
srv_make_invnt_saldo 436 436 0 0.00 80 113713 4564 0 0
srv_make_money_saldo 897 897 0 0.00 1 10451 326 0 0
srv_recalc_idx_stat 4 4 0 0.00 158988 301077 218255 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5034 5034 0 0.00 1 12658 942 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 6397 582 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 4076 27 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1217 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 4729 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 733 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2074 37 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 103200 6866 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 4677 25 0 0
t$perf-norm:sp_client_order 19990 19990 0 0.00 0 6306 36 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 815 15 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 3715 20 0 0
t$perf-norm:sp_pay_to_supplier 5654 5654 0 0.00 0 6309 19 0 0
t$perf-norm:sp_reserve_write_off 9795 9795 0 0.00 0 5644 23 0 0
t$perf-norm:sp_supplier_invoice 5346 5346 0 0.00 0 6082 33 0 0
t$perf-norm:sp_supplier_order 5120 5120 0 0.00 0 4347 31 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 421 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 3739 67 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 800 15 0 0
t$perf-norm:srv_make_money_saldo 897 897 0 0.00 0 1910 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 337 88 0 0
v_add_invoice_to_stock 10188 9988 200 1.96 0 4916 24 0 200
v_all_customers 36796 36796 0 0.00 0 70 4 0 0
v_all_suppliers 29282 29282 0 0.00 0 93 4 0 0
v_all_wares 39980 39980 0 0.00 0 48 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1566 16 0 0
v_cancel_client_order 3390 3390 0 0.00 0 3019 11 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 1891 14 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1974 13 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 4171 22 0 0
v_cancel_supplier_invoice 768 756 12 1.56 0 3001 26 0 12
v_cancel_supplier_order 782 782 0 0.00 0 1693 17 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2012 24 0 0
v_max_id_clo_ord 5120 5120 0 0.00 0 810 5 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 6943 20 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 3028 7 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 49 7 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 1148 7 0 0
v_min_id_clo_ord 5120 5120 0 0.00 0 2741 7 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 5804 16 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 5910 11 0 0
v_min_non_paid_invoice 5654 5654 0 0.00 0 9568 15 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 9316 19 0 0
v_our_firm 10002 10002 0 0.00 0 73 4 0 0
v_reserve_write_off 19590 19590 0 0.00 0 2735 11 0 0
x_lock_depdocs_on_canc_invoice 11303 11303 0 0.00 0 22004 121 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 6654 349 0 0
x_make_qstorno_1000_1200 5120 5120 0 0.00 3 37593 1295 0 0
x_make_qstorno_1200_2000 5346 5346 0 0.00 5 90794 2860 0 0
x_make_qstorno_2100_3300 468096 468096 0 0.00 0 110587 206 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 28-03-2022 07:38:28 to 28-03-2022 07:38:33.

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:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:14 29098 8192000000 9713113888 10012102656 788823328 8.783 100 87 174 0 817548240 7675344 21049568
05:09:48 9953 8192000000 9751238656 10054819840 825078384 9.150 100 94 188 0 860307744 12720608 22508752
05:11:05 14448 8192000000 9780064112 10094039040 844509824 9.345 100 84 168 0 885273984 20446864 20317296
05:12:23 16478 8192000000 9861947312 10189680640 909422208 9.992 100 84 168 0 965864016 37274832 19166976
05:13:45 4813 8192000000 9933083408 10275344384 957554720 10.465 100 87 171 0 1044103968 68181680 18367568
05:14:54 2445 8192000000 9973665264 10327777280 991751760 10.798 100 95 184 1 1086506256 75953728 18800768
05:15:57 1730 8192000000 10024220336 10387030016 1020411648 11.076 100 91 170 5 1138973344 101175472 17386224
05:17:00 1227 8192000000 10014091264 10380910592 1035701888 11.223 100 96 185 1 1134089648 79469520 18918240
05:18:04 1203 8192000000 10044995264 10421997568 1057030896 11.428 100 96 186 0 1163776224 87964240 18781088
05:19:06 752 8192000000 10043121568 10418982912 1060812240 11.464 100 98 194 1 1163115584 82903200 19400144
05:20:08 1155 8192000000 10030669904 10411028480 1069276416 11.545 100 94 178 2 1151546512 63910528 18359568
05:21:10 1081 8192000000 10049017824 10431381504 1076864528 11.618 100 94 183 1 1170634048 73859280 19910240
05:22:12 1359 8192000000 10054849552 10442133504 1080686656 11.654 100 92 172 0 1173237200 74340800 18209744
05:23:14 1220 8192000000 10044701984 10434682880 1082606640 11.672 100 89 169 4 1164414384 64313616 17494128
05:24:16 1235 8192000000 10037377008 10428592128 1092596464 11.767 100 84 159 2 1166022480 56108592 17317424
05:25:18 2210 8192000000 10055396896 10450182144 1095222400 11.792 100 76 145 3 1179549152 69279792 15046960
05:26:21 1120 8192000000 10050553696 10442612736 1097758288 11.816 100 96 182 1 1172211680 55380048 19073344
05:27:23 2891 8192000000 10036319104 10433253376 1100128224 11.839 100 57 110 0 1165104240 54112080 10863936
05:28:27 1288 8192000000 10060463744 10448859136 1100902144 11.846 100 86 169 2 1182126064 64720384 16503536
05:29:29 2134 8192000000 10046523104 10440040448 1088827792 11.732 99 71 135 3 1167440720 64214528 14398400
05:30:32 1478 8192000000 10019306544 10417643520 1088504448 11.728 100 60 110 3 1148148624 48995120 10649056
05:31:35 1010 8192000000 9984938304 10363502592 1045337344 11.316 100 93 168 1 1106313568 44360272 16615952
05:32:37 1130 8192000000 10040532000 10422444032 1068789984 11.541 100 95 182 1 1162706656 74551376 19365296
05:33:38 1036 8192000000 9990545184 10370265088 1042979824 11.293 100 83 157 3 1116770784 54167520 19623440
05:34:40 1194 8192000000 10008864240 10382651392 1047433024 11.336 100 93 178 0 1131551568 66511232 17607312
05:35:42 1252 8192000000 9989557760 10358898688 1025085296 11.121 99 89 170 5 1103564176 62459296 16019584
05:36:44 1203 8192000000 10012351424 10394030080 1044939296 11.312 100 80 153 2 1136167568 75504288 15723984
05:37:49 957 8192000000 9997530912 10369363968 1057874336 11.436 100 94 177 3 1117924896 41887520 18163040
05:38:57 23916 8192000000 10038648016 10424791040 1075683344 11.606 100 57 114 0 1155922608 69073264 11166000
05:40:35 886 8192000000 10068193616 10451152896 1092710016 11.768 100 84 151 0 1184694000 78203792 13780192
05:41:40 1955 8192000000 10078125616 10465472512 1104969792 11.885 100 79 149 2 1198410768 78999168 14441808
05:42:45 942 8192000000 10128500592 10516881408 1121977952 12.046 100 96 184 2 1247006336 104680304 20348080
05:43:49 1768 8192000000 10174892640 10559246336 1129927088 12.121 100 89 166 3 1283343680 136200864 17215728
05:44:55 2174 8192000000 10178031120 10574098432 1135181104 12.170 100 79 154 2 1293288528 140917040 17190384
05:46:03 1178 8192000000 10217988880 10614865920 1142985824 12.244 100 91 180 0 1336929888 174144464 19799600
05:47:08 2304 8192000000 10215486224 10631323648 1147901760 12.290 100 85 165 2 1336067648 168556096 19609792
05:48:14 4639 8192000000 10258178400 10660925440 1159658160 12.400 100 77 152 0 1369019904 191401056 17960688
05:49:32 1370 8192000000 10265146304 10686197760 1171469904 12.511 100 94 186 0 1382101472 190118672 20512896
05:50:37 2174 8192000000 10296460160 10707558400 1176666320 12.559 100 91 180 1 1407195264 209938160 20590784
05:51:42 1080 8192000000 10343530736 10745094144 1179670000 12.587 100 92 183 1 1456351296 256207584 20473712
05:52:46 866 8192000000 10359441328 10773803008 1180170560 12.592 100 90 177 0 1468346672 267987776 20188336
05:53:51 984 8192000000 10402356416 10815741952 1189473728 12.678 100 99 194 1 1510757664 298158832 23125104
05:54:54 1780 8192000000 10420714160 10835222528 1189374928 12.678 100 91 173 1 1526062720 317471888 19215904
05:56:01 1336 8192000000 10433285504 10842542080 1185859440 12.645 100 94 188 2 1546549456 339823280 20866736
05:57:08 2428 8192000000 10456943136 10879995904 1188900704 12.673 100 92 178 2 1562053328 352347184 20805440
05:58:13 814 8192000000 10506132432 10951536640 1194103552 12.722 100 97 188 0 1611658512 394669392 22885568
05:59:18 1665 8192000000 10493804384 10941296640 1187673312 12.662 100 90 179 2 1597662384 388526688 21462384
06:00:23 3556 8192000000 10499069088 10926690304 1185249648 12.639 100 66 130 1 1602907232 401341440 16316144
06:01:41 6734 8192000000 10527572800 10950414336 1174292512 12.537 100 52 104 0 1633846656 445599888 13954256
06:02:55 1529 8192000000 10523208928 10984402944 1183232896 12.620 100 85 170 0 1627850416 423471264 21146256
06:04:01 9291 8192000000 10528962416 11000877056 1174893104 12.543 100 59 118 0 1632743344 442413104 15437136
06:05:17 18606 8192000000 10439791072 10976104448 1171984800 12.515 100 39 78 1 1560438192 377554064 10899328
06:06:42 27272 8192000000 10387827536 10915557376 1162885024 12.430 99 36 72 2 1493215104 321435584 8894496
06:08:11 37922 8192000000 10322124688 10893418496 1157920496 12.384 100 19 38 1 1490902112 327818224 5163392
06:09:55 52366 8192000000 10158808576 10603003904 1107151200 11.905 96 19 37 1 1235188912 123388912 4648800
06:11:49 55244 8192000000 10047606384 10481639424 1098121296 11.820 97 15 29 1 1128275120 27435440 2718384
06:13:48 45730 8192000000 10099665808 10523738112 1131066256 12.131 99 47 92 5 1196736016 56229456 9440304
06:15:37 121414 8192000000 10070162656 10579427328 1131181584 12.132 99 25 50 0 1195033008 58589728 5261696
06:18:42 50784 8192000000 10104211152 10573856768 1138219440 12.199 100 21 42 0 1199927168 57356512 4351216
06:20:34 14960 8192000000 10097980976 10542362624 1140119040 12.217 100 27 52 0 1199812016 54096160 5596816
06:21:52 44653 8192000000 10124634768 10559369216 1122950032 12.055 99 44 87 2 1211856832 78319488 10587312
06:23:48 35056 8192000000 10083936960 10578567168 1117084656 11.999 100 10 19 1 1197852608 78818112 1949840
06:25:26 22694 8192000000 10083157968 10534637568 1112181008 11.953 99 15 30 0 1182227536 66586864 3459664
06:26:49 56791 8192000000 10107008880 10571431936 1120111056 12.028 100 19 38 0 1207346624 82790736 4444832
06:28:48 73353 8192000000 10087545392 10554904576 1101647920 11.853 97 23 46 1 1189850960 82638640 5564400
06:31:08 40544 8192000000 10117027312 10554875904 1119383008 12.021 99 28 54 1 1202912624 77851776 5677840
06:32:55 65982 8192000000 10140287376 10596564992 1110323312 11.935 99 22 44 0 1242879632 127240928 5315392
06:35:15 86793 8192000000 10079905872 10513461248 1085837808 11.703 96 23 46 0 1134531840 44174096 4519936
06:37:42 38813 8192000000 10099259664 10532954112 1121835984 12.044 99 40 80 0 1173230992 42903120 8491888
06:39:23 99475 8192000000 10104332016 10552668160 1133839200 12.158 100 25 50 0 1198887664 59646096 5402368
06:42:03 79552 8192000000 10099708624 10559455232 1121348656 12.040 98 32 64 0 1180045184 52183712 6512816
06:44:29 71189 8192000000 10118492256 10594148352 1139184560 12.208 99 14 28 1 1210060832 67800192 3076080
06:47:04 80475 8192000000 10167618048 10626904064 1163147120 12.433 100 22 42 0 1268205168 100015376 5042672
06:49:25 45305 8192000000 10079344688 10581585920 1148471872 12.295 99 17 34 1 1231587920 78788192 4327856
06:51:12 44495 8192000000 10097479248 10558107648 1126011712 12.084 97 19 38 1 1183081088 52998928 4070448
06:52:57 37925 8192000000 10154753760 10599309312 1118053408 12.009 97 30 60 0 1219270976 95025952 6191616
06:54:46 48234 8192000000 10169055504 10632966144 1127417984 12.097 98 26 52 0 1234763312 102038544 5306784
06:56:36 41064 8192000000 10181132656 10617868288 1150705520 12.316 100 31 62 1 1293612368 136100240 6806608
06:58:19 39977 8192000000 10089975600 10616770560 1105164816 11.887 97 17 34 0 1216428368 107511536 3752016
07:00:05 4203 8192000000 10074490768 10490335232 1113651408 11.967 100 73 146 0 1173689568 43380560 16657600
07:01:13 95859 8192000000 10106923200 10590351360 1106366688 11.898 99 43 80 0 1197753440 83565488 7821264
07:03:50 57621 8192000000 10115155184 10559856640 1105385616 11.889 98 29 58 1 1195167712 83457552 6324544
07:05:55 61361 8192000000 10036597200 10559602688 1089378864 11.737 98 5 10 0 1130025552 39814048 832640
07:07:57 46228 8192000000 10053015920 10510123008 1108139520 11.915 99 25 50 1 1152014672 36750160 7124992
07:09:47 82449 8192000000 10102315248 10570989568 1111559456 11.947 98 37 72 0 1193918608 74393920 7965232
07:12:11 22957 8192000000 10120292624 10569379840 1111239648 11.944 98 28 56 1 1205325312 87675456 6410208
07:13:38 29894 8192000000 10127476320 10589609984 1127714528 12.100 100 31 62 1 1224600336 90346016 6539792
07:15:08 34267 8192000000 10153133184 10584567808 1123016368 12.055 99 40 79 1 1234629120 103358800 8253952
07:16:46 66887 8192000000 10087994112 10540105728 1090932368 11.752 97 34 62 1 1225776368 128238944 6605056
07:18:53 32333 8192000000 10123664720 10582622208 1109437696 11.927 99 43 86 1 1200780880 82842512 8500672
07:20:37 79571 8192000000 10098712464 10617229312 1098110912 11.820 97 10 20 1 1207575856 107373200 2091744
07:22:57 128708 8192000000 10098340608 10536738816 1124632960 12.071 100 35 68 2 1218156896 86658224 6865712
07:26:11 42817 8192000000 10044853040 10510544896 1126411408 12.088 100 14 28 1 1164970640 36027504 2531728
07:27:54 58629 8192000000 10049928832 10512248832 1110597248 11.938 99 14 29 0 1146409104 33076960 2734896
07:29:59 53966 8192000000 10052680816 10527064064 1110891280 11.941 99 10 20 0 1145262864 32281696 2089888
07:32:32 66717 8192000000 10092272960 10556981248 1088694272 11.730 96 15 30 0 1172314928 80079088 3541568
07:34:58 34599 8192000000 10098812384 10549850112 1110851856 11.940 98 30 58 1 1188717808 71412448 6453504
07:36:33 36755 8192000000 9940823376 10354937856 979837168 10.683 87 20 40 1 1050353744 66346672 4169904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-03-2022 07:38:35 to 28-03-2022 07:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-03-2022 07:38:52 to 28-03-2022 07:38:53.

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          211435
MON$OLDEST_ACTIVE               216420
MON$OLDEST_SNAPSHOT             216420
MON$NEXT_TRANSACTION            216420
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                       2059104
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             767
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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Mon Mar 28 07:37:17 2022
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
--- end of diff output ---

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

28.03.22 07:38:54. 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-28 07:38:57 No severe PSQL-related problems occured
28.03.22 07:38:58. End of report.