Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 22:39
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 16647164 kB
MemAvailable 31466736 kB
Buffers 0 kB
Cached 15045108 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-17 22:38:13
DTS_END                         2022-04-17 22:38:13.8420
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(57:17)
IP                              ::1/46504
TRN_ID                          242188
ATT_ID                          656
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: 9188.48 17561 1102617
customer order: creation 176.74 338 21209
customer order: refuse 14.73 327 1768
order to supplier: creation 46.11 1246 5533
order to supplier: removal 3.50 87591 420
invoice (draft): creation 45.21 3204 5425
invoice (draft): removal 51.42 695 6170
invoice accept: apply 42.41 19810 5089
invoice accept: cancel 46.45 849 5574
customer reserve: creation 3939.51 197 472741
customer reserve: removal 4530.03 53 543604
realization accept: apply 85.95 170 10314
realization accept: cancel 77.28 101 9274
payment from customer: creation 58.58 107 7029
payment from customer: removal 5.70 93 684
payment to supplier: creation 49.17 116 5900
payment to supplier: removal 3.29 46 395
service: total inventory turnovers 4.74 993 569
service: total monetary turnovers 6.82 42 818
service: aggregate perf. data 0.81 20486 97
service: refresh index statistics 0.03 214758 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-04-2022 22:39:27 to 17-04-2022 22:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6773
TEST_TIME 2 6393
TEST_TIME 3 5643
TEST_TIME 4 6394
TEST_TIME 5 6040
TEST_TIME 6 5627
TEST_TIME 7 5829
TEST_TIME 8 5640
TEST_TIME 9 5813
TEST_TIME 10 6703
TEST_TIME 11 7107
TEST_TIME 12 7038
TEST_TIME 13 7024
TEST_TIME 14 7245
TEST_TIME 15 7399
TEST_TIME 16 7469
TEST_TIME 17 7468
TEST_TIME 18 7540
TEST_TIME 19 8027
TEST_TIME 20 8307
TEST_TIME 21 8833
TEST_TIME 22 9118
TEST_TIME 23 8938
TEST_TIME 24 9152
TEST_TIME 25 9333
TEST_TIME 26 9224
TEST_TIME 27 9594
TEST_TIME 28 9722
TEST_TIME 29 9963
TEST_TIME 30 10146
TEST_TIME 31 9964
TEST_TIME 32 10127
TEST_TIME 33 9986
TEST_TIME 34 9820
TEST_TIME 35 10093
TEST_TIME 36 10055
TEST_TIME 37 9868
TEST_TIME 38 9726
TEST_TIME 39 9681
TEST_TIME 40 9597
TEST_TIME 41 9504
TEST_TIME 42 9429
TEST_TIME 43 9383
TEST_TIME 44 9481
TEST_TIME 45 9582
TEST_TIME 46 9505
TEST_TIME 47 9442
TEST_TIME 48 9433
TEST_TIME 49 9465
TEST_TIME 50 9456
TEST_TIME 51 9369
TEST_TIME 52 9311
TEST_TIME 53 9225
TEST_TIME 54 9187
TEST_TIME 55 9126
TEST_TIME 56 9038
TEST_TIME 57 9000
TEST_TIME 58 9070
TEST_TIME 59 9045
TEST_TIME 60 9120
TEST_TIME 61 9166
TEST_TIME 62 9178
TEST_TIME 63 9274
TEST_TIME 64 9302
TEST_TIME 65 9314
TEST_TIME 66 9272
TEST_TIME 67 9460
TEST_TIME 68 9455
TEST_TIME 69 9426
TEST_TIME 70 9498
TEST_TIME 71 9570
TEST_TIME 72 9524
TEST_TIME 73 9621
TEST_TIME 74 9499
TEST_TIME 75 9483
TEST_TIME 76 9409
TEST_TIME 77 9373
TEST_TIME 78 9344
TEST_TIME 79 9355
TEST_TIME 80 9286
TEST_TIME 81 9284
TEST_TIME 82 9242
TEST_TIME 83 9236
TEST_TIME 84 9381
TEST_TIME 85 9345
TEST_TIME 86 9382
TEST_TIME 87 9385
TEST_TIME 88 9390
TEST_TIME 89 9381
TEST_TIME 90 9382
TEST_TIME 91 9368
TEST_TIME 92 9462
TEST_TIME 93 9462
TEST_TIME 94 9421
TEST_TIME 95 9431
TEST_TIME 96 9433
TEST_TIME 97 9393
TEST_TIME 98 9366
TEST_TIME 99 9347
TEST_TIME 100 9291
TEST_TIME 101 9276
TEST_TIME 102 9265
TEST_TIME 103 9208
TEST_TIME 104 9229
TEST_TIME 105 9186
TEST_TIME 106 9179
TEST_TIME 107 9180
TEST_TIME 108 9202
TEST_TIME 109 9189
TEST_TIME 110 9225
TEST_TIME 111 9221
TEST_TIME 112 9209
TEST_TIME 113 9235
TEST_TIME 114 9255
TEST_TIME 115 9263
TEST_TIME 116 9299
TEST_TIME 117 9314
TEST_TIME 118 9324
TEST_TIME 119 9269
TEST_TIME 120 9192

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-04-2022 22:39:31 to 17-04-2022 22:39:34.

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 1541045 1541045 0 0.00 0 21084 7 0 0
doc_list_biud 1541039 1541039 0 0.00 0 38138 24 0 0
sp_add_invoice_to_stock 5185 5089 96 1.85 25 203354 19810 0 96
sp_cancel_adding_invoice 5574 5574 0 0.00 5 75891 850 0 0
sp_cancel_client_order 1768 1768 0 0.00 4 7075 328 0 0
sp_cancel_customer_reserve 543604 543604 0 0.00 1 21831 54 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 5705 93 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2313 47 0 0
sp_cancel_supplier_invoice 6174 6170 4 0.06 2 17327 695 0 4
sp_cancel_supplier_order 420 420 0 0.00 6 846589 87592 0 0
sp_cancel_write_off 9274 9274 0 0.00 1 10120 102 0 0
sp_client_order 21209 21209 0 0.00 3 28343 339 0 0
sp_customer_reserve 472741 472741 0 0.00 0 41472 198 0 0
sp_fill_shopping_cart 32152 32152 0 0.00 1 23834 37 0 0
sp_fill_shopping_cart_clo_res 472734 472734 0 0.00 0 20324 12 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 19825 263 0 0
sp_kill_cost_storno 15927 15927 0 0.00 0 7033 53 0 0
sp_kill_qstorno_ret_qs2qd 1103072 1103072 0 0.00 0 21820 29 0 0
sp_kill_qty_storno 582206 582206 0 0.00 0 38138 64 0 0
sp_lock_selected_doc 21113 21113 0 0.00 0 7676 4 0 0
sp_make_cost_storno 28325 28325 0 0.00 0 12393 50 0 0
sp_multiply_rows_for_pdistr 28325 28325 0 0.00 0 15346 28 0 0
sp_multiply_rows_for_qdistr 21209 21209 0 0.00 0 11220 159 0 0
sp_pay_from_customer 7029 7029 0 0.00 4 15786 108 0 0
sp_pay_to_supplier 5900 5900 0 0.00 4 11547 117 0 0
sp_payment_common 12929 12929 0 0.00 1 15780 32 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 7067 298 0 0
sp_qd_handle_on_invoice_upd_sts 10656 10656 0 0.00 0 38136 338 0 0
sp_qd_handle_on_reserve_upd_sts 10314 10314 0 0.00 0 17144 58 0 0
sp_reserve_write_off 10314 10314 0 0.00 6 17222 171 0 0
sp_supplier_invoice 5425 5425 0 0.00 5 75988 3204 0 0
sp_supplier_order 5533 5533 0 0.00 5 23965 1247 0 0
srv_aggregate_perf_data 97 97 0 0.00 2560 244586 20486 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1986 131743 44918 0 0
srv_find_qd_qs_mism 1054632 1054632 0 0.00 0 21083 6 0 0
srv_make_invnt_saldo 569 569 0 0.00 39 58781 993 0 0
srv_make_money_saldo 818 818 0 0.00 1 7926 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 172862 261894 214759 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5089 5089 0 0.00 0 11280 170 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 6860 145 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 10030 22 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 6084 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 6071 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1901 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1015 22 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 21371 1592 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 3819 12 0 0
t$perf-norm:sp_client_order 21209 21209 0 0.00 0 13460 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 2034 9 0 0
t$perf-norm:sp_pay_from_customer 7029 7029 0 0.00 0 12763 13 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 13983 16 0 0
t$perf-norm:sp_reserve_write_off 10314 10314 0 0.00 0 11887 13 0 0
t$perf-norm:sp_supplier_invoice 5426 5426 0 0.00 0 5607 17 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 17564 22 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 407 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 125 3 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 981 22 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 638 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10356 10164 192 1.85 0 5763 18 0 192
v_all_customers 37778 37778 0 0.00 0 27 1 0 0
v_all_suppliers 30396 30396 0 0.00 0 82 1 0 0
v_all_wares 42418 42418 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 3074 11 0 0
v_cancel_client_order 3536 3536 0 0.00 0 2401 7 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 3129 7 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 1565 5 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2297 5 0 0
v_cancel_supplier_invoice 756 748 8 1.05 0 5239 26 0 8
v_cancel_supplier_order 840 840 0 0.00 0 1005 5 0 0
v_cancel_write_off 1980 1980 0 0.00 0 1624 14 0 0
v_max_id_clo_ord 5531 5531 0 0.00 0 1811 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 2509 16 0 0
v_max_id_ord_sup 5412 5412 0 0.00 0 4418 6 0 0
v_max_non_paid_invoice 1645 1645 0 0.00 0 1914 4 0 0
v_max_non_paid_realizn 4067 4067 0 0.00 0 5695 5 0 0
v_min_id_clo_ord 5531 5531 0 0.00 0 3242 7 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 905 4 0 0
v_min_id_ord_sup 5412 5412 0 0.00 0 6716 9 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 11280 23 0 0
v_min_non_paid_realizn 7029 7029 0 0.00 0 15776 26 0 0
v_our_firm 10564 10564 0 0.00 0 131 1 0 0
v_reserve_write_off 20628 20628 0 0.00 0 4767 5 0 0
x_lock_depdocs_on_canc_invoice 11370 11370 0 0.00 0 15608 107 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 7107 310 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 1 23926 1164 0 0
x_make_qstorno_1200_2000 5425 5425 0 0.00 2 75970 3071 0 0
x_make_qstorno_2100_3300 472741 472741 0 0.00 0 37869 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 22:39:35 to 17-04-2022 22:39:39.

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
20:08:17 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:18 15568 8192000000 9900926080 10210553856 982994848 10.713 100 84 168 0 1010421504 5773088 21653568
20:10:39 15771 8192000000 9948658800 10269052928 1029435776 11.163 100 88 176 0 1060786928 8549568 22801584
20:12:00 12164 8192000000 9983801056 10312540160 1063410048 11.489 100 89 178 0 1095523408 9852768 22260592
20:13:17 16690 8192000000 10021671680 10368270336 1110814448 11.940 100 73 146 2 1139070688 11067616 17188624
20:14:37 11366 8192000000 10064959008 10420871168 1150900784 12.318 100 81 159 1 1182554896 13592640 18061472
20:15:52 8257 8192000000 10094064752 10465759232 1190827984 12.691 100 67 134 0 1218388048 12916704 14643360
20:17:04 3480 8192000000 10153644272 10524811264 1236935344 13.118 100 79 158 0 1266844944 12008768 17900832
20:18:08 2113 8192000000 10192271168 10569134080 1270347392 13.425 100 84 170 1 1302058336 12643552 19067392
20:19:12 1676 8192000000 10204930944 10591150080 1290323536 13.607 100 86 171 0 1323113280 13502832 19286912
20:20:15 5670 8192000000 10199143008 10599981056 1302935424 13.722 100 39 79 0 1319256464 7702016 8619024
20:21:21 41249 8192000000 10200411248 10612699136 1310043168 13.786 100 35 69 2 1324746560 6416512 8286880
20:23:09 4665 8192000000 10223156640 10632769536 1323381664 13.907 100 35 70 0 1339096432 8225488 7489280
20:24:14 7596 8192000000 10221677312 10640572416 1332162880 13.987 100 23 47 3 1343525120 6241648 5120592
20:25:23 62398 8192000000 10220737696 10644066304 1327030272 13.940 99 40 81 2 1345890160 9583936 9275952
20:27:26 6004 8192000000 10214545152 10630647808 1303319360 13.725 98 30 60 2 1316636800 6121408 7196032
20:28:32 5123 8192000000 10174963680 10581966848 1269482752 13.417 98 33 65 2 1283789520 6715632 7591136
20:29:38 6015 8192000000 10168904000 10570366976 1253279216 13.268 98 20 40 0 1264051776 6348016 4424544
20:30:45 20191 8192000000 10139473008 10542100480 1206877824 12.840 95 25 50 0 1218025680 4844352 6303504
20:32:09 5815 8192000000 10126654000 10523299840 1224175808 13.000 98 27 54 1 1237293056 5778128 7339120
20:33:21 7845 8192000000 10162671152 10569138176 1249901152 13.237 98 32 64 0 1264109104 6267584 7940368
20:34:32 12357 8192000000 10180739232 10585280512 1282725792 13.538 100 16 32 0 1291733648 5734944 3272912
20:35:49 21691 8192000000 10178017792 10593288192 1288463264 13.590 100 30 60 0 1301548144 6203328 6881552
20:37:14 9867 8192000000 10196565328 10609860608 1300453760 13.699 100 26 53 1 1317055520 11134320 5467440
20:38:29 12886 8192000000 10239417120 10648256512 1329778304 13.965 100 39 77 1 1344407568 6353120 8276144
20:39:47 39040 8192000000 10272871152 10684936192 1353092000 14.175 100 50 100 1 1374608880 10316816 11200064
20:41:44 21535 8192000000 10277667376 10695770112 1366265424 14.294 100 41 80 1 1388536448 12129840 10141184
20:43:11 19836 8192000000 10290053584 10712870912 1377773472 14.397 100 51 102 1 1402997248 13342112 11881664
20:44:36 5972 8192000000 10298086288 10721689600 1386376528 14.474 100 54 108 3 1412707184 14113536 12217120
20:45:47 9302 8192000000 10304135008 10731438080 1394075664 14.542 100 46 92 0 1419825760 14612496 11137600
20:47:01 6468 8192000000 10328762288 10749030400 1402804768 14.620 100 58 116 4 1435400336 18687120 13908448
20:48:11 7332 8192000000 10328190560 10749882368 1391488176 14.519 99 61 121 1 1429586016 23197024 14900816
20:49:20 3426 8192000000 10317777984 10737393664 1392431440 14.528 100 75 151 2 1429173504 18827232 17914832
20:50:31 13415 8192000000 10315361120 10737696768 1393278560 14.535 100 55 110 1 1427088576 20813728 12996288
20:51:52 9765 8192000000 10301081360 10721845248 1370707632 14.333 99 45 90 0 1402609088 20677168 11224288
20:53:05 10784 8192000000 10285878192 10700713984 1364780240 14.280 99 53 106 2 1395456032 17701040 12974752
20:54:19 7680 8192000000 10262048880 10671091712 1332204624 13.987 99 59 118 0 1368386160 21691232 14490304
20:55:31 6045 8192000000 10288785648 10697699328 1357211968 14.212 100 64 129 3 1394096560 20496368 16388224
20:56:45 1985 8192000000 10282124800 10681634816 1336072496 14.022 100 78 154 1 1388013440 32302032 19638912
20:57:53 2258 8192000000 10292976624 10697981952 1349881792 14.146 100 69 139 1 1396503376 28686032 17935552
20:58:58 5136 8192000000 10307821056 10719358976 1364233824 14.275 100 60 120 0 1409921072 30480432 15206816
21:00:08 8862 8192000000 10297634512 10716848128 1362554128 14.260 100 40 80 0 1402780416 29415600 10810688
21:01:22 46492 8192000000 10282179808 10698018816 1329886352 13.966 98 44 87 1 1364353648 23725136 10742160
21:03:13 24773 8192000000 10257951392 10698182656 1313479792 13.818 97 17 34 0 1345703680 27689248 4534640
21:04:47 11954 8192000000 10277862576 10707861504 1345511632 14.107 100 21 42 0 1379711104 28386976 5812496
21:06:05 29828 8192000000 10262558256 10694361088 1311911840 13.803 97 15 30 0 1337967696 22267872 3787984
21:07:37 25958 8192000000 10257309280 10673278976 1329051856 13.959 99 31 60 0 1345806960 9554336 7200768
21:09:10 123731 8192000000 10268371984 10718294016 1296861168 13.667 95 5 10 0 1311313696 13712160 740368
21:12:15 60924 8192000000 10293684160 10739351552 1376312688 14.384 100 27 54 0 1395366672 12718384 6335600
21:14:19 72964 8192000000 10289703648 10736001024 1379743872 14.414 100 11 22 0 1392419776 10308864 2367040
21:16:43 43371 8192000000 10307972928 10750078976 1374768208 14.370 99 36 70 0 1397696288 14665248 8262832
21:18:27 28102 8192000000 10301485424 10744401920 1374985968 14.372 99 28 54 2 1393284672 10876640 7422064
21:19:57 26404 8192000000 10323945648 10764259328 1403853392 14.629 100 45 90 0 1431452176 16722928 10875856
21:21:32 52796 8192000000 10315544400 10775961600 1403415088 14.625 100 18 36 0 1432873936 24323664 5135184
21:23:56 87055 8192000000 10303319200 10762608640 1388392560 14.492 99 13 26 1 1404030048 12186144 3451344
21:26:41 57206 8192000000 10294685664 10749636608 1395056704 14.551 100 23 46 0 1409720080 8901520 5761856
21:28:52 99175 8192000000 10297276768 10761420800 1376129312 14.382 98 9 18 0 1389372544 11198352 2044880
21:32:04 18155 8192000000 10298786384 10761756672 1403683344 14.628 100 19 38 1 1416443504 8483536 4276624
21:33:27 57970 8192000000 10273647152 10737049600 1351224080 14.158 97 15 30 0 1362183936 7642064 3317792
21:35:56 35171 8192000000 10281604112 10744569856 1370732224 14.334 99 15 30 0 1387369392 13330432 3306736
21:37:40 27108 8192000000 10284188192 10722758656 1347518416 14.125 98 41 82 0 1367039968 10293648 9227904
21:39:08 43798 8192000000 10272532304 10725699584 1319765712 13.875 96 26 51 2 1341010480 15429664 5815104
21:40:57 45165 8192000000 10250660608 10712821760 1313166144 13.815 96 18 36 1 1334607408 17343008 4098256
21:42:50 34846 8192000000 10291273488 10737524736 1371494224 14.340 100 22 45 1 1390125744 14004544 4626976
21:44:34 47977 8192000000 10264636544 10721882112 1321659744 13.892 96 22 44 0 1347647120 21273600 4713776
21:46:22 62520 8192000000 10286076992 10730356736 1346035552 14.112 98 11 22 0 1359953680 11498512 2419616
21:48:26 79310 8192000000 10288230896 10735071232 1318247936 13.861 96 25 51 1 1336048240 11836192 5964112
21:50:46 79226 8192000000 10283879424 10755579904 1363105808 14.265 99 11 22 2 1383627136 17710336 2810992
21:53:55 16128 8192000000 10283291312 10723082240 1357163200 14.212 99 50 100 1 1378732096 9770464 11798432
21:55:14 20796 8192000000 10279627984 10715275264 1347644880 14.126 98 44 88 2 1370861664 12885152 10331632
21:56:38 72493 8192000000 10262198688 10727608320 1363192400 14.266 100 15 30 0 1382782160 16304992 3284768
21:58:54 109376 8192000000 10285517072 10747682816 1375359856 14.375 100 13 26 1 1394681664 16345296 2976512
22:01:43 115762 8192000000 10277679808 10727866368 1321385664 13.889 96 12 24 1 1341761216 17578288 2797264
22:04:44 92196 8192000000 10283476128 10728005632 1338728640 14.046 97 18 35 1 1355925760 13144672 4052448
22:07:23 51481 8192000000 10302844400 10748076032 1387752464 14.486 100 28 56 2 1407192784 12382384 7057936
22:09:25 99801 8192000000 10311472544 10770628608 1388802864 14.495 99 15 30 0 1404698000 12902272 2992864
22:12:05 22804 8192000000 10339508496 10778251264 1405009760 14.640 100 43 86 1 1422707616 8084144 9613712
22:13:35 131742 8192000000 10304879792 10777600000 1373659632 14.360 98 18 36 0 1396089920 18503520 3926768
22:16:49 41752 8192000000 10298637760 10764668928 1385432208 14.465 99 22 44 2 1401947632 11802208 4713216
22:18:33 93648 8192000000 10285521520 10758569984 1387424480 14.483 99 11 22 0 1403494304 13796976 2272848
22:21:10 79527 8192000000 10287275424 10740518912 1375268912 14.374 99 24 48 0 1392180480 11398368 5513200
22:23:36 66178 8192000000 10292037888 10741620736 1345161376 14.104 97 8 15 0 1358599760 11877792 1560592
22:25:43 61301 8192000000 10269682304 10733068288 1318287648 13.861 95 27 54 0 1335174064 10279600 6606816
22:27:52 64246 8192000000 10263134816 10707484672 1303079024 13.723 95 16 32 0 1316307056 9381568 3846464
22:29:56 69144 8192000000 10300293648 10742763520 1359929536 14.237 99 25 50 2 1378372208 12986608 5456064
22:32:05 39543 8192000000 10268081376 10710904832 1298614144 13.683 95 29 58 0 1315273088 10421600 6237344
22:33:47 18817 8192000000 10277512720 10713944064 1331526688 13.981 98 41 82 0 1350443248 10257392 8659168
22:35:06 104571 8192000000 10257413472 10714533888 1294984448 13.650 95 11 22 1 1308165952 10639888 2541616
22:37:51 34559 8192000000 9143905344 9390395392 311321136 3.661 23 7 12 1 314970256 2170560 1478560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-04-2022 22:39:41 to 17-04-2022 22:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-04-2022 22:39:57 to 17-04-2022 22:39:59.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242889
MON$OLDEST_ACTIVE               242890
MON$OLDEST_SNAPSHOT             242890
MON$NEXT_TRANSACTION            242890
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-03 16:41:55.3470
MON$PAGES                       2023808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:588: 
:589: mscs-test-161.budzdorov.ru    Sun Apr 17 22:38:29 2022
:590:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:591: 
:592: 
:593: mscs-test-161.budzdorov.ru    Sun Apr 17 22:38:29 2022
:594:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:595: 
:596: 
:597: mscs-test-161.budzdorov.ru    Sun Apr 17 22:38:29 2022
:598:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:599: 
--- end of diff output ---

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

17.04.22 22:40:00. There are 3 logs in /var/tmp/logs-oltp30 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-17 22:40:02 No severe PSQL-related problems occured
17.04.22 22:40:04. End of report.