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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16419340 kB
MemAvailable 31543800 kB
Buffers 0 kB
Cached 15307780 kB
SwapCached 2688 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-04-13 10:37:16
DTS_END                         2022-04-13 10:37:16.0290
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/48828
TRN_ID                          214406
ATT_ID                          650
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: 8740.59 25460 1048871
customer order: creation 166.86 486 20023
customer order: refuse 14.33 415 1720
order to supplier: creation 43.73 1375 5247
order to supplier: removal 3.29 173515 395
invoice (draft): creation 44.16 3176 5299
invoice (draft): removal 48.23 985 5787
invoice accept: apply 40.48 27065 4858
invoice accept: cancel 44.26 1278 5311
customer reserve: creation 3780.96 257 453715
customer reserve: removal 4270.71 105 512485
realization accept: apply 79.54 284 9545
realization accept: cancel 78.41 166 9409
payment from customer: creation 57.95 189 6954
payment from customer: removal 5.17 118 620
payment to supplier: creation 46.60 203 5592
payment to supplier: removal 3.72 113 446
service: total inventory turnovers 3.62 4360 434
service: total monetary turnovers 7.64 395 917
service: aggregate perf. data 0.92 15187 110
service: refresh index statistics 0.03 279537 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6498
TEST_TIME 2 6179
TEST_TIME 3 6243
TEST_TIME 4 6808
TEST_TIME 5 6220
TEST_TIME 6 6342
TEST_TIME 7 6121
TEST_TIME 8 6001
TEST_TIME 9 6098
TEST_TIME 10 6509
TEST_TIME 11 6649
TEST_TIME 12 6622
TEST_TIME 13 6679
TEST_TIME 14 6929
TEST_TIME 15 6886
TEST_TIME 16 7261
TEST_TIME 17 7522
TEST_TIME 18 7501
TEST_TIME 19 7771
TEST_TIME 20 8054
TEST_TIME 21 7978
TEST_TIME 22 8166
TEST_TIME 23 8286
TEST_TIME 24 8402
TEST_TIME 25 8685
TEST_TIME 26 8750
TEST_TIME 27 9005
TEST_TIME 28 8990
TEST_TIME 29 9033
TEST_TIME 30 9213
TEST_TIME 31 9209
TEST_TIME 32 9853
TEST_TIME 33 10072
TEST_TIME 34 10248
TEST_TIME 35 10685
TEST_TIME 36 10869
TEST_TIME 37 10720
TEST_TIME 38 10480
TEST_TIME 39 10374
TEST_TIME 40 10198
TEST_TIME 41 10099
TEST_TIME 42 9996
TEST_TIME 43 9935
TEST_TIME 44 9920
TEST_TIME 45 9834
TEST_TIME 46 9739
TEST_TIME 47 9695
TEST_TIME 48 9619
TEST_TIME 49 9747
TEST_TIME 50 9564
TEST_TIME 51 9420
TEST_TIME 52 9550
TEST_TIME 53 9504
TEST_TIME 54 9565
TEST_TIME 55 9494
TEST_TIME 56 9473
TEST_TIME 57 9467
TEST_TIME 58 9401
TEST_TIME 59 9344
TEST_TIME 60 9369
TEST_TIME 61 9436
TEST_TIME 62 9496
TEST_TIME 63 9563
TEST_TIME 64 9591
TEST_TIME 65 9486
TEST_TIME 66 9362
TEST_TIME 67 9346
TEST_TIME 68 9355
TEST_TIME 69 9287
TEST_TIME 70 9249
TEST_TIME 71 9226
TEST_TIME 72 9224
TEST_TIME 73 9192
TEST_TIME 74 9125
TEST_TIME 75 9063
TEST_TIME 76 9079
TEST_TIME 77 9083
TEST_TIME 78 9087
TEST_TIME 79 9116
TEST_TIME 80 9141
TEST_TIME 81 9112
TEST_TIME 82 9164
TEST_TIME 83 9138
TEST_TIME 84 9121
TEST_TIME 85 9114
TEST_TIME 86 9157
TEST_TIME 87 9161
TEST_TIME 88 9124
TEST_TIME 89 9040
TEST_TIME 90 8980
TEST_TIME 91 9019
TEST_TIME 92 8972
TEST_TIME 93 8919
TEST_TIME 94 8923
TEST_TIME 95 8914
TEST_TIME 96 8877
TEST_TIME 97 8847
TEST_TIME 98 8871
TEST_TIME 99 8934
TEST_TIME 100 8939
TEST_TIME 101 8913
TEST_TIME 102 8891
TEST_TIME 103 8840
TEST_TIME 104 9027
TEST_TIME 105 9014
TEST_TIME 106 9034
TEST_TIME 107 9055
TEST_TIME 108 9058
TEST_TIME 109 9017
TEST_TIME 110 8948
TEST_TIME 111 8972
TEST_TIME 112 8979
TEST_TIME 113 8945
TEST_TIME 114 8905
TEST_TIME 115 8867
TEST_TIME 116 8845
TEST_TIME 117 8834
TEST_TIME 118 8823
TEST_TIME 119 8821
TEST_TIME 120 8762

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-04-2022 10:38:41 to 13-04-2022 10:38:45.

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 1466618 1466618 0 0.00 0 22721 14 0 0
doc_list_biud 1466606 1466606 0 0.00 0 26364 43 0 0
sp_add_invoice_to_stock 4977 4858 119 2.39 73 223332 27065 0 119
sp_cancel_adding_invoice 5311 5311 0 0.00 6 99766 1279 0 0
sp_cancel_client_order 1720 1720 0 0.00 6 13016 416 0 0
sp_cancel_customer_reserve 512485 512485 0 0.00 1 22908 105 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 4 4471 118 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 4 2487 114 0 0
sp_cancel_supplier_invoice 5793 5787 6 0.10 3 19979 985 0 6
sp_cancel_supplier_order 395 395 0 0.00 22 1182729 173515 0 0
sp_cancel_write_off 9409 9409 0 0.00 2 11058 166 0 0
sp_client_order 20023 20023 0 0.00 6 23460 486 0 0
sp_customer_reserve 453715 453715 0 0.00 1 55961 257 0 0
sp_fill_shopping_cart 30558 30558 0 0.00 2 9120 84 0 0
sp_fill_shopping_cart_clo_res 453690 453690 0 0.00 0 14115 19 0 0
sp_get_clo_for_invoice 4825 4825 0 0.00 0 21395 325 0 0
sp_kill_cost_storno 15786 15786 0 0.00 0 11084 78 0 0
sp_kill_qstorno_ret_qs2qd 1040561 1040561 0 0.00 0 22720 46 0 0
sp_kill_qty_storno 549476 549476 0 0.00 0 26364 105 0 0
sp_lock_selected_doc 19953 19953 0 0.00 0 9064 8 0 0
sp_make_cost_storno 26915 26915 0 0.00 0 11170 76 0 0
sp_multiply_rows_for_pdistr 26914 26914 0 0.00 0 20231 40 0 0
sp_multiply_rows_for_qdistr 20021 20021 0 0.00 0 17018 190 0 0
sp_pay_from_customer 6954 6954 0 0.00 6 20302 189 0 0
sp_pay_to_supplier 5592 5592 0 0.00 7 16317 204 0 0
sp_payment_common 12544 12544 0 0.00 2 12030 42 0 0
sp_qd_handle_on_cancel_clo 1720 1720 0 0.00 0 13007 340 0 0
sp_qd_handle_on_invoice_upd_sts 10135 10135 0 0.00 0 26363 553 0 0
sp_qd_handle_on_reserve_upd_sts 9545 9545 0 0.00 0 11067 69 0 0
sp_reserve_write_off 9545 9545 0 0.00 9 16274 285 0 0
sp_supplier_invoice 5299 5299 0 0.00 8 102553 3176 0 0
sp_supplier_order 5247 5247 0 0.00 8 104612 1375 0 0
srv_aggregate_perf_data 110 110 0 0.00 2346 166560 15188 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 704 124341 35851 0 0
srv_find_qd_qs_mism 1001253 1001253 0 0.00 0 22720 11 0 0
srv_make_invnt_saldo 434 434 0 0.00 92 89691 4361 0 0
srv_make_money_saldo 917 917 0 0.00 1 16434 396 0 0
srv_recalc_idx_stat 4 4 0 0.00 82938 393596 279538 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4864 4864 0 0.00 1 11786 980 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 6025 534 0 0
t$perf-norm:sp_cancel_client_order 1720 1720 0 0.00 0 11245 41 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1751 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 3270 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 933 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 7579 56 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 93058 7975 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 7459 26 0 0
t$perf-norm:sp_client_order 20024 20024 0 0.00 0 12717 41 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 441 13 0 0
t$perf-norm:sp_pay_from_customer 6954 6954 0 0.00 0 11498 27 0 0
t$perf-norm:sp_pay_to_supplier 5592 5592 0 0.00 0 7822 25 0 0
t$perf-norm:sp_reserve_write_off 9545 9545 0 0.00 0 8311 26 0 0
t$perf-norm:sp_supplier_invoice 5299 5299 0 0.00 0 17146 43 0 0
t$perf-norm:sp_supplier_order 5247 5247 0 0.00 0 2533 32 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 419 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 74 5 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 7897 39 0 0
t$perf-norm:srv_make_money_saldo 917 917 0 0.00 0 1027 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 179 55 0 0
v_add_invoice_to_stock 9882 9644 238 2.40 0 5978 26 0 238
v_all_customers 36444 36444 0 0.00 0 79 4 0 0
v_all_suppliers 29188 29188 0 0.00 0 158 4 0 0
v_all_wares 40040 40040 0 0.00 0 123 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 2242 19 0 0
v_cancel_client_order 3440 3440 0 0.00 0 2124 10 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 4410 14 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 1 3067 13 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2439 14 0 0
v_cancel_supplier_invoice 722 710 12 1.66 0 3625 28 0 12
v_cancel_supplier_order 790 790 0 0.00 0 1545 14 0 0
v_cancel_write_off 1998 1998 0 0.00 0 1198 23 0 0
v_max_id_clo_ord 5245 5245 0 0.00 0 6258 7 0 0
v_max_id_clo_res 962 962 0 0.00 0 3172 15 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 4067 7 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 403 7 0 0
v_max_non_paid_realizn 3708 3708 0 0.00 0 12021 10 0 0
v_min_id_clo_ord 5245 5245 0 0.00 0 5664 10 0 0
v_min_id_clo_res 962 962 0 0.00 0 7714 19 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 4866 12 0 0
v_min_non_paid_invoice 5592 5592 0 0.00 0 2681 12 0 0
v_min_non_paid_realizn 6952 6952 0 0.00 0 7298 16 0 0
v_our_firm 10082 10082 0 0.00 0 87 5 0 0
v_reserve_write_off 19088 19088 0 0.00 0 10573 12 0 0
x_lock_depdocs_on_canc_invoice 10743 10743 0 0.00 0 38998 133 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 9062 369 0 0
x_make_qstorno_1000_1200 5247 5247 0 0.00 2 104596 1198 0 0
x_make_qstorno_1200_2000 5299 5299 0 0.00 4 102539 2954 0 0
x_make_qstorno_2100_3300 453715 453715 0 0.00 0 55913 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 10:38:46 to 13-04-2022 10:38:50.

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:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 41466 8192000000 9735768320 10038329344 807193104 8.969 100 88 176 0 836783856 8325632 21265120
08:10:07 17431 8192000000 9777672016 10087759872 847868208 9.379 100 91 182 0 884206592 15347744 20990640
08:11:35 19606 8192000000 9821417792 10145435648 878009840 9.680 100 81 159 2 923040720 27181408 17849472
08:12:58 10193 8192000000 9878975568 10210836480 914646480 10.043 100 86 171 1 987847152 54358032 18842640
08:14:13 4343 8192000000 9910395888 10256183296 955717136 10.447 100 84 165 0 1029543824 56288256 17538432
08:15:19 1677 8192000000 9962716672 10313199616 982834864 10.712 100 90 178 0 1072936208 72468064 17633280
08:16:23 1197 8192000000 9993824944 10352975872 1008489120 10.961 100 91 179 2 1112646608 85946800 18210688
08:17:25 1761 8192000000 9997437184 10364579840 1030798672 11.176 100 87 167 3 1124652992 78218080 15636240
08:18:28 1237 8192000000 10039415824 10413383680 1055463904 11.413 100 93 183 1 1155418272 81274512 18679856
08:19:30 955 8192000000 10017883280 10395373568 1065598432 11.510 100 93 173 1 1135214096 50581536 19034128
08:20:33 1266 8192000000 10068939296 10448670720 1067934928 11.532 100 98 197 1 1187488272 99813312 19740032
08:21:35 1219 8192000000 10033351456 10413850624 1074500224 11.595 100 92 177 0 1155939376 62131712 19307440
08:22:37 1088 8192000000 10048198992 10433978368 1078256288 11.631 100 95 182 1 1173376384 75609984 19510112
08:23:39 1574 8192000000 10051739040 10440376320 1086327968 11.708 100 98 186 1 1175615520 69862816 19424736
08:24:42 1209 8192000000 10064097520 10455728128 1086526944 11.710 100 95 180 1 1183606368 78208688 18870736
08:25:44 1185 8192000000 10064832000 10451472384 1088724944 11.731 100 96 190 1 1182808992 74738528 19345520
08:26:46 6538 8192000000 10051677920 10444472320 1096738832 11.807 100 68 125 3 1181814608 72453600 12622176
08:27:54 1028 8192000000 10060284288 10451177472 1099627008 11.834 100 94 177 2 1180456640 62112496 18717136
08:28:55 5926 8192000000 10051867280 10443739136 1093892816 11.780 100 73 139 2 1168966016 61554304 13518896
08:30:02 967 8192000000 10038772336 10427691008 1087712128 11.721 100 86 166 3 1163687344 58401728 17573488
08:31:04 1177 8192000000 10026077984 10404417536 1054008176 11.399 100 92 179 2 1151149440 78567520 18573744
08:32:06 1274 8192000000 10011719104 10398146560 1064374960 11.498 99 81 158 1 1131219504 51415968 15428576
08:33:08 1110 8192000000 9974952400 10346254336 1041471680 11.279 100 86 166 1 1099342288 40381744 17488864
08:34:10 1092 8192000000 9981025856 10350407680 1026615344 11.136 100 90 174 0 1101780752 57226560 17938848
08:35:14 1067 8192000000 9994852560 10366496768 1033179776 11.199 100 88 163 2 1117344224 67545120 16619328
08:36:15 1536 8192000000 9970981744 10341142528 1031226016 11.180 100 78 155 2 1098345872 50428848 16691008
08:37:22 9917 8192000000 9978115280 10362167296 1058233296 11.440 100 58 102 1 1104863488 37002368 9627824
08:38:37 1717 8192000000 10049972000 10428977152 1074700816 11.597 100 87 169 0 1169585456 77446160 17438480
08:39:44 2920 8192000000 10061563104 10442866688 1092673120 11.768 100 73 136 4 1176206736 70126880 13406736
08:40:53 8208 8192000000 10089465472 10487328768 1104712448 11.882 100 71 134 2 1217840160 98629216 14498496
08:42:05 6400 8192000000 10113031472 10505715712 1125177200 12.076 100 69 128 1 1230598000 91140784 14280016
08:43:13 3968 8192000000 10152827760 10555891712 1133627504 12.156 100 87 156 2 1270737488 120247232 16862752
08:44:21 2286 8192000000 10183479376 10580508672 1145739376 12.269 100 88 170 3 1296570288 132752112 18078800
08:45:28 2966 8192000000 10193078032 10591821824 1147667376 12.288 100 84 161 2 1310282608 144904752 17710480
08:46:36 1439 8192000000 10233754688 10640130048 1160193344 12.405 100 93 185 0 1349051472 167694224 21163904
08:47:40 2616 8192000000 10237154752 10638700544 1167281536 12.471 100 82 162 0 1352659328 167073392 18304400
08:48:47 858 8192000000 10289479360 10689691648 1177292112 12.565 100 98 194 0 1395814912 196389584 22133216
08:49:52 1143 8192000000 10314915040 10719338496 1173641888 12.531 100 87 171 0 1427707600 235016448 19049264
08:50:56 1155 8192000000 10325471952 10745184256 1179530416 12.586 100 83 165 1 1433245840 235101232 18614192
08:52:02 891 8192000000 10342437424 10741764096 1169358736 12.491 100 95 182 0 1449215504 259326544 20530224
08:53:06 702 8192000000 10348959328 10773004288 1171761792 12.513 100 96 186 0 1458154848 264234976 22158080
08:54:13 950 8192000000 10365030288 10782494720 1188183568 12.666 100 97 186 1 1472715888 264026816 20505504
08:55:16 887 8192000000 10401423808 10811629568 1191259328 12.695 100 97 187 2 1507920144 294781664 21879152
08:56:20 999 8192000000 10393774592 10847207424 1201220384 12.788 100 96 190 1 1502621840 278665264 22736192
08:57:23 795 8192000000 10448349248 10884337664 1203770240 12.811 100 94 185 2 1554352944 328339664 22243040
08:58:27 1424 8192000000 10431612368 10853998592 1188040656 12.665 100 79 158 2 1535677760 327813312 19823792
08:59:34 1618 8192000000 10433388320 10868928512 1196099824 12.740 100 81 160 0 1534064032 319563648 18400560
09:00:38 998 8192000000 10456575376 10879291392 1185943024 12.646 100 96 192 1 1559684672 351966416 21775232
09:01:41 2307 8192000000 10443388592 10922745856 1178088032 12.572 100 76 148 1 1548549856 351543248 18918576
09:02:46 4015 8192000000 10426442224 10870984704 1171087152 12.507 100 65 123 0 1547039488 360007536 15944800
09:04:07 1019 8192000000 10443705488 10889089024 1190715760 12.690 100 93 181 2 1547609184 334976144 21917280
09:05:11 10848 8192000000 10444614752 10873122816 1182273760 12.611 99 63 122 2 1540038016 342896064 14868192
09:07:05 12948 8192000000 10461692240 10949869568 1184008688 12.628 100 44 88 1 1578689744 383146176 11534880
09:08:30 7011 8192000000 10442188560 10921943040 1176690704 12.559 100 49 96 1 1553262400 364349168 12222528
09:09:39 26199 8192000000 10352664704 10853236736 1179878400 12.589 100 27 54 1 1460213600 273294560 7040640
09:11:09 39867 8192000000 10242008688 10703032320 1158735280 12.391 99 26 52 1 1347324752 182416688 6172784
09:13:20 84659 8192000000 10069368896 10514821120 1045773600 11.320 91 27 54 0 1106726416 55383568 5569248
09:15:54 34748 8192000000 10063053984 10518102016 1123774000 12.063 100 63 101 3 1164792800 31001728 10017072
09:17:37 3276 8192000000 10047859168 10465181696 1105044864 11.885 99 80 148 3 1145755968 25703184 15007920
09:18:42 54707 8192000000 10107474272 10550902784 1111417632 11.946 98 40 78 2 1173650256 53009520 9223104
09:21:07 56236 8192000000 10074112640 10541805568 1076529936 11.614 96 17 34 2 1149959456 70126672 3302848
09:23:12 53254 8192000000 10105443984 10546360320 1119277536 12.020 100 19 38 0 1210937952 87973088 3687328
09:25:48 44953 8192000000 10143475216 10655354880 1125199312 12.076 99 15 30 1 1272111376 143006128 3905936
09:27:51 64862 8192000000 10124262016 10576158720 1107794352 11.912 98 26 51 1 1202193984 88588800 5810832
09:29:57 107143 8192000000 10080979680 10533031936 1102414432 11.861 98 20 38 1 1174695904 67735280 4546192
09:32:47 18892 8192000000 10070713680 10504626176 1107266592 11.907 99 53 104 1 1168511008 50500880 10743536
09:34:08 71431 8192000000 10111430256 10552643584 1104863552 11.884 98 13 26 0 1175797712 67847760 3086400
09:36:20 62056 8192000000 10156863952 10598445056 1098978992 11.828 97 20 40 0 1219650560 116199664 4471904
09:38:56 49134 8192000000 10114969616 10576498688 1098374592 11.822 97 32 64 1 1184120064 79284064 6461408
09:40:53 66751 8192000000 10051072192 10481532928 1095388560 11.794 97 22 42 1 1146698096 46778256 4531280
09:43:02 124341 8192000000 10087479936 10542792704 1125640832 12.080 100 38 76 0 1204260864 70759328 7860704
09:46:23 33214 8192000000 10074293392 10521812992 1124169168 12.066 99 16 30 0 1168097376 39948416 3979792
09:47:57 58576 8192000000 10106655504 10579333120 1137017904 12.187 100 29 58 0 1231832144 88419456 6394784
09:50:01 70373 8192000000 10039694320 10525736960 1100420080 11.842 97 11 22 0 1162270288 59399376 2450832
09:52:53 97465 8192000000 10091929456 10567356416 1127032128 12.093 100 9 18 0 1175946800 46749712 2164960
09:55:38 47363 8192000000 10081156624 10560778240 1114319888 11.973 98 14 28 0 1179725568 62419648 2986032
09:57:25 37059 8192000000 10057466896 10484256768 1096641568 11.806 97 20 32 2 1127822144 27583840 3596736
09:59:03 61428 8192000000 10065748112 10536427520 1133915456 12.158 100 22 44 1 1204141280 65105488 5120336
10:01:06 28343 8192000000 10088603424 10518581248 1128618864 12.108 99 46 89 2 1184165872 46571920 8975088
10:02:35 4581 8192000000 10106132640 10538192896 1136831456 12.186 100 63 124 1 1214175104 64653248 12690400
10:03:42 31743 8192000000 10117942128 10556436480 1129098288 12.113 99 36 71 1 1203375696 67138960 7138448
10:05:26 19686 8192000000 10130242240 10564513792 1131013664 12.131 99 41 76 2 1210660864 71717904 7929296
10:06:48 71797 8192000000 10105756288 10575667200 1128075264 12.103 99 40 80 0 1224612848 88516896 8020688
10:09:02 63367 8192000000 10077727824 10545709056 1097354272 11.813 96 9 18 0 1152879840 53597936 1927632
10:11:05 58093 8192000000 10093449200 10564747264 1088571280 11.729 96 14 28 1 1162408048 71143248 2693520
10:13:08 60015 8192000000 10097835184 10540736512 1081560880 11.662 95 21 39 1 1139151872 53404560 4186432
10:15:08 51285 8192000000 10124888832 10574770176 1106120784 11.896 98 25 50 0 1193413504 81520944 5771776
10:17:00 67010 8192000000 10072344864 10526121984 1103965024 11.875 98 19 38 0 1175648016 67465328 4217664
10:19:12 81242 8192000000 10197596768 10653126656 1131283552 12.133 99 28 56 1 1235845728 99028080 5534096
10:21:34 56686 8192000000 10078324560 10507862016 1083685920 11.683 96 22 41 2 1122179568 34042848 4450800
10:23:33 121642 8192000000 10096045680 10576318464 1100394000 11.841 97 22 42 0 1153277184 49105424 3777760
10:26:42 98444 8192000000 10084283184 10546012160 1102114208 11.858 97 35 69 0 1186149776 76610832 7424736
10:29:24 51502 8192000000 10045650592 10511028224 1083222640 11.678 97 22 43 1 1136185136 48823712 4138784
10:31:16 65267 8192000000 10055610752 10502934528 1104429568 11.880 99 15 29 0 1148382112 40591728 3360816
10:33:26 45611 8192000000 10086218368 10526625792 1127031552 12.093 100 14 28 1 1188912144 58916768 2963824
10:35:12 71958 8192000000 10089005552 10565885952 1112585040 11.957 98 8 16 0 1172629920 58063024 1981856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-04-2022 10:38:52 to 13-04-2022 10:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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:02, from 13-04-2022 10:39:09 to 13-04-2022 10:39:11.

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          211541
MON$OLDEST_ACTIVE               216575
MON$OLDEST_SNAPSHOT             216575
MON$NEXT_TRANSACTION            216575
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                       2061408
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

13.04.22 10:39:12. 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-04-13 10:39:14 No severe PSQL-related problems occured
13.04.22 10:39:16. End of report.