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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15905644 kB
MemAvailable 31269212 kB
Buffers 0 kB
Cached 15735316 kB
SwapCached 1180 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.1
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-01-09 13:37:11
DTS_END                         2022-01-09 13:37:11.2520
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/42866
TRN_ID                          220062
ATT_ID                          659
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: 9204.43 24269 1104532
customer order: creation 173.93 467 20872
customer order: refuse 14.95 466 1794
order to supplier: creation 46.61 1469 5593
order to supplier: removal 3.49 194485 419
invoice (draft): creation 47.18 3325 5662
invoice (draft): removal 51.58 1181 6189
invoice accept: apply 42.44 27939 5093
invoice accept: cancel 46.68 1363 5601
customer reserve: creation 3895.97 268 467516
customer reserve: removal 4584.79 115 550175
realization accept: apply 82.32 300 9878
realization accept: cancel 83.10 190 9972
payment from customer: creation 59.91 185 7189
payment from customer: removal 5.60 145 672
payment to supplier: creation 49.33 219 5919
payment to supplier: removal 3.51 148 421
service: total inventory turnovers 3.97 4710 476
service: total monetary turnovers 8.02 405 962
service: aggregate perf. data 1.04 16633 125
service: refresh index statistics 0.03 231375 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 13:38:09 to 09-01-2022 13:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7037
TEST_TIME 2 5953
TEST_TIME 3 5773
TEST_TIME 4 6152
TEST_TIME 5 5991
TEST_TIME 6 6621
TEST_TIME 7 6632
TEST_TIME 8 6352
TEST_TIME 9 6491
TEST_TIME 10 6710
TEST_TIME 11 6814
TEST_TIME 12 6964
TEST_TIME 13 6911
TEST_TIME 14 7102
TEST_TIME 15 7042
TEST_TIME 16 7315
TEST_TIME 17 7296
TEST_TIME 18 7394
TEST_TIME 19 7684
TEST_TIME 20 7943
TEST_TIME 21 8019
TEST_TIME 22 7960
TEST_TIME 23 7895
TEST_TIME 24 7921
TEST_TIME 25 8037
TEST_TIME 26 8122
TEST_TIME 27 8179
TEST_TIME 28 8214
TEST_TIME 29 8141
TEST_TIME 30 8283
TEST_TIME 31 8262
TEST_TIME 32 8489
TEST_TIME 33 8725
TEST_TIME 34 9393
TEST_TIME 35 9690
TEST_TIME 36 9805
TEST_TIME 37 10241
TEST_TIME 38 10788
TEST_TIME 39 11096
TEST_TIME 40 10943
TEST_TIME 41 10757
TEST_TIME 42 10612
TEST_TIME 43 10463
TEST_TIME 44 10313
TEST_TIME 45 10287
TEST_TIME 46 10318
TEST_TIME 47 10300
TEST_TIME 48 10250
TEST_TIME 49 10210
TEST_TIME 50 10121
TEST_TIME 51 10075
TEST_TIME 52 10120
TEST_TIME 53 10061
TEST_TIME 54 10024
TEST_TIME 55 9945
TEST_TIME 56 9918
TEST_TIME 57 9915
TEST_TIME 58 9890
TEST_TIME 59 9800
TEST_TIME 60 9740
TEST_TIME 61 9649
TEST_TIME 62 9585
TEST_TIME 63 9550
TEST_TIME 64 9522
TEST_TIME 65 9512
TEST_TIME 66 9554
TEST_TIME 67 9574
TEST_TIME 68 9573
TEST_TIME 69 9568
TEST_TIME 70 9509
TEST_TIME 71 9530
TEST_TIME 72 9567
TEST_TIME 73 9651
TEST_TIME 74 9674
TEST_TIME 75 9703
TEST_TIME 76 9742
TEST_TIME 77 9635
TEST_TIME 78 9592
TEST_TIME 79 9532
TEST_TIME 80 9496
TEST_TIME 81 9463
TEST_TIME 82 9482
TEST_TIME 83 9435
TEST_TIME 84 9363
TEST_TIME 85 9299
TEST_TIME 86 9282
TEST_TIME 87 9235
TEST_TIME 88 9256
TEST_TIME 89 9288
TEST_TIME 90 9274
TEST_TIME 91 9244
TEST_TIME 92 9277
TEST_TIME 93 9235
TEST_TIME 94 9233
TEST_TIME 95 9315
TEST_TIME 96 9359
TEST_TIME 97 9394
TEST_TIME 98 9374
TEST_TIME 99 9391
TEST_TIME 100 9386
TEST_TIME 101 9335
TEST_TIME 102 9320
TEST_TIME 103 9266
TEST_TIME 104 9261
TEST_TIME 105 9239
TEST_TIME 106 9222
TEST_TIME 107 9210
TEST_TIME 108 9190
TEST_TIME 109 9152
TEST_TIME 110 9136
TEST_TIME 111 9176
TEST_TIME 112 9151
TEST_TIME 113 9117
TEST_TIME 114 9138
TEST_TIME 115 9109
TEST_TIME 116 9105
TEST_TIME 117 9121
TEST_TIME 118 9170
TEST_TIME 119 9198
TEST_TIME 120 9220

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-01-2022 13:38:13 to 09-01-2022 13:38:16.

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 1536268 1536268 0 0.00 0 18672 15 0 0
doc_list_biud 1536260 1536260 0 0.00 0 70905 47 0 0
sp_add_invoice_to_stock 5211 5093 118 2.26 57 192384 27939 0 118
sp_cancel_adding_invoice 5601 5601 0 0.00 7 86230 1363 0 0
sp_cancel_client_order 1794 1794 0 0.00 7 10467 466 0 0
sp_cancel_customer_reserve 550175 550175 0 0.00 1 70912 115 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 6524 146 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 4 8172 149 0 0
sp_cancel_supplier_invoice 6196 6189 7 0.11 2 19264 1182 0 7
sp_cancel_supplier_order 419 419 0 0.00 11 1560689 194486 0 0
sp_cancel_write_off 9972 9972 0 0.00 2 20515 190 0 0
sp_client_order 20872 20872 0 0.00 6 18633 468 0 0
sp_customer_reserve 467516 467516 0 0.00 1 52028 268 0 0
sp_fill_shopping_cart 32110 32110 0 0.00 1 22051 87 0 0
sp_fill_shopping_cart_clo_res 467495 467495 0 0.00 0 16512 20 0 0
sp_get_clo_for_invoice 5061 5061 0 0.00 0 27804 259 0 0
sp_kill_cost_storno 16666 16666 0 0.00 0 9639 84 0 0
sp_kill_qstorno_ret_qs2qd 1116930 1116930 0 0.00 0 70892 49 0 0
sp_kill_qty_storno 589089 589089 0 0.00 0 70905 114 0 0
sp_lock_selected_doc 20805 20805 0 0.00 0 9206 8 0 0
sp_make_cost_storno 28047 28047 0 0.00 0 14902 93 0 0
sp_multiply_rows_for_pdistr 28046 28046 0 0.00 0 7819 40 0 0
sp_multiply_rows_for_qdistr 20871 20871 0 0.00 0 15082 175 0 0
sp_pay_from_customer 7189 7189 0 0.00 6 10584 185 0 0
sp_pay_to_supplier 5919 5919 0 0.00 6 15005 219 0 0
sp_payment_common 13107 13107 0 0.00 2 10482 50 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 10457 381 0 0
sp_qd_handle_on_invoice_upd_sts 10662 10662 0 0.00 0 19056 585 0 0
sp_qd_handle_on_reserve_upd_sts 9878 9878 0 0.00 0 19937 66 0 0
sp_reserve_write_off 9878 9878 0 0.00 9 19951 300 0 0
sp_supplier_invoice 5662 5662 0 0.00 7 102069 3325 0 0
sp_supplier_order 5593 5593 0 0.00 8 22131 1469 0 0
srv_aggregate_perf_data 125 125 0 0.00 2135 298859 16633 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 741 175959 30520 0 0
srv_find_qd_qs_mism 1055589 1055589 0 0.00 0 18670 12 0 0
srv_make_invnt_saldo 476 476 0 0.00 61 61491 4710 0 0
srv_make_money_saldo 962 962 0 0.00 1 11545 406 0 0
srv_recalc_idx_stat 4 4 0 0.00 89090 412474 231375 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 0 13385 1071 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 13932 493 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 12549 44 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 2087 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1432 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 388 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1077 41 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 103842 8291 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 6722 26 0 0
t$perf-norm:sp_client_order 20873 20873 0 0.00 0 18503 44 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 5069 23 0 0
t$perf-norm:sp_pay_from_customer 7189 7189 0 0.00 0 4798 22 0 0
t$perf-norm:sp_pay_to_supplier 5920 5920 0 0.00 0 9848 22 0 0
t$perf-norm:sp_reserve_write_off 9878 9878 0 0.00 0 9059 25 0 0
t$perf-norm:sp_supplier_invoice 5662 5662 0 0.00 0 7051 37 0 0
t$perf-norm:sp_supplier_order 5593 5593 0 0.00 0 2111 35 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 461 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 516 14 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1544 16 0 0
t$perf-norm:srv_make_money_saldo 962 962 0 0.00 0 2115 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 145 43 0 0
v_add_invoice_to_stock 10342 10106 236 2.28 0 5617 28 0 236
v_all_customers 38520 38520 0 0.00 0 86 4 0 0
v_all_suppliers 31478 31478 0 0.00 0 159 5 0 0
v_all_wares 41738 41738 0 0.00 0 158 4 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1412 15 0 0
v_cancel_client_order 3588 3588 0 0.00 0 2863 11 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3433 13 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 2285 15 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 494 13 0 0
v_cancel_supplier_invoice 856 842 14 1.63 0 1258 28 0 14
v_cancel_supplier_order 838 838 0 0.00 0 2933 14 0 0
v_cancel_write_off 1974 1974 0 0.00 0 4332 27 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 4738 7 0 0
v_max_id_clo_res 995 995 0 0.00 0 1181 15 0 0
v_max_id_ord_sup 5652 5652 0 0.00 0 3073 8 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 1043 10 0 0
v_max_non_paid_realizn 3582 3582 0 0.00 0 2734 9 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 5407 10 0 0
v_min_id_clo_res 995 995 0 0.00 0 8150 20 0 0
v_min_id_ord_sup 5652 5652 0 0.00 0 2079 7 0 0
v_min_non_paid_invoice 5918 5918 0 0.00 0 7801 19 0 0
v_min_non_paid_realizn 7188 7188 0 0.00 0 7237 16 0 0
v_our_firm 10430 10430 0 0.00 0 75 5 0 0
v_reserve_write_off 19756 19756 0 0.00 0 4406 11 0 0
x_lock_depdocs_on_canc_invoice 11369 11369 0 0.00 0 23661 135 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 8914 391 0 0
x_make_qstorno_1000_1200 5593 5593 0 0.00 3 18855 1272 0 0
x_make_qstorno_1200_2000 5662 5662 0 0.00 3 102044 3107 0 0
x_make_qstorno_2100_3300 467516 467516 0 0.00 0 52023 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 13:38:17 to 09-01-2022 13:38:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 23750 8192000000 9715770752 10012250112 795495536 8.851 100 87 174 0 823645664 7532720 20617408
11:09:42 22018 8192000000 9761564592 10069020672 839880656 9.299 100 90 178 1 874601392 13528400 21192336
11:11:26 15673 8192000000 9806466400 10124619776 869854912 9.599 100 84 168 0 914526768 25642064 19029792
11:12:48 8779 8192000000 9860544848 10195763200 916955328 10.066 100 78 156 1 979262992 45926416 16381248
11:14:00 2929 8192000000 9919637920 10262396928 953636384 10.427 100 88 170 2 1032490080 60500688 18353008
11:15:07 4109 8192000000 9975212304 10326495232 993876048 10.819 100 87 172 0 1084554512 73106016 17572448
11:16:13 1517 8192000000 9995776256 10357436416 1018584992 11.058 100 93 178 1 1116685248 80384192 17716064
11:17:16 808 8192000000 10014399792 10380709888 1046690800 11.329 100 99 192 3 1133321648 67741360 18889488
11:18:19 1112 8192000000 10037859088 10412085248 1065136016 11.506 100 92 183 0 1160239200 76370640 18732544
11:19:21 1024 8192000000 10043504432 10422280192 1074429200 11.594 100 93 178 0 1163605760 71138320 18038240
11:20:24 15258 8192000000 10026438896 10408914944 1081837136 11.665 100 76 145 0 1155250864 59731840 13681888
11:21:41 906 8192000000 10072864592 10454290432 1090703568 11.749 100 97 191 1 1186659664 78010016 17946080
11:22:43 6536 8192000000 10045923776 10429976576 1093159328 11.773 100 78 147 0 1170125936 63359296 13607312
11:23:53 876 8192000000 10054173840 10442948608 1097902992 11.818 100 95 185 2 1181029296 64082640 19043664
11:24:55 1288 8192000000 10082049984 10465456128 1101359456 11.851 100 97 192 1 1201795088 81348816 19086816
11:25:58 1284 8192000000 10085386544 10475450368 1099286640 11.831 100 97 190 1 1205420976 86389568 19744768
11:27:00 4036 8192000000 10045934192 10442694656 1096746048 11.807 100 68 134 2 1165800624 55675776 13378800
11:28:05 939 8192000000 10038793696 10422419456 1090232992 11.745 99 93 180 0 1163555856 53485184 19837680
11:29:07 1117 8192000000 10045411376 10437201920 1096220288 11.802 100 84 163 4 1168998816 57658544 15119984
11:30:09 783 8192000000 10046570272 10433884160 1087102224 11.715 100 95 180 0 1167415024 61713344 18599456
11:31:12 6670 8192000000 10022088528 10407100416 1064136304 11.496 99 59 113 2 1142168832 66754544 11277984
11:32:19 1325 8192000000 10020077264 10400149504 1063446576 11.489 100 74 140 1 1139218672 61837056 13935040
11:33:21 1458 8192000000 9990828256 10367737856 1038248256 11.248 100 74 140 2 1114180080 62089680 13842144
11:34:24 2739 8192000000 9990481744 10365460480 1032167760 11.189 100 76 139 1 1112261488 66550288 13543440
11:35:27 3366 8192000000 9981316544 10355249152 1040126912 11.266 100 71 128 2 1097902352 44899040 12876400
11:36:32 1724 8192000000 9968551568 10338758656 1027256960 11.142 100 93 169 1 1089997424 46071856 16668608
11:37:37 16632 8192000000 9969906144 10339201024 1046765824 11.330 100 66 123 1 1088808560 30917328 11125408
11:39:04 22297 8192000000 10039995152 10429227008 1069913008 11.551 100 68 134 2 1167708384 83724864 14070512
11:40:30 1975 8192000000 10066356032 10446258176 1084751984 11.693 100 91 170 2 1189135264 86531968 17851312
11:41:40 1004 8192000000 10080548272 10464657408 1093618848 11.777 100 86 164 0 1199435104 88121696 17694560
11:42:44 1985 8192000000 10127979520 10509996032 1107600864 11.910 100 92 176 0 1241878784 117179920 17098000
11:43:49 1230 8192000000 10149086704 10538446848 1119000064 12.018 100 93 180 0 1272370096 133770304 19599728
11:44:53 1385 8192000000 10168962528 10559819776 1129003856 12.112 100 92 166 2 1284926000 139103904 16818240
11:45:58 4730 8192000000 10200304720 10598072320 1131327248 12.134 100 88 176 0 1319374928 168118320 19929360
11:47:09 1093 8192000000 10212341808 10601332736 1142195168 12.236 100 92 170 2 1324653232 163531856 18926208
11:48:12 1688 8192000000 10222060592 10623873024 1157296416 12.378 100 86 159 0 1334825344 159152688 18376240
11:49:17 1112 8192000000 10265290880 10674171904 1160572624 12.409 100 90 168 0 1378881136 198709760 19598752
11:50:21 1359 8192000000 10299999072 10712133632 1173662992 12.531 100 99 193 1 1411395232 216123136 21609104
11:51:29 1743 8192000000 10304449248 10704732160 1176628672 12.559 100 85 165 1 1426788960 232318816 17841472
11:52:35 1714 8192000000 10362459952 10764886016 1183890368 12.626 100 88 171 1 1474589584 271146096 19553120
11:53:42 1790 8192000000 10373909216 10805260288 1190923376 12.692 100 94 182 2 1478616848 266252016 21441456
11:54:51 952 8192000000 10404230848 10828832768 1201019616 12.786 100 97 190 2 1513124544 289729984 22374944
11:56:02 879 8192000000 10396910768 10841772032 1201323200 12.789 100 97 189 0 1514212912 291168048 21721664
11:57:06 1345 8192000000 10425395344 10860089344 1208360176 12.854 100 96 185 1 1534109296 303675504 22073616
11:58:10 3579 8192000000 10388257200 10817880064 1195372256 12.733 100 63 120 0 1499425440 289478768 14574416
11:59:21 1400 8192000000 10449328720 10864058368 1197135488 12.750 100 92 176 0 1559804304 342350800 20318016
12:00:27 867 8192000000 10502488352 10924240896 1207314464 12.844 100 98 193 0 1607190704 376966096 22910144
12:01:31 1117 8192000000 10528090080 10948759552 1209135136 12.861 100 92 183 0 1634655888 403826320 21694432
12:02:37 729 8192000000 10549757648 10976444416 1204307184 12.816 100 98 191 3 1652860896 425484976 23068736
12:03:39 933 8192000000 10571595184 11026378752 1211306848 12.881 100 97 184 0 1672474608 438888368 22279392
12:04:43 1288 8192000000 10589894160 11014086656 1201526880 12.791 99 94 186 1 1691051600 466927840 22596880
12:05:48 3196 8192000000 10637863952 11056607232 1198060144 12.758 100 73 144 0 1741907248 525485600 18361504
12:06:59 3280 8192000000 10672622224 11120578560 1190398224 12.687 99 86 166 1 1768678720 557267264 21013232
12:08:10 1966 8192000000 10662215664 11078873088 1186002208 12.646 100 78 154 1 1764818096 559764736 19051152
12:09:15 1181 8192000000 10692417232 11158908928 1192974592 12.711 100 69 133 1 1794544512 584246944 17322976
12:10:28 10633 8192000000 10593560704 11081195520 1174955824 12.543 100 51 100 0 1690973984 502354704 13663456
12:11:49 8500 8192000000 10508883888 10977386496 1173517216 12.530 100 37 72 0 1622309872 439580528 9212128
12:12:58 30779 8192000000 10412464944 10897776640 1159338336 12.397 99 32 64 3 1541196848 373800368 8058144
12:15:01 48116 8192000000 10165615872 10684329984 1119725088 12.024 97 21 42 2 1337479296 212054304 5699904
12:16:51 58490 8192000000 10068477552 10493591552 1117970128 12.008 98 32 56 0 1145056816 20974512 6112176
12:18:55 175958 8192000000 10174731712 10612797440 1117687488 12.005 98 20 40 0 1230182128 107972688 4521952
12:22:55 34242 8192000000 10174656288 10636632064 1141113616 12.226 100 32 64 0 1274138800 125923184 7102000
12:24:34 48858 8192000000 10090646464 10530394112 1143882944 12.252 100 23 44 2 1187099296 38053408 5162944
12:26:25 19536 8192000000 10102403680 10557038592 1144327952 12.256 100 34 66 2 1204802432 53611056 6863424
12:27:48 13512 8192000000 10164602128 10588082176 1150536432 12.315 99 68 123 2 1248884528 85372016 12976080
12:29:05 32757 8192000000 10160824496 10611372032 1147148128 12.283 99 22 44 1 1243589792 91490608 4951056
12:30:38 84533 8192000000 10092068768 10562039808 1124404368 12.069 98 18 35 0 1205601552 77370176 3827008
12:33:04 49954 8192000000 10119251072 10576097280 1149647920 12.306 100 12 23 2 1231147248 78838416 2660912
12:35:02 57320 8192000000 10047908032 10556645376 1076373040 11.613 95 10 20 0 1138292928 59701968 2217920
12:37:00 57105 8192000000 10057034976 10511695872 1134080608 12.160 100 31 62 0 1183201776 43176000 5945168
12:39:13 80535 8192000000 10051607280 10519142400 1101255472 11.850 97 8 17 0 1141977376 39252320 1469584
12:41:35 29726 8192000000 10078754464 10534793216 1089110832 11.734 96 25 50 1 1151252800 56787872 5354096
12:43:14 24122 8192000000 10045930032 10506338304 1116233904 11.991 100 26 51 2 1159279104 37062992 5982208
12:44:40 23018 8192000000 10102876464 10551914496 1120547632 12.032 99 30 60 0 1182535600 56037776 5950192
12:46:08 53237 8192000000 10128168912 10588594176 1130858768 12.129 99 29 58 1 1222671808 85836272 5976768
12:48:02 76220 8192000000 10141810608 10619068416 1086960736 11.714 95 15 30 1 1241516960 150874528 3681696
12:50:20 3960 8192000000 10064504336 10487930880 1124497216 12.069 100 63 107 1 1160529360 23913984 12118160
12:51:24 25754 8192000000 10165464736 10603851776 1127904384 12.102 100 31 62 1 1228991776 94358704 6728688
12:52:51 42493 8192000000 10099838528 10553036800 1120296576 12.030 99 30 61 1 1215280464 88462944 6520944
12:54:38 132802 8192000000 10065102304 10518290432 1085680448 11.702 96 27 54 0 1130824800 39738400 5405952
12:57:51 62198 8192000000 10091324368 10555273216 1123015584 12.055 99 18 36 0 1186332352 59275296 4041472
13:00:00 36832 8192000000 10069771136 10510204928 1123516112 12.060 99 27 55 0 1152432624 23996512 4920000
13:01:44 67807 8192000000 10095875424 10576773120 1133968992 12.159 100 10 20 1 1208334048 71935280 2429776
13:03:52 46783 8192000000 10092631376 10540081152 1121069728 12.037 99 22 44 1 1194064976 68020480 4974768
13:05:47 43140 8192000000 10064283120 10510979072 1120383920 12.031 99 19 38 1 1165690464 41069408 4237136
13:07:32 69927 8192000000 10101842000 10537693184 1109547968 11.928 97 36 72 1 1167432336 50115728 7768640
13:09:42 27270 8192000000 10099393008 10526642176 1113570208 11.966 98 31 60 1 1184480816 64758752 6151856
13:11:12 22375 8192000000 10220455456 10665021440 1159393312 12.398 100 49 98 1 1298266160 128397920 10474928
13:12:55 28953 8192000000 10119629616 10566348800 1124378416 12.068 98 26 50 0 1204667744 73958640 6330688
13:14:32 37411 8192000000 10067169584 10496872448 1109916560 11.932 98 37 74 1 1154461712 36882656 7662496
13:16:13 34447 8192000000 10134217664 10573950976 1126633152 12.090 99 48 95 0 1199311648 62898464 9780032
13:17:48 82661 8192000000 10059258672 10523869184 1089054272 11.734 97 15 30 0 1137294032 44740128 3499632
13:20:19 37721 8192000000 10087296016 10548445184 1122488624 12.050 100 21 42 1 1196193552 69564304 4140624
13:21:58 41708 8192000000 10061673664 10535178240 1126433248 12.088 99 20 40 1 1172645952 42015120 4197584
13:23:40 65569 8192000000 10055423712 10531586048 1106480256 11.899 98 13 25 2 1163244416 54033440 2730720
13:26:01 83051 8192000000 10096797408 10565365760 1116910464 11.998 98 16 32 0 1164224688 43387104 3927120
13:28:24 22044 8192000000 10093112624 10539290624 1122298672 12.049 99 30 59 0 1188246512 60004496 5943344
13:29:47 22564 8192000000 10144174656 10588368896 1149944288 12.309 100 29 57 1 1239273264 82576336 6752640
13:31:12 31448 8192000000 10170158144 10618597376 1152419280 12.332 100 32 64 0 1270663120 110846608 7397232
13:32:43 52345 8192000000 10204542112 10662674432 1140007056 12.216 99 35 70 2 1292940224 144835600 8097568
13:34:39 63998 8192000000 10147767360 10625843200 1093405440 11.775 96 26 52 0 1259948416 160389920 6153056
13:37:01 32445 8192000000 9122683280 9454391296 251337456 2.976 23 1 2 0 279161584 27788720 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 09-01-2022 13:38:23 to 09-01-2022 13:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-01-2022 13:38:41 to 09-01-2022 13:38:43.

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          220378
MON$OLDEST_ACTIVE               220379
MON$OLDEST_SNAPSHOT             220379
MON$NEXT_TRANSACTION            220379
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                       2096896
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:232: 
:233: mscs-test-161.budzdorov.ru    Sun Jan  9 13:37:39 2022
:234:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:235: 
--- end of diff output ---

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

09.01.22 13:38:44. 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-01-09 13:38:46 No severe PSQL-related problems occured
09.01.22 13:38:47. End of report.