Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.04.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 16268864 kB
MemAvailable 31507760 kB
Buffers 0 kB
Cached 15394632 kB
SwapCached 3196 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-28 13:37:16
DTS_END                         2022-04-28 13:37:16.9350
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/52800
TRN_ID                          218690
ATT_ID                          608
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: 9063.83 22487 1087659
customer order: creation 169.84 470 20381
customer order: refuse 14.97 381 1796
order to supplier: creation 46.12 1267 5534
order to supplier: removal 3.59 161396 431
invoice (draft): creation 44.54 2895 5345
invoice (draft): removal 51.24 947 6149
invoice accept: apply 40.19 24215 4823
invoice accept: cancel 46.31 1101 5557
customer reserve: creation 3766.62 228 451994
customer reserve: removal 4588.80 100 550656
realization accept: apply 81.89 259 9827
realization accept: cancel 81.52 162 9782
payment from customer: creation 58.69 161 7043
payment from customer: removal 6.04 111 725
payment to supplier: creation 48.11 175 5773
payment to supplier: removal 3.13 170 376
service: total inventory turnovers 3.80 4199 456
service: total monetary turnovers 7.50 298 900
service: aggregate perf. data 0.89 16222 107
service: refresh index statistics 0.03 234988 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-04-2022 13:38:17 to 28-04-2022 13:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7530
TEST_TIME 2 6218
TEST_TIME 3 5849
TEST_TIME 4 5266
TEST_TIME 5 5469
TEST_TIME 6 5278
TEST_TIME 7 5372
TEST_TIME 8 5660
TEST_TIME 9 6065
TEST_TIME 10 6193
TEST_TIME 11 6417
TEST_TIME 12 6628
TEST_TIME 13 6780
TEST_TIME 14 6767
TEST_TIME 15 6815
TEST_TIME 16 6961
TEST_TIME 17 7038
TEST_TIME 18 7184
TEST_TIME 19 7720
TEST_TIME 20 7793
TEST_TIME 21 8015
TEST_TIME 22 7943
TEST_TIME 23 8129
TEST_TIME 24 8265
TEST_TIME 25 8113
TEST_TIME 26 8600
TEST_TIME 27 8964
TEST_TIME 28 9409
TEST_TIME 29 10278
TEST_TIME 30 10631
TEST_TIME 31 10904
TEST_TIME 32 10908
TEST_TIME 33 10856
TEST_TIME 34 10790
TEST_TIME 35 10590
TEST_TIME 36 10423
TEST_TIME 37 10577
TEST_TIME 38 10407
TEST_TIME 39 10251
TEST_TIME 40 10289
TEST_TIME 41 10189
TEST_TIME 42 10035
TEST_TIME 43 10075
TEST_TIME 44 10140
TEST_TIME 45 10102
TEST_TIME 46 10076
TEST_TIME 47 10048
TEST_TIME 48 10028
TEST_TIME 49 10047
TEST_TIME 50 10009
TEST_TIME 51 9831
TEST_TIME 52 9734
TEST_TIME 53 9627
TEST_TIME 54 9531
TEST_TIME 55 9444
TEST_TIME 56 9439
TEST_TIME 57 9418
TEST_TIME 58 9383
TEST_TIME 59 9284
TEST_TIME 60 9431
TEST_TIME 61 9520
TEST_TIME 62 9470
TEST_TIME 63 9462
TEST_TIME 64 9467
TEST_TIME 65 9453
TEST_TIME 66 9452
TEST_TIME 67 9419
TEST_TIME 68 9585
TEST_TIME 69 9520
TEST_TIME 70 9505
TEST_TIME 71 9520
TEST_TIME 72 9619
TEST_TIME 73 9574
TEST_TIME 74 9675
TEST_TIME 75 9697
TEST_TIME 76 9685
TEST_TIME 77 9586
TEST_TIME 78 9570
TEST_TIME 79 9519
TEST_TIME 80 9439
TEST_TIME 81 9401
TEST_TIME 82 9377
TEST_TIME 83 9329
TEST_TIME 84 9358
TEST_TIME 85 9316
TEST_TIME 86 9228
TEST_TIME 87 9270
TEST_TIME 88 9256
TEST_TIME 89 9248
TEST_TIME 90 9225
TEST_TIME 91 9255
TEST_TIME 92 9198
TEST_TIME 93 9243
TEST_TIME 94 9220
TEST_TIME 95 9199
TEST_TIME 96 9278
TEST_TIME 97 9423
TEST_TIME 98 9414
TEST_TIME 99 9418
TEST_TIME 100 9472
TEST_TIME 101 9482
TEST_TIME 102 9437
TEST_TIME 103 9367
TEST_TIME 104 9325
TEST_TIME 105 9289
TEST_TIME 106 9281
TEST_TIME 107 9237
TEST_TIME 108 9233
TEST_TIME 109 9207
TEST_TIME 110 9151
TEST_TIME 111 9124
TEST_TIME 112 9107
TEST_TIME 113 9123
TEST_TIME 114 9168
TEST_TIME 115 9146
TEST_TIME 116 9159
TEST_TIME 117 9156
TEST_TIME 118 9154
TEST_TIME 119 9118
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-04-2022 13:38:20 to 28-04-2022 13:38:24.

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 1505752 1505752 0 0.00 0 32077 13 0 0
doc_list_biud 1505745 1505745 0 0.00 0 67332 42 0 0
sp_add_invoice_to_stock 4939 4823 116 2.34 40 214791 24216 0 116
sp_cancel_adding_invoice 5557 5557 0 0.00 5 75547 1102 0 0
sp_cancel_client_order 1796 1796 0 0.00 6 11740 381 0 0
sp_cancel_customer_reserve 550656 550656 0 0.00 1 69323 101 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 3792 112 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 13527 170 0 0
sp_cancel_supplier_invoice 6168 6149 19 0.30 2 43510 948 0 19
sp_cancel_supplier_order 431 431 0 0.00 10 1386469 161397 0 0
sp_cancel_write_off 9782 9782 0 0.00 2 29682 163 0 0
sp_client_order 20381 20381 0 0.00 5 38420 470 0 0
sp_customer_reserve 451994 451994 0 0.00 1 160455 228 0 0
sp_fill_shopping_cart 31250 31250 0 0.00 2 13876 75 0 0
sp_fill_shopping_cart_clo_res 451964 451964 0 0.00 0 21660 19 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 21315 299 0 0
sp_kill_cost_storno 16440 16440 0 0.00 0 16228 69 0 0
sp_kill_qstorno_ret_qs2qd 1117674 1117674 0 0.00 0 67327 44 0 0
sp_kill_qty_storno 588976 588976 0 0.00 0 67332 99 0 0
sp_lock_selected_doc 20417 20417 0 0.00 0 9751 8 0 0
sp_make_cost_storno 27422 27422 0 0.00 0 11622 75 0 0
sp_multiply_rows_for_pdistr 27421 27421 0 0.00 0 8426 39 0 0
sp_multiply_rows_for_qdistr 20381 20381 0 0.00 0 11900 185 0 0
sp_pay_from_customer 7043 7043 0 0.00 5 15194 161 0 0
sp_pay_to_supplier 5773 5773 0 0.00 6 7913 175 0 0
sp_payment_common 12815 12815 0 0.00 2 8462 44 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 1 11728 316 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 0 26879 473 0 0
sp_qd_handle_on_reserve_upd_sts 9825 9825 0 0.00 0 9118 56 0 0
sp_reserve_write_off 9827 9827 0 0.00 9 18945 260 0 0
sp_supplier_invoice 5345 5345 0 0.00 10 89672 2895 0 0
sp_supplier_order 5534 5534 0 0.00 8 38235 1268 0 0
srv_aggregate_perf_data 107 107 0 0.00 2655 190274 16223 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 651 172071 39045 0 0
srv_find_qd_qs_mism 1040417 1040417 0 0.00 0 32074 10 0 0
srv_make_invnt_saldo 456 456 0 0.00 58 108793 4199 0 0
srv_make_money_saldo 900 900 0 0.00 1 11200 299 0 0
srv_recalc_idx_stat 4 4 0 0.00 78071 397208 234989 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4829 4829 0 0.00 1 13089 831 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 6034 380 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 2363 25 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 671 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 2355 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 622 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 732 28 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 93006 5901 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 4443 28 0 0
t$perf-norm:sp_client_order 20381 20381 0 0.00 0 4512 32 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 3925 23 0 0
t$perf-norm:sp_pay_from_customer 7043 7043 0 0.00 0 9837 17 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 4155 17 0 0
t$perf-norm:sp_reserve_write_off 9828 9828 0 0.00 0 6139 21 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 8011 31 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 6366 30 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 2231 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 155 8 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 641 12 0 0
t$perf-norm:srv_make_money_saldo 900 900 0 0.00 0 1392 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 70 134 93 0 0
v_add_invoice_to_stock 9784 9552 232 2.37 0 3011 19 0 232
v_all_customers 37300 37300 0 0.00 0 70 3 0 0
v_all_suppliers 30864 30864 0 0.00 0 161 4 0 0
v_all_wares 40752 40752 0 0.00 0 58 3 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1056 15 0 0
v_cancel_client_order 3592 3592 0 0.00 0 1858 9 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 527 9 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 485 9 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 2915 15 0 0
v_cancel_supplier_invoice 816 778 38 4.65 0 1957 20 0 38
v_cancel_supplier_order 862 862 0 0.00 0 302 8 0 0
v_cancel_write_off 2002 2002 0 0.00 0 4453 24 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 145 4 0 0
v_max_id_clo_res 942 942 0 0.00 0 2301 14 0 0
v_max_id_ord_sup 5340 5340 0 0.00 0 8841 8 0 0
v_max_non_paid_invoice 1211 1211 0 0.00 0 130 7 0 0
v_max_non_paid_realizn 3692 3692 0 0.00 0 3906 8 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 1433 7 0 0
v_min_id_clo_res 942 942 0 0.00 0 4097 18 0 0
v_min_id_ord_sup 5340 5340 0 0.00 0 4843 11 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 7600 19 0 0
v_min_non_paid_realizn 7042 7042 0 0.00 0 6499 17 0 0
v_our_firm 10150 10150 0 0.00 0 138 4 0 0
v_reserve_write_off 19648 19648 0 0.00 0 4012 8 0 0
x_lock_depdocs_on_canc_invoice 11317 11317 0 0.00 0 18289 138 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 16841 388 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 38223 1117 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 5 89658 2689 0 0
x_make_qstorno_2100_3300 451994 451994 0 0.00 0 160453 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-04-2022 13:38:25 to 28-04-2022 13:38:29.

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:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 38167 8192000000 9734791344 10038161408 808264240 8.980 100 87 174 0 836957216 7677296 21015680
11:10:04 19646 8192000000 9773941280 10082922496 843382016 9.334 100 92 184 2 879445488 13901744 22161728
11:11:30 19526 8192000000 9823244912 10149793792 880409376 9.704 100 81 162 0 928023424 29206736 18407312
11:12:52 9945 8192000000 9877082416 10216796160 925981696 10.155 100 80 158 0 995045296 51662656 17400944
11:14:08 8058 8192000000 9917993504 10268418048 963700064 10.525 100 76 152 0 1031651968 52027760 15924144
11:15:20 2127 8192000000 9961339952 10317324288 996586672 10.845 100 93 176 0 1080752096 67073440 17091984
11:16:24 2484 8192000000 9981066480 10346463232 1019592656 11.068 100 90 174 1 1097984720 61205488 17186576
11:17:29 1132 8192000000 10008556640 10382282752 1043409216 11.297 100 93 183 2 1131576032 70320176 17846640
11:18:32 1006 8192000000 10010353824 10385707008 1055395072 11.412 100 87 167 0 1127933280 56490336 16047872
11:19:34 1136 8192000000 10051668720 10429779968 1060723824 11.463 100 98 191 1 1166721328 85736048 20261456
11:20:36 989 8192000000 10047273792 10424647680 1065443552 11.509 100 92 177 0 1161451008 77315808 18691648
11:21:40 805 8192000000 10034852768 10424451072 1074088416 11.591 100 79 157 0 1159942912 69816736 16037760
11:22:41 1080 8192000000 10026115520 10411921408 1078731824 11.635 100 88 168 1 1148758176 51949424 18076928
11:23:44 1136 8192000000 10037247456 10422726656 1083985744 11.685 100 95 176 0 1157662720 55698080 17978896
11:24:45 1387 8192000000 10065483216 10452402176 1085855296 11.703 100 89 176 1 1187350688 82642576 18852816
11:25:49 4628 8192000000 10044815776 10439045120 1092871520 11.770 100 65 128 1 1170990496 65474544 12644432
11:26:54 2506 8192000000 10047670032 10438602752 1092556448 11.767 100 81 151 1 1164969696 57789440 14623808
11:27:57 1089 8192000000 10040770768 10428747776 1088039200 11.724 100 85 167 1 1161480224 57057520 16383504
11:28:59 6132 8192000000 10037363808 10430410752 1081663936 11.663 100 62 120 0 1156215024 62726624 11824464
11:30:06 1169 8192000000 10013205584 10391879680 1062857648 11.484 99 97 188 0 1132923728 51839744 18226336
11:31:08 4580 8192000000 10028980992 10419486720 1070054080 11.553 100 75 145 2 1154787760 69910160 14823520
11:32:14 1321 8192000000 10005245376 10380505088 1049213968 11.353 100 88 170 3 1124831232 58592784 17024480
11:33:16 2431 8192000000 9997906880 10376814592 1035074560 11.217 100 80 160 0 1120108640 68037168 16996912
11:34:19 3589 8192000000 10035935056 10423349248 1053471504 11.394 100 61 114 0 1150350256 85493120 11385632
11:35:23 1025 8192000000 10001429360 10378022912 1041582656 11.280 100 94 184 0 1121557168 60360560 19613952
11:36:25 12175 8192000000 9998786800 10380103680 1039659680 11.261 100 77 146 1 1118166672 62641120 15865872
11:37:44 34368 8192000000 10013310336 10401718272 1068783424 11.540 100 67 128 0 1141165056 59281104 13100528
11:39:24 2536 8192000000 10053274192 10449248256 1087609056 11.720 100 60 114 1 1171939728 72809856 11520816
11:40:31 3257 8192000000 10058849024 10443837440 1105001376 11.885 100 72 139 2 1170005440 50643920 14360144
11:41:38 13191 8192000000 10115384992 10509459456 1109662624 11.929 100 57 108 1 1241510032 119576240 12271168
11:43:02 3533 8192000000 10145250144 10536980480 1121489120 12.041 100 70 132 1 1257417424 121826368 14101936
11:44:18 6043 8192000000 10169922000 10570407936 1135375344 12.172 100 69 129 0 1293280336 144217424 13687568
11:45:34 637 8192000000 10244408320 10639695872 1147574784 12.287 100 91 165 1 1356887968 191077920 18235264
11:46:39 1444 8192000000 10266330832 10660147200 1162346288 12.425 100 97 188 1 1379328656 196484528 20497840
11:47:44 980 8192000000 10308791216 10710061056 1169044976 12.488 100 97 191 1 1423475744 230972304 23458464
11:48:49 1762 8192000000 10305715776 10719559680 1164553552 12.446 100 90 174 2 1421415888 236864320 19998016
11:49:53 1316 8192000000 10348667008 10760749056 1165995584 12.459 100 95 184 2 1459835824 272517296 21322944
11:50:58 1254 8192000000 10376700192 10779467776 1184329232 12.631 100 93 180 1 1488427328 282235248 21862848
11:52:04 2011 8192000000 10441328608 10846216192 1200622992 12.782 100 91 181 0 1545973072 324338720 21011360
11:53:10 1130 8192000000 10489672352 10902802432 1195806224 12.737 100 99 192 0 1599860992 381403056 22651712
11:54:16 754 8192000000 10488535312 10913595392 1185518304 12.642 100 95 190 1 1596878000 388226592 23133104
11:55:21 779 8192000000 10509616576 10972360704 1193579488 12.717 100 96 192 0 1622778224 405449792 23748944
11:56:24 3525 8192000000 10496872400 10931294208 1191826432 12.700 100 65 130 0 1607294400 399167168 16300800
11:57:32 806 8192000000 10485754624 10925379584 1199868288 12.775 100 82 143 2 1591635232 375527584 16239360
11:58:37 2694 8192000000 10551931024 10980630528 1210297536 12.872 100 81 157 1 1658709168 428684688 19726944
11:59:44 1258 8192000000 10580778048 11012390912 1217579968 12.939 100 95 182 1 1682390080 443155008 21655104
12:00:47 5021 8192000000 10582377360 11037454336 1207884944 12.849 100 61 121 1 1692495824 468784576 15826304
12:02:08 9013 8192000000 10642343216 11071328256 1197985792 12.758 100 58 115 0 1738403408 525192096 15225520
12:03:20 7305 8192000000 10608133408 11091718144 1194171184 12.722 100 42 82 2 1712607008 506827952 11607872
12:04:37 25043 8192000000 10497383552 10977456128 1164627360 12.447 99 31 62 1 1639945168 466666096 8651712
12:06:03 16825 8192000000 10331404704 10868084736 1137162832 12.189 99 44 86 0 1429502240 281753632 10585776
12:07:31 71761 8192000000 10128496976 10588655616 1077090864 11.620 94 19 38 0 1189441296 108008944 4341488
12:09:43 26772 8192000000 10102751392 10546049024 1115004720 11.980 98 25 48 1 1176662288 56606144 5051424
12:11:16 40041 8192000000 10061733312 10536296448 1131125600 12.132 100 13 26 0 1185592336 51771440 2695296
12:12:59 85769 8192000000 10105932928 10594938880 1121098128 12.037 99 16 30 1 1196362480 72057920 3206432
12:15:25 78042 8192000000 10098945360 10544902144 1102970736 11.866 97 29 55 1 1156986880 48260112 5756032
12:18:05 25366 8192000000 10071111232 10511171584 1138240192 12.199 100 27 54 2 1167303136 23337872 5725072
12:19:31 107347 8192000000 10098945472 10583355392 1114085888 11.971 98 30 58 1 1187406176 67280224 6040064
12:22:21 95614 8192000000 10099220112 10567553024 1123024128 12.056 99 15 30 0 1165738320 40076192 2638000
12:24:58 24976 8192000000 10095373200 10528768000 1141294832 12.228 100 60 115 0 1196726448 42767968 12663648
12:26:24 154490 8192000000 10069641664 10527510528 1103738496 11.873 97 15 30 0 1142673920 35992288 2943136
12:30:19 148814 8192000000 10132986336 10563325952 1059491168 11.452 92 24 48 0 1136766688 71425456 5850064
12:33:48 27715 8192000000 10155220304 10599383040 1129864544 12.120 99 56 100 3 1263927712 121798752 12264416
12:35:22 172070 8192000000 10133240656 10655539200 1097048768 11.810 96 18 36 0 1254238224 153150336 4039120
12:39:17 68794 8192000000 10088290720 10566074368 1130797536 12.129 100 6 12 0 1183535856 51081088 1657232
12:41:39 55602 8192000000 10090581360 10526437376 1110309760 11.935 98 26 52 1 1161502656 45812128 5380768
12:43:35 33054 8192000000 10164863936 10621644800 1135140784 12.170 100 39 78 0 1269332736 126025072 8166880
12:45:16 58539 8192000000 10094362144 10567913472 1119662880 12.024 98 19 38 2 1207159696 83320640 4176176
12:47:16 50369 8192000000 10164880048 10633576448 1132235760 12.142 99 22 44 0 1237004000 100016496 4751744
12:49:06 68079 8192000000 10121817760 10557501440 1120861232 12.035 98 27 54 1 1181700784 54845088 5994464
12:51:15 94492 8192000000 10096616416 10580418560 1114812960 11.978 97 16 32 1 1223850576 105650192 3387424
12:53:49 107244 8192000000 10074603984 10521346048 1105710128 11.892 97 28 56 1 1149602480 37875568 6016784
12:56:37 53407 8192000000 10084759440 10527252480 1129172944 12.114 99 30 56 1 1175298960 39849936 6276080
12:58:40 32585 8192000000 10129695568 10583355392 1140504768 12.220 100 25 50 1 1231973472 85998016 5470688
13:00:21 65606 8192000000 10136703296 10589163520 1129489664 12.117 99 15 30 2 1219100096 86333296 3277136
13:02:31 54756 8192000000 10093359728 10546884608 1134475008 12.164 100 10 20 0 1190068480 53710400 1883072
13:04:25 70587 8192000000 10060963904 10529390592 1088890992 11.732 96 9 18 2 1145985072 54933536 2160544
13:06:48 25062 8192000000 10095271968 10528382976 1105242272 11.887 97 35 70 0 1167594128 54961056 7390800
13:08:14 8179 8192000000 10105862112 10533801984 1128802992 12.110 100 40 78 0 1209874832 73606736 7465104
13:09:23 22044 8192000000 10204003840 10635063296 1155261152 12.359 100 52 104 1 1284798416 118577856 10959408
13:10:45 47598 8192000000 10183454128 10638598144 1118337200 12.011 97 23 46 2 1274898544 151064896 5496448
13:12:33 39847 8192000000 10218372144 10650079232 1135584624 12.174 100 29 58 3 1311514352 169765184 6164544
13:14:18 34478 8192000000 10066100912 10504216576 1096969056 11.809 97 29 57 2 1152696544 49830912 5896576
13:15:53 9560 8192000000 10111756016 10532847616 1129853936 12.120 100 51 102 1 1195495584 55164192 10477456
13:17:05 32113 8192000000 10096019056 10599243776 1135378192 12.172 100 27 54 0 1228641888 87921952 5341744
13:19:04 46956 8192000000 10112979200 10559168512 1138323552 12.200 99 32 60 1 1190203920 45675008 6205360
13:21:23 49837 8192000000 10094151008 10550423552 1137077440 12.188 99 36 70 0 1177713008 33348784 7286784
13:23:23 68738 8192000000 10075856784 10564935680 1120550032 12.032 98 13 26 2 1171120240 47877744 2692464
13:25:32 56190 8192000000 10083715632 10531155968 1138952288 12.206 100 21 42 1 1187944384 44469872 4522224
13:27:28 51528 8192000000 10083376816 10532319232 1117238096 12.001 98 16 31 1 1177927072 57152512 3536464
13:29:28 29863 8192000000 10110511904 10543378432 1109571840 11.928 98 29 54 1 1184124240 68611264 5941136
13:31:00 80846 8192000000 10083373248 10547744768 1085743984 11.702 95 16 32 0 1144849936 55155312 3950640
13:33:21 20358 8192000000 10046306192 10470567936 1088824688 11.731 97 55 106 2 1118900048 18717200 11358160
13:34:43 36198 8192000000 10098549056 10531139584 1125870976 12.082 100 44 87 1 1192401776 57434496 9096304
13:36:23 16050 8192000000 10130897136 10559967232 1118891520 12.017 99 59 116 1 1209695472 79267424 11536528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-04-2022 13:38:31 to 28-04-2022 13:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 28-04-2022 13:38:48 to 28-04-2022 13:38:49.

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          215874
MON$OLDEST_ACTIVE               220479
MON$OLDEST_SNAPSHOT             220479
MON$NEXT_TRANSACTION            220479
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                       2069280
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             776
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 ---
:1506: 
:1507: mscs-test-161.budzdorov.ru    Thu Apr 28 13:37:18 2022
:1508:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1509: 
:1510: 
:1511: mscs-test-161.budzdorov.ru    Thu Apr 28 13:37:18 2022
:1512:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1513: 
:1514: 
:1515: mscs-test-161.budzdorov.ru    Thu Apr 28 13:37:29 2022
:1516:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1517: 
:1518: 
:1519: mscs-test-161.budzdorov.ru    Thu Apr 28 13:37:29 2022
:1520:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1521: 
--- end of diff output ---

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

28.04.22 13:38:50. 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-28 13:38:53 No severe PSQL-related problems occured
28.04.22 13:38:54. End of report.