Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2022 01: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 16783896 kB
MemAvailable 31572596 kB
Buffers 0 kB
Cached 14908888 kB
SwapCached 2188 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-03-31 01:38:10
DTS_END                         2022-03-31 01:38:10.2620
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/39842
TRN_ID                          244737
ATT_ID                          661
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: 9089.53 22911 1090743
customer order: creation 174.34 346 20921
customer order: refuse 15.26 344 1831
order to supplier: creation 47.55 1317 5706
order to supplier: removal 3.51 92034 421
invoice (draft): creation 45.65 3389 5478
invoice (draft): removal 50.50 743 6060
invoice accept: apply 42.44 19060 5093
invoice accept: cancel 45.44 783 5453
customer reserve: creation 3883.76 189 466051
customer reserve: removal 4490.21 56 538825
realization accept: apply 85.09 183 10211
realization accept: cancel 71.33 107 8559
payment from customer: creation 61.89 114 7427
payment from customer: removal 5.73 78 688
payment to supplier: creation 49.84 131 5981
payment to supplier: removal 3.53 75 424
service: total inventory turnovers 5.31 1212 637
service: total monetary turnovers 7.20 62 864
service: aggregate perf. data 0.91 21102 109
service: refresh index statistics 0.03 316885 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-03-2022 01:38:59 to 31-03-2022 01:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6246
TEST_TIME 2 5967
TEST_TIME 3 6220
TEST_TIME 4 5658
TEST_TIME 5 5603
TEST_TIME 6 5999
TEST_TIME 7 5906
TEST_TIME 8 7325
TEST_TIME 9 7844
TEST_TIME 10 8255
TEST_TIME 11 8229
TEST_TIME 12 8146
TEST_TIME 13 8783
TEST_TIME 14 8756
TEST_TIME 15 8694
TEST_TIME 16 8558
TEST_TIME 17 8583
TEST_TIME 18 8821
TEST_TIME 19 9161
TEST_TIME 20 9367
TEST_TIME 21 9403
TEST_TIME 22 9559
TEST_TIME 23 9762
TEST_TIME 24 10184
TEST_TIME 25 10309
TEST_TIME 26 10182
TEST_TIME 27 10546
TEST_TIME 28 10575
TEST_TIME 29 10665
TEST_TIME 30 10700
TEST_TIME 31 10406
TEST_TIME 32 10341
TEST_TIME 33 10118
TEST_TIME 34 10207
TEST_TIME 35 10115
TEST_TIME 36 9924
TEST_TIME 37 9833
TEST_TIME 38 9786
TEST_TIME 39 9737
TEST_TIME 40 9637
TEST_TIME 41 9860
TEST_TIME 42 9776
TEST_TIME 43 9689
TEST_TIME 44 9597
TEST_TIME 45 9448
TEST_TIME 46 9469
TEST_TIME 47 9407
TEST_TIME 48 9394
TEST_TIME 49 9270
TEST_TIME 50 9211
TEST_TIME 51 9144
TEST_TIME 52 9140
TEST_TIME 53 9170
TEST_TIME 54 9084
TEST_TIME 55 9017
TEST_TIME 56 9014
TEST_TIME 57 8934
TEST_TIME 58 9061
TEST_TIME 59 9018
TEST_TIME 60 9039
TEST_TIME 61 9086
TEST_TIME 62 9194
TEST_TIME 63 9156
TEST_TIME 64 9235
TEST_TIME 65 9182
TEST_TIME 66 9203
TEST_TIME 67 9345
TEST_TIME 68 9293
TEST_TIME 69 9324
TEST_TIME 70 9381
TEST_TIME 71 9392
TEST_TIME 72 9356
TEST_TIME 73 9297
TEST_TIME 74 9250
TEST_TIME 75 9286
TEST_TIME 76 9238
TEST_TIME 77 9213
TEST_TIME 78 9188
TEST_TIME 79 9161
TEST_TIME 80 9182
TEST_TIME 81 9160
TEST_TIME 82 9089
TEST_TIME 83 9011
TEST_TIME 84 8981
TEST_TIME 85 9043
TEST_TIME 86 9090
TEST_TIME 87 9061
TEST_TIME 88 9098
TEST_TIME 89 9080
TEST_TIME 90 9150
TEST_TIME 91 9161
TEST_TIME 92 9176
TEST_TIME 93 9226
TEST_TIME 94 9231
TEST_TIME 95 9241
TEST_TIME 96 9242
TEST_TIME 97 9356
TEST_TIME 98 9342
TEST_TIME 99 9368
TEST_TIME 100 9330
TEST_TIME 101 9282
TEST_TIME 102 9220
TEST_TIME 103 9182
TEST_TIME 104 9170
TEST_TIME 105 9101
TEST_TIME 106 9122
TEST_TIME 107 9170
TEST_TIME 108 9124
TEST_TIME 109 9103
TEST_TIME 110 9075
TEST_TIME 111 9096
TEST_TIME 112 9080
TEST_TIME 113 9077
TEST_TIME 114 9070
TEST_TIME 115 9157
TEST_TIME 116 9128
TEST_TIME 117 9151
TEST_TIME 118 9150
TEST_TIME 119 9140
TEST_TIME 120 9100

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-03-2022 01:39:03 to 31-03-2022 01: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 1522965 1522965 0 0.00 0 21146 6 0 0
doc_list_biud 1522963 1522963 0 0.00 0 83589 26 0 0
sp_add_invoice_to_stock 5250 5093 157 2.99 40 206352 19060 0 157
sp_cancel_adding_invoice 5453 5453 0 0.00 5 69836 784 0 0
sp_cancel_client_order 1831 1831 0 0.00 4 14120 344 0 0
sp_cancel_customer_reserve 538825 538825 0 0.00 1 83592 57 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 5397 78 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 3107 76 0 0
sp_cancel_supplier_invoice 6081 6060 21 0.34 1 13574 744 0 21
sp_cancel_supplier_order 421 421 0 0.00 7 873489 92035 0 0
sp_cancel_write_off 8559 8559 0 0.00 1 7999 108 0 0
sp_client_order 20921 20921 0 0.00 3 58700 346 0 0
sp_customer_reserve 466051 466051 0 0.00 0 35333 190 0 0
sp_fill_shopping_cart 32097 32097 0 0.00 1 18901 40 0 0
sp_fill_shopping_cart_clo_res 466037 466037 0 0.00 0 16035 15 0 0
sp_get_clo_for_invoice 5076 5076 0 0.00 0 31034 308 0 0
sp_kill_cost_storno 15124 15124 0 0.00 0 21134 60 0 0
sp_kill_qstorno_ret_qs2qd 1092690 1092690 0 0.00 0 83587 30 0 0
sp_kill_qty_storno 576433 576433 0 0.00 0 83589 67 0 0
sp_lock_selected_doc 21144 21144 0 0.00 0 8572 5 0 0
sp_make_cost_storno 28692 28692 0 0.00 0 19476 55 0 0
sp_multiply_rows_for_pdistr 28692 28692 0 0.00 0 9198 28 0 0
sp_multiply_rows_for_qdistr 20921 20921 0 0.00 0 58693 144 0 0
sp_pay_from_customer 7427 7427 0 0.00 4 20634 115 0 0
sp_pay_to_supplier 5981 5981 0 0.00 5 18931 132 0 0
sp_payment_common 13408 13408 0 0.00 1 20630 33 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 14115 312 0 0
sp_qd_handle_on_invoice_upd_sts 10526 10526 0 0.00 0 21033 345 0 0
sp_qd_handle_on_reserve_upd_sts 10211 10211 0 0.00 0 12805 58 0 0
sp_reserve_write_off 10211 10211 0 0.00 6 16163 184 0 0
sp_supplier_invoice 5478 5478 0 0.00 6 79917 3390 0 0
sp_supplier_order 5706 5706 0 0.00 5 76903 1317 0 0
srv_aggregate_perf_data 109 109 0 0.00 3293 286961 21102 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1358 122663 45401 0 0
srv_find_qd_qs_mism 1042446 1042446 0 0.00 0 20742 5 0 0
srv_make_invnt_saldo 637 637 0 0.00 29 119870 1212 0 0
srv_make_money_saldo 864 864 0 0.00 1 30642 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 179344 554390 316885 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5093 5093 0 0.00 0 28359 211 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 11106 152 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 1458 14 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 5885 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 611 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 511 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 483 14 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 23272 1648 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 1686 9 0 0
t$perf-norm:sp_client_order 20921 20921 0 0.00 0 18474 20 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 683 3 0 0
t$perf-norm:sp_pay_from_customer 7427 7427 0 0.00 0 5434 10 0 0
t$perf-norm:sp_pay_to_supplier 5981 5981 0 0.00 0 9028 11 0 0
t$perf-norm:sp_reserve_write_off 10211 10211 0 0.00 0 19925 15 0 0
t$perf-norm:sp_supplier_invoice 5478 5478 0 0.00 0 17262 24 0 0
t$perf-norm:sp_supplier_order 5706 5706 0 0.00 0 10408 19 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 407 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 68 2 0 0
t$perf-norm:srv_make_invnt_saldo 637 637 0 0.00 0 7403 29 0 0
t$perf-norm:srv_make_money_saldo 864 864 0 0.00 0 963 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 6 0 0
v_add_invoice_to_stock 10460 10146 314 3.00 0 17899 25 0 314
v_all_customers 37940 37940 0 0.00 0 17 1 0 0
v_all_suppliers 30782 30782 0 0.00 0 103 1 0 0
v_all_wares 41840 41840 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 568 6 0 0
v_cancel_client_order 3662 3662 0 0.00 0 3375 7 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 5349 14 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 7303 9 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1329 8 0 0
v_cancel_supplier_invoice 780 738 42 5.38 0 4257 25 0 42
v_cancel_supplier_order 842 842 0 0.00 0 258 3 0 0
v_cancel_write_off 2054 2054 0 0.00 0 3194 14 0 0
v_max_id_clo_ord 5706 5706 0 0.00 0 1019 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 1746 13 0 0
v_max_id_ord_sup 5471 5471 0 0.00 0 4804 3 0 0
v_max_non_paid_invoice 1767 1767 0 0.00 0 3214 8 0 0
v_max_non_paid_realizn 4123 4123 0 0.00 0 4796 5 0 0
v_min_id_clo_ord 5706 5706 0 0.00 0 3776 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 992 4 0 0
v_min_id_ord_sup 5471 5471 0 0.00 0 6398 6 0 0
v_min_non_paid_invoice 5981 5981 0 0.00 0 15026 20 0 0
v_min_non_paid_realizn 7427 7427 0 0.00 0 20627 22 0 0
v_our_firm 10508 10508 0 0.00 0 21 1 0 0
v_reserve_write_off 20422 20422 0 0.00 0 16068 7 0 0
x_lock_depdocs_on_canc_invoice 11144 11144 0 0.00 0 15688 115 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 12161 435 0 0
x_make_qstorno_1000_1200 5706 5706 0 0.00 1 76879 1228 0 0
x_make_qstorno_1200_2000 5478 5478 0 0.00 2 72626 3252 0 0
x_make_qstorno_2100_3300 466051 466051 0 0.00 0 35333 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2022 01:39:08 to 31-03-2022 01: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
23:08:14 3 8192000000 8800721504 8812724224 6871728 0.083 2 2 3 0 7014176 65600 76848
23:09:14 22854 8192000000 9909105312 10222313472 985850368 10.741 100 86 172 0 1014667200 6702976 22113856
23:10:47 17027 8192000000 9954636400 10277445632 1038412736 11.249 100 92 184 0 1070845936 8953776 23479424
23:12:11 13291 8192000000 9976305984 10307391488 1061216800 11.468 100 89 178 0 1094092848 10522000 22354048
23:13:29 18007 8192000000 10021161072 10368495616 1108287072 11.916 100 74 148 0 1136601216 10458816 17855328
23:14:54 10383 8192000000 10089392928 10448805888 1169703552 12.494 100 76 151 0 1198296912 10841792 17751568
23:16:08 3988 8192000000 10135588944 10504937472 1218482768 12.948 100 85 170 0 1250178112 13351664 18343680
23:17:16 2362 8192000000 10167937216 10541654016 1251557872 13.253 100 88 176 2 1284687856 13982896 19147088
23:18:20 1612 8192000000 10198442000 10579623936 1282634144 13.537 100 93 187 1 1316454720 13634960 20185616
23:19:22 19503 8192000000 10206332544 10599211008 1300383808 13.699 100 57 115 1 1324076624 9957136 13735680
23:20:42 5461 8192000000 10194997392 10610106368 1313914944 13.822 100 17 34 1 1323426912 5635408 3876560
23:21:50 9194 8192000000 10205300320 10622386176 1321782176 13.893 100 16 33 1 1331163360 6005632 3375552
23:23:01 46893 8192000000 10242285904 10640039936 1326689904 13.937 100 55 108 2 1344419840 4383776 13346160
23:24:48 4552 8192000000 10226173488 10648162304 1333890496 14.002 100 35 70 0 1349175584 7681024 7604064
23:25:54 6320 8192000000 10228811328 10648969216 1334049504 14.004 100 33 63 2 1348166176 6610912 7505760
23:27:03 39851 8192000000 10234062688 10652057600 1331895280 13.984 100 54 111 0 1352257120 7777536 12584304
23:28:46 5969 8192000000 10180940944 10595278848 1274617456 13.464 98 20 40 0 1284509440 5446816 4445168
23:29:53 8144 8192000000 10140436496 10547732480 1226796576 13.024 97 27 52 3 1239226848 6650064 5780208
23:31:02 9735 8192000000 10142965904 10550706176 1253181856 13.267 100 17 34 1 1262912048 5771088 3959104
23:32:13 18329 8192000000 10144294192 10555637760 1232240864 13.075 97 17 34 0 1242208448 5574000 4393584
23:33:31 9576 8192000000 10162274432 10571059200 1264738496 13.373 99 24 49 0 1280268384 10157600 5372288
23:34:41 12179 8192000000 10186105248 10600267776 1292587808 13.628 100 21 42 1 1303599392 5417104 5594480
23:35:54 55858 8192000000 10207224208 10617143296 1304301280 13.734 100 24 47 2 1314158544 4878496 4978768
23:38:01 8259 8192000000 10219377712 10634444800 1318877888 13.867 100 40 78 3 1333449488 5483600 9088000
23:39:16 24097 8192000000 10254777248 10664415232 1347207216 14.122 100 52 104 3 1367228960 8711872 11309872
23:40:57 11236 8192000000 10270008176 10692489216 1366211776 14.293 100 40 79 2 1387272192 10455168 10605248
23:42:16 6286 8192000000 10306192560 10719866880 1384289648 14.455 100 65 128 2 1413880848 14075968 15515232
23:43:25 10304 8192000000 10277560144 10698833920 1366417680 14.295 99 49 98 0 1393592256 15996016 11178560
23:44:39 1823 8192000000 10315363216 10732109824 1390807600 14.513 100 73 145 0 1423369408 16513776 16048032
23:45:50 12364 8192000000 10313152640 10736144384 1388961776 14.497 100 55 109 2 1424665024 22555216 13148032
23:47:06 1357 8192000000 10331805920 10742796288 1385993360 14.470 100 92 184 1 1434171072 27426352 20751360
23:48:13 2742 8192000000 10311105424 10721980416 1375814640 14.379 99 80 160 1 1416316688 21333984 19168064
23:49:19 4103 8192000000 10309216864 10723536896 1371150672 14.337 99 78 152 2 1413721072 23202736 19367664
23:50:30 11581 8192000000 10318045552 10727698432 1365509664 14.287 99 75 150 2 1411234432 26919392 18805376
23:51:46 1492 8192000000 10299335712 10708631552 1340643008 14.063 98 80 157 2 1384778960 24237408 19898544
23:52:51 2399 8192000000 10289506032 10700304384 1346719328 14.118 99 66 132 0 1385955040 23306000 15929712
23:54:02 20893 8192000000 10301116544 10717491200 1362248480 14.258 100 47 94 2 1405403104 30754064 12400560
23:55:38 14329 8192000000 10294450240 10712264704 1348750736 14.136 99 52 104 1 1394316032 31894240 13671056
23:57:02 3549 8192000000 10314696160 10720460800 1362174304 14.257 99 70 138 0 1414271056 34268272 17828480
23:58:11 17948 8192000000 10282095424 10699296768 1334121472 14.004 99 36 70 2 1375021232 30792048 10107712
23:59:36 4527 8192000000 10288502304 10701139968 1343052704 14.085 99 51 102 1 1381057104 25071840 12932560
00:00:45 5385 8192000000 10279266272 10697441280 1334902176 14.011 99 48 97 3 1378060544 29838112 13320256
00:01:59 5333 8192000000 10285378544 10701352960 1347851232 14.128 100 46 92 0 1388876208 28546304 12478672
00:03:13 12290 8192000000 10296326032 10727354368 1353982992 14.183 99 33 64 4 1392193152 29634000 8576160
00:04:41 18305 8192000000 10291520976 10722828288 1350729904 14.154 99 32 64 1 1388809360 29411200 8668256
00:06:05 28842 8192000000 10280442736 10720972800 1359737184 14.235 100 24 48 0 1390626752 24295280 6594288
00:07:38 14539 8192000000 10285410480 10714923008 1354588592 14.189 99 49 97 1 1381164352 14691808 11883952
00:08:54 60613 8192000000 10292344176 10732593152 1372392080 14.348 100 34 68 3 1393992320 12471504 9128736
00:11:09 15553 8192000000 10283420576 10736644096 1380464960 14.421 100 6 12 1 1396316288 14514432 1336896
00:12:28 81905 8192000000 10289960800 10739343360 1373863248 14.362 99 21 43 1 1392878016 15085008 3929760
00:14:51 48594 8192000000 10305757168 10754965504 1394503776 14.546 100 31 63 0 1416183552 14421360 7258416
00:16:41 53998 8192000000 10323778928 10776289280 1399042768 14.586 100 12 24 0 1429947248 28281680 2622800
00:18:45 85414 8192000000 10315749408 10773901312 1401390992 14.607 100 17 34 1 1420052448 14810656 3850800
00:21:11 78835 8192000000 10314680896 10768560128 1405882976 14.647 100 30 59 2 1424756720 11787136 7086608
00:23:34 67938 8192000000 10313929648 10767613952 1409682848 14.681 100 31 62 0 1426618048 9937248 6997952
00:25:51 35754 8192000000 10303706384 10761338880 1405940384 14.648 100 27 54 0 1420360864 8197264 6223216
00:27:41 25400 8192000000 10310813136 10766925824 1408105536 14.667 100 13 26 1 1418586384 6702992 3777856
00:29:07 21990 8192000000 10315717296 10772213760 1413722256 14.717 100 28 56 1 1431991824 11023120 7246448
00:30:30 88970 8192000000 10302154128 10774458368 1395763200 14.557 99 11 22 1 1411145168 12758896 2623072
00:33:43 78312 8192000000 10286747984 10757025792 1369168512 14.320 98 11 22 0 1378701344 7233632 2299200
00:36:52 53406 8192000000 10306346704 10758873088 1337881120 14.038 95 14 28 0 1351194336 10279536 3033680
00:38:50 110792 8192000000 10279408464 10751246336 1290757744 13.611 92 8 16 0 1301934976 9780656 1396576
00:41:43 59521 8192000000 10282606112 10745298944 1361870576 14.254 98 14 28 1 1376464240 11582736 3010928
00:43:48 100378 8192000000 10278751152 10743463936 1345534480 14.107 97 23 46 0 1364953792 14413280 5006032
00:46:29 30336 8192000000 10301227792 10746609664 1367764144 14.307 99 46 92 0 1388550240 10677728 10108368
00:48:06 94830 8192000000 10262348256 10710806528 1283102832 13.541 93 9 18 0 1303801664 18609776 2089056
00:50:41 48297 8192000000 10271798112 10704556032 1310831808 13.794 96 36 72 3 1332101136 12483136 8786192
00:52:34 91459 8192000000 10245172016 10696450048 1325697200 13.928 98 37 74 0 1349915408 15453968 8764240
00:56:28 64149 8192000000 10252226320 10704392192 1315526176 13.836 96 10 18 1 1327876944 9748688 2602080
00:58:33 54528 8192000000 10264917488 10716356608 1362460544 14.259 100 25 50 0 1376620016 8178016 5981456
01:00:29 65335 8192000000 10278404624 10727182336 1359883136 14.236 99 23 46 0 1373082816 7941520 5258160
01:02:34 31190 8192000000 10269529616 10728128512 1369270624 14.321 100 16 32 0 1388506896 15464864 3771408
01:04:06 79444 8192000000 10260466736 10714116096 1321299600 13.888 96 10 20 0 1331863680 8292320 2271760
01:06:26 54145 8192000000 10270093120 10717327360 1361548080 14.251 100 26 52 0 1375862864 8390048 5924736
01:08:27 57593 8192000000 10288353904 10743779328 1385650400 14.467 100 16 32 0 1398810576 9617184 3542992
01:10:25 75169 8192000000 10284780048 10743369728 1374383520 14.366 99 16 32 1 1389035488 10973584 3678384
01:12:46 57070 8192000000 10309624480 10760286208 1388966640 14.497 100 14 28 0 1403809760 11591376 3251744
01:14:56 41966 8192000000 10313888320 10766950400 1399679088 14.592 100 26 52 1 1422427200 16869472 5878640
01:16:41 91076 8192000000 10296767392 10746568704 1328859840 13.957 95 17 34 2 1353137536 19958320 4319376
01:19:27 93799 8192000000 10302259632 10754715648 1376704480 14.387 99 20 40 1 1387557312 6586272 4266560
01:22:01 68556 8192000000 10298465920 10763616256 1369339488 14.321 98 11 22 1 1393584656 21646608 2598560
01:24:10 98231 8192000000 10291742992 10765963264 1379705616 14.414 99 15 29 0 1404749472 21746544 3297312
01:26:59 61016 8192000000 10283456176 10748518400 1363037024 14.265 98 13 26 0 1374900336 8442064 3421248
01:29:18 122663 8192000000 10284513264 10750373888 1303736288 13.729 94 12 24 0 1314898048 8971232 2190528
01:32:24 76278 8192000000 10270542032 10733436928 1316009952 13.841 95 24 48 2 1334761744 13350176 5401616
01:35:16 62009 8192000000 10278485888 10732408832 1347496928 14.125 98 11 22 1 1361227648 11506976 2223744
01:37:18 37943 8192000000 10272216768 10716844032 1337886016 14.038 98 33 66 0 1356664496 11640064 7138416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-03-2022 01:39:14 to 31-03-2022 01:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2022 01:39:29 to 31-03-2022 01:39:31.

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          242131
MON$OLDEST_ACTIVE               244887
MON$OLDEST_SNAPSHOT             244887
MON$NEXT_TRANSACTION            244887
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                       2021248
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:745: 
:746: mscs-test-161.budzdorov.ru    Thu Mar 31 01:38:11 2022
:747:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:748: 
--- end of diff output ---

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

31.03.22 01:39:32. 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-03-31 01:39:35 No severe PSQL-related problems occured
31.03.22 01:39:36. End of report.