Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 13: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 16186248 kB
MemAvailable 31570016 kB
Buffers 0 kB
Cached 15546464 kB
SwapCached 2540 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-23 13:37:15
DTS_END                         2022-04-23 13:37:15.0220
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/33454
TRN_ID                          224396
ATT_ID                          617
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: 9322.88 29557 1118746
customer order: creation 167.49 488 20099
customer order: refuse 14.82 419 1778
order to supplier: creation 46.37 1403 5564
order to supplier: removal 3.58 172249 430
invoice (draft): creation 44.87 3215 5384
invoice (draft): removal 53.62 986 6434
invoice accept: apply 41.48 25825 4977
invoice accept: cancel 48.39 1326 5807
customer reserve: creation 3853.43 244 462411
customer reserve: removal 4745.68 102 569481
realization accept: apply 83.23 299 9987
realization accept: cancel 90.71 172 10885
payment from customer: creation 58.86 214 7063
payment from customer: removal 5.43 135 651
payment to supplier: creation 48.63 208 5836
payment to supplier: removal 3.60 153 432
service: total inventory turnovers 3.93 4327 471
service: total monetary turnovers 7.75 391 930
service: aggregate perf. data 1.02 14260 122
service: refresh index statistics 0.03 364719 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 13:39:00 to 23-04-2022 13:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12022
TEST_TIME 2 9761
TEST_TIME 3 8206
TEST_TIME 4 8169
TEST_TIME 5 8488
TEST_TIME 6 8203
TEST_TIME 7 7844
TEST_TIME 8 8037
TEST_TIME 9 7705
TEST_TIME 10 7562
TEST_TIME 11 7531
TEST_TIME 12 7398
TEST_TIME 13 7551
TEST_TIME 14 7537
TEST_TIME 15 7411
TEST_TIME 16 7666
TEST_TIME 17 7586
TEST_TIME 18 7690
TEST_TIME 19 7549
TEST_TIME 20 7631
TEST_TIME 21 7749
TEST_TIME 22 7658
TEST_TIME 23 7688
TEST_TIME 24 7846
TEST_TIME 25 7854
TEST_TIME 26 7889
TEST_TIME 27 7965
TEST_TIME 28 8284
TEST_TIME 29 8540
TEST_TIME 30 9271
TEST_TIME 31 9659
TEST_TIME 32 10281
TEST_TIME 33 10607
TEST_TIME 34 11046
TEST_TIME 35 10914
TEST_TIME 36 10951
TEST_TIME 37 11048
TEST_TIME 38 11048
TEST_TIME 39 10896
TEST_TIME 40 10824
TEST_TIME 41 10676
TEST_TIME 42 10579
TEST_TIME 43 10553
TEST_TIME 44 10693
TEST_TIME 45 10555
TEST_TIME 46 10471
TEST_TIME 47 10690
TEST_TIME 48 10686
TEST_TIME 49 10491
TEST_TIME 50 10317
TEST_TIME 51 10202
TEST_TIME 52 10034
TEST_TIME 53 10024
TEST_TIME 54 9996
TEST_TIME 55 9912
TEST_TIME 56 9848
TEST_TIME 57 9830
TEST_TIME 58 9816
TEST_TIME 59 9794
TEST_TIME 60 9747
TEST_TIME 61 9704
TEST_TIME 62 9656
TEST_TIME 63 9739
TEST_TIME 64 9885
TEST_TIME 65 9821
TEST_TIME 66 9791
TEST_TIME 67 9770
TEST_TIME 68 9738
TEST_TIME 69 9736
TEST_TIME 70 9883
TEST_TIME 71 9896
TEST_TIME 72 9935
TEST_TIME 73 9956
TEST_TIME 74 9965
TEST_TIME 75 10007
TEST_TIME 76 9983
TEST_TIME 77 9928
TEST_TIME 78 9818
TEST_TIME 79 9781
TEST_TIME 80 9703
TEST_TIME 81 9701
TEST_TIME 82 9655
TEST_TIME 83 9661
TEST_TIME 84 9613
TEST_TIME 85 9591
TEST_TIME 86 9520
TEST_TIME 87 9443
TEST_TIME 88 9423
TEST_TIME 89 9396
TEST_TIME 90 9430
TEST_TIME 91 9400
TEST_TIME 92 9377
TEST_TIME 93 9388
TEST_TIME 94 9377
TEST_TIME 95 9420
TEST_TIME 96 9403
TEST_TIME 97 9418
TEST_TIME 98 9480
TEST_TIME 99 9516
TEST_TIME 100 9584
TEST_TIME 101 9570
TEST_TIME 102 9489
TEST_TIME 103 9545
TEST_TIME 104 9514
TEST_TIME 105 9493
TEST_TIME 106 9445
TEST_TIME 107 9454
TEST_TIME 108 9465
TEST_TIME 109 9453
TEST_TIME 110 9424
TEST_TIME 111 9431
TEST_TIME 112 9421
TEST_TIME 113 9436
TEST_TIME 114 9422
TEST_TIME 115 9455
TEST_TIME 116 9467
TEST_TIME 117 9527
TEST_TIME 118 9461
TEST_TIME 119 9409
TEST_TIME 120 9347

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 13:39:04 to 23-04-2022 13:39:07.

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 1546621 1546621 0 0.00 0 34010 13 0 0
doc_list_biud 1546607 1546607 0 0.00 0 30342 43 0 0
sp_add_invoice_to_stock 5135 4977 158 3.07 82 246971 25825 0 158
sp_cancel_adding_invoice 5807 5807 0 0.00 6 97058 1327 0 0
sp_cancel_client_order 1778 1778 0 0.00 6 16703 419 0 0
sp_cancel_customer_reserve 569481 569481 0 0.00 1 30385 102 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 5424 136 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 15669 153 0 0
sp_cancel_supplier_invoice 6443 6434 9 0.13 3 30006 987 0 9
sp_cancel_supplier_order 430 430 0 0.00 11 1372038 172250 0 0
sp_cancel_write_off 10885 10885 0 0.00 2 14137 172 0 0
sp_client_order 20099 20099 0 0.00 6 30022 488 0 0
sp_customer_reserve 462411 462411 0 0.00 1 112008 245 0 0
sp_fill_shopping_cart 31031 31031 0 0.00 1 28021 83 0 0
sp_fill_shopping_cart_clo_res 462379 462379 0 0.00 0 16027 18 0 0
sp_get_clo_for_invoice 4932 4932 0 0.00 0 18749 264 0 0
sp_kill_cost_storno 17775 17775 0 0.00 0 16188 75 0 0
sp_kill_qstorno_ret_qs2qd 1156711 1156711 0 0.00 0 30230 44 0 0
sp_kill_qty_storno 609731 609731 0 0.00 0 30339 101 0 0
sp_lock_selected_doc 20766 20766 0 0.00 0 7292 8 0 0
sp_make_cost_storno 27817 27817 0 0.00 0 14796 88 0 0
sp_multiply_rows_for_pdistr 27816 27816 0 0.00 0 8160 44 0 0
sp_multiply_rows_for_qdistr 20097 20097 0 0.00 0 15578 176 0 0
sp_pay_from_customer 7063 7063 0 0.00 6 16526 215 0 0
sp_pay_to_supplier 5836 5836 0 0.00 6 15642 208 0 0
sp_payment_common 12899 12899 0 0.00 2 8474 56 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 16696 343 0 0
sp_qd_handle_on_invoice_upd_sts 10737 10737 0 0.00 0 20136 517 0 0
sp_qd_handle_on_reserve_upd_sts 9986 9986 0 0.00 0 14107 65 0 0
sp_reserve_write_off 9987 9987 0 0.00 8 17657 299 0 0
sp_supplier_invoice 5384 5384 0 0.00 8 84513 3215 0 0
sp_supplier_order 5564 5564 0 0.00 9 51159 1404 0 0
srv_aggregate_perf_data 122 122 0 0.00 2600 212448 14260 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 768 141195 32834 0 0
srv_find_qd_qs_mism 1071196 1071196 0 0.00 0 34006 10 0 0
srv_make_invnt_saldo 471 471 0 0.00 70 98399 4328 0 0
srv_make_money_saldo 930 930 0 0.00 1 13582 391 0 0
srv_recalc_idx_stat 4 4 0 0.00 292497 465742 364719 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 1 9526 953 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 2 7298 505 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 3272 32 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 948 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 1776 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 762 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1987 44 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 103697 6795 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 1441 20 0 0
t$perf-norm:sp_client_order 20102 20102 0 0.00 0 19067 39 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 624 14 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 5354 19 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 3167 20 0 0
t$perf-norm:sp_reserve_write_off 9987 9987 0 0.00 0 4097 22 0 0
t$perf-norm:sp_supplier_invoice 5385 5385 0 0.00 0 4317 32 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 2773 31 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 897 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 137 6 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 544 17 0 0
t$perf-norm:srv_make_money_saldo 930 930 0 0.00 0 310 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 293 82 0 0
v_add_invoice_to_stock 10172 9856 316 3.10 0 15952 32 0 316
v_all_customers 37206 37206 0 0.00 0 105 4 0 0
v_all_suppliers 30788 30788 0 0.00 0 106 4 0 0
v_all_wares 40184 40184 0 0.00 0 161 4 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 2905 18 0 0
v_cancel_client_order 3556 3556 0 0.00 0 4753 14 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 1724 12 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1323 12 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 15563 32 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 1954 27 0 18
v_cancel_supplier_order 860 860 0 0.00 0 1537 12 0 0
v_cancel_write_off 2110 2110 0 0.00 0 5471 31 0 0
v_max_id_clo_ord 5558 5558 0 0.00 0 304 5 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 809 13 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 6213 11 0 0
v_max_non_paid_invoice 1376 1376 0 0.00 0 911 10 0 0
v_max_non_paid_realizn 3480 3480 0 0.00 0 3972 11 0 0
v_min_id_clo_ord 5558 5558 0 0.00 0 2626 9 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 290 7 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 8921 11 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 8448 22 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 7162 29 0 0
v_our_firm 10144 10144 0 0.00 0 97 5 0 0
v_reserve_write_off 19972 19972 0 0.00 0 5184 12 0 0
x_lock_depdocs_on_canc_invoice 11854 11854 0 0.00 0 27666 133 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 5300 354 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 3 51148 1230 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 3 84479 2994 0 0
x_make_qstorno_2100_3300 462411 462411 0 0.00 0 112004 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 13:39:08 to 23-04-2022 13:39:12.

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:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 32613 8192000000 9734599264 10037989376 812989552 9.028 100 88 174 2 841918192 8017504 20911136
11:10:02 18412 8192000000 9772384464 10083508224 847601312 9.376 100 91 182 1 882675008 13653344 21420352
11:11:26 13744 8192000000 9813245440 10137440256 884397728 9.743 100 86 172 0 923167824 18937328 19832768
11:12:46 5575 8192000000 9890559200 10227073024 935069728 10.245 100 88 175 0 1000922464 46787072 19065664
11:13:53 3156 8192000000 9945573248 10291638272 978191376 10.667 100 94 182 2 1064104848 66875632 19037840
11:14:59 1954 8192000000 9991664496 10352173056 1005987456 10.937 100 94 181 0 1121366128 97354656 18024016
11:16:03 778 8192000000 10008450192 10375426048 1023964800 11.110 100 98 182 1 1127790944 85990816 17835328
11:17:06 1052 8192000000 10029378592 10403880960 1046832512 11.330 100 95 187 1 1148843424 83768736 18242176
11:18:08 1001 8192000000 10051168720 10429288448 1062798176 11.483 100 93 179 3 1169705888 88632800 18274912
11:19:10 8711 8192000000 10033461120 10409066496 1068815376 11.541 100 84 159 1 1149191824 65585872 14790576
11:20:20 997 8192000000 10048909856 10429509632 1078255648 11.631 100 95 179 0 1169434064 72279984 18898432
11:21:21 4168 8192000000 10028729664 10415529984 1079776496 11.645 100 54 97 2 1140668736 50907024 9985216
11:22:27 913 8192000000 10035670288 10425090048 1086303424 11.707 100 86 170 4 1160872544 55485632 19083488
11:23:29 1143 8192000000 10057190208 10446041088 1089483168 11.738 100 80 152 2 1174401056 69633600 15284288
11:24:31 1212 8192000000 10047749232 10441916416 1095213296 11.792 100 76 146 0 1168564832 58859552 14491984
11:25:34 1140 8192000000 10089377600 10484715520 1099413424 11.832 100 96 186 0 1211517360 92834624 19269312
11:26:36 2262 8192000000 10065076112 10458505216 1098402880 11.822 100 78 147 0 1180234352 67055552 14775920
11:27:39 1119 8192000000 10046160368 10434101248 1083019840 11.676 100 94 176 1 1164199328 62387248 18792240
11:28:41 1293 8192000000 10080205904 10467659776 1094321776 11.784 100 97 187 2 1193670880 79516864 19832240
11:29:43 1195 8192000000 10028985824 10405703680 1057716608 11.435 99 96 175 1 1146353776 70255472 18381696
11:30:46 890 8192000000 10066746864 10453962752 1079545264 11.643 100 95 186 0 1189780896 89781120 20454512
11:31:47 1241 8192000000 10041870512 10426023936 1076361152 11.613 100 94 179 0 1163845856 68974192 18510512
11:32:50 1296 8192000000 9997874384 10368331776 1035370896 11.220 100 96 191 1 1117889840 63597264 18921680
11:33:52 956 8192000000 9973764112 10347069440 1033909456 11.206 99 89 165 4 1097482016 45532000 18040560
11:34:53 2421 8192000000 9997746992 10370322432 1048185008 11.343 100 70 139 0 1113690496 49676112 15829376
11:35:56 1128 8192000000 10006501552 10380697600 1044004352 11.303 100 93 174 1 1123181008 61055168 18121488
11:36:58 4165 8192000000 10011768368 10391150592 1049997808 11.361 100 76 143 0 1136077232 69309760 16769664
11:38:10 817 8192000000 9983216096 10355077120 1054875008 11.407 100 91 173 2 1098339504 29160736 14303760
11:39:17 1492 8192000000 10042425472 10427674624 1078804704 11.636 100 92 174 2 1160522592 63636800 18081088
11:40:41 12547 8192000000 10022249456 10415767552 1082498560 11.671 100 56 112 0 1142901280 49188896 11213824
11:41:57 1045 8192000000 10096491040 10480963584 1103833856 11.874 100 95 184 1 1218110528 94268528 20008144
11:43:01 1288 8192000000 10104941712 10492493824 1113041344 11.961 100 95 179 0 1223157264 90538016 19577904
11:44:05 1017 8192000000 10143248784 10535882752 1122120800 12.047 100 97 191 2 1262656736 120556800 19979136
11:45:08 1273 8192000000 10149439408 10549415936 1129072240 12.113 100 84 160 0 1267362880 120599056 17691584
11:46:13 1291 8192000000 10163538256 10558746624 1143782912 12.251 100 84 157 2 1279089200 118308816 16997472
11:47:17 1750 8192000000 10209060064 10602831872 1157711024 12.382 100 92 169 1 1324197984 148560976 17925984
11:48:22 884 8192000000 10239345712 10637934592 1168516496 12.483 100 93 175 5 1349150960 161668112 18966352
11:49:25 1980 8192000000 10272581584 10665902080 1160057680 12.404 99 90 173 1 1380572224 202545504 17969040
11:50:31 2346 8192000000 10320526288 10720890880 1178300304 12.574 100 94 185 3 1423754592 225048368 20405920
11:51:37 2078 8192000000 10310664336 10713251840 1174968944 12.543 99 92 177 1 1424121648 228663776 20488928
11:52:42 913 8192000000 10343920368 10770194432 1186092816 12.647 100 97 184 0 1450206272 243903120 20210336
11:53:45 1326 8192000000 10367054192 10765209600 1202789360 12.802 100 94 186 1 1474752560 250909952 21053248
11:54:53 1933 8192000000 10410112112 10831065088 1212282288 12.890 100 94 182 1 1514008512 280707520 21018704
11:56:00 966 8192000000 10460990528 10870251520 1215023680 12.916 100 97 188 2 1569553376 330556352 23973344
11:57:03 2883 8192000000 10485310848 10908676096 1198133984 12.759 100 90 168 1 1594230336 376357872 19738480
11:58:10 1831 8192000000 10517523472 10948329472 1202379904 12.798 99 91 180 0 1621359040 396070576 22908560
11:59:15 1174 8192000000 10590981600 10997190656 1212770704 12.895 100 97 189 1 1696645552 459556352 24318496
12:00:18 2876 8192000000 10620224336 11052765184 1218766352 12.950 100 85 165 2 1726018928 486296624 20955952
12:01:23 1087 8192000000 10687800624 11107786752 1229224400 13.047 100 96 192 1 1789554592 536658336 23671856
12:02:27 756 8192000000 10691560336 11118911488 1211038160 12.879 100 83 166 1 1794919328 563497344 20383824
12:03:31 1682 8192000000 10688448992 11109539840 1188082720 12.666 100 99 196 1 1785794352 573218032 24493600
12:04:37 6060 8192000000 10723984496 11164811264 1198635904 12.764 100 73 142 0 1829853568 612489152 18728512
12:05:44 4991 8192000000 10671277792 11168833536 1188988960 12.674 100 52 104 0 1774124912 570641696 14494256
12:07:28 11118 8192000000 10525898736 11064889344 1175450240 12.548 100 39 78 0 1646880256 460530256 10899760
12:08:48 15732 8192000000 10361379904 10917335040 1164277280 12.443 100 37 74 0 1532632960 358900432 9455248
12:10:35 30987 8192000000 10185502432 10712645632 1155128896 12.358 100 16 32 3 1365438512 206667376 3642240
12:12:07 25270 8192000000 10104682832 10538795008 1119244496 12.020 98 41 73 2 1178526384 51873296 7408592
12:13:35 41075 8192000000 10141814112 10618048512 1148840336 12.299 100 22 43 1 1239824960 85935360 5049264
12:15:17 130655 8192000000 10108018400 10617483264 1119700368 12.024 97 11 22 0 1157610384 36157616 1752400
12:18:28 8200 8192000000 10104398432 10541174784 1153234640 12.340 100 65 110 5 1205299280 39304256 12760384
12:19:36 62999 8192000000 10114555888 10577842176 1090394096 11.746 94 18 35 1 1166245200 72493584 3357520
12:22:10 39077 8192000000 10094181408 10528595968 1150883856 12.318 100 34 59 3 1186329824 29327632 6118336
12:23:57 33942 8192000000 10135859648 10607423488 1122677552 12.052 97 15 30 1 1226593920 100884464 3031904
12:25:35 132220 8192000000 10069401504 10515447808 1113552592 11.966 97 18 33 2 1141652128 24203520 3896016
12:28:49 46678 8192000000 10107644416 10539577344 1146178352 12.274 100 60 116 1 1191324608 34496464 10649792
12:30:37 10748 8192000000 10148943792 10587643904 1153832992 12.345 100 58 116 0 1236943712 71582480 11528240
12:31:51 26312 8192000000 10129012128 10580180992 1151306592 12.322 100 31 60 1 1217722912 59583680 6832640
12:33:25 20634 8192000000 10142996176 10582765568 1105416240 11.889 96 53 97 4 1200634384 84283776 10934368
12:34:50 57818 8192000000 10144038992 10628751360 1128133984 12.104 99 25 51 0 1233028848 98515440 6379424
12:37:22 54487 8192000000 10178998352 10633162752 1123436000 12.059 98 26 50 2 1242940656 113821392 5683264
12:39:18 141194 8192000000 10097757120 10595471360 1086610784 11.710 95 17 34 0 1212024112 121400352 4012976
12:42:40 29890 8192000000 10105948160 10542886912 1114502112 11.975 98 34 64 1 1176604640 55621024 6481504
12:44:14 37537 8192000000 10148239328 10616135680 1125786160 12.082 99 25 50 1 1248731056 117176656 5768240
12:45:53 50697 8192000000 10173001888 10634608640 1133408512 12.153 99 18 36 0 1272771296 135153872 4208912
12:47:50 45223 8192000000 10115544128 10571276288 1136823936 12.186 99 44 85 1 1193870944 48662736 8384272
12:49:36 40532 8192000000 10113654464 10565484544 1121743024 12.043 98 29 58 0 1207258720 79828512 5687184
12:51:17 90611 8192000000 10060518720 10533527552 1075660112 11.606 95 24 48 2 1156037856 74114176 6263568
12:53:48 13314 8192000000 10077377840 10513715200 1121055088 12.037 99 39 76 1 1156522816 27297472 8170256
12:55:01 99122 8192000000 10072625504 10507063296 1122587712 12.051 99 34 68 0 1178609312 49278016 6743584
12:57:44 28524 8192000000 10108096720 10546585600 1134273504 12.162 100 50 101 0 1190826400 46365040 10187856
12:59:17 65269 8192000000 10085554880 10541338624 1130436064 12.125 99 14 29 0 1188072064 54476560 3159440
13:01:42 41888 8192000000 10069101536 10520244224 1125318400 12.077 98 25 50 1 1165903664 35720080 4865184
13:03:27 21410 8192000000 10072728016 10534387712 1135853840 12.177 100 8 16 0 1174623872 37055776 1714256
13:04:48 59827 8192000000 10103330672 10584768512 1114316016 11.973 98 23 46 0 1188910672 70011872 4582784
13:07:51 52039 8192000000 10096649056 10541457408 1119953216 12.027 98 23 46 0 1197079648 71826656 5299776
13:09:47 44971 8192000000 10142814208 10614894592 1124053824 12.065 98 29 58 1 1220282784 89670592 6558368
13:11:32 29369 8192000000 10178041648 10622169088 1135415200 12.172 99 39 76 2 1252401040 108446128 8539712
13:13:06 46254 8192000000 10166542320 10604642304 1101186224 11.849 96 41 79 1 1216747216 106688624 8872368
13:15:13 46835 8192000000 10153885696 10621378560 1130415008 12.125 99 22 43 2 1258846656 123658928 4772720
13:17:18 65491 8192000000 10113380880 10584305664 1110170544 11.934 97 33 67 0 1204751376 87564272 7016560
13:19:30 48668 8192000000 10110744944 10557472768 1135680272 12.175 100 39 72 2 1212707424 70082272 6944880
13:21:30 58731 8192000000 10089478944 10544537600 1119939360 12.026 98 9 18 0 1178612016 56925968 1746688
13:23:29 48828 8192000000 10128365536 10583441408 1113994000 11.970 98 36 67 2 1183017696 61794864 7228832
13:25:55 106017 8192000000 10077663840 10518781952 1112238672 11.954 97 16 32 1 1144651296 28429648 3982976
13:28:57 27758 8192000000 10092025424 10542538752 1124054224 12.065 99 42 84 1 1171618000 38369184 9194592
13:30:43 45415 8192000000 10103726160 10553270272 1109656736 11.929 97 29 57 2 1166945520 51567088 5721696
13:32:34 17815 8192000000 10083668272 10533015552 1122004608 12.046 99 29 58 1 1172173840 44226544 5942688
13:33:56 121515 8192000000 10053675072 10549653504 1091993696 11.762 96 14 28 0 1161277584 66356320 2927568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-04-2022 13:39:14 to 23-04-2022 13:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 13:39:31 to 23-04-2022 13:39:33.

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          223115
MON$OLDEST_ACTIVE               224789
MON$OLDEST_SNAPSHOT             224789
MON$NEXT_TRANSACTION            224789
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                       2088928
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             784
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 ---
:552: 
:553: mscs-test-161.budzdorov.ru    Sat Apr 23 13:37:16 2022
:554:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:555: 
:556: 
:557: mscs-test-161.budzdorov.ru    Sat Apr 23 13:37:30 2022
:558:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:559: 
--- end of diff output ---

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

23.04.22 13:39:34. 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-23 13:39:37 No severe PSQL-related problems occured
23.04.22 13:39:38. End of report.