Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.11.2021 01:40
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 16687360 kB
MemAvailable 31628488 kB
Buffers 0 kB
Cached 15020428 kB
SwapCached 0 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.8
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 2021-11-18 01:37:53
DTS_END                         2021-11-18 01:37:53.1390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55092
TRN_ID                          241406
ATT_ID                          642
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: 9419.58 18853 1130349
customer order: creation 175.43 337 21052
customer order: refuse 15.68 313 1881
order to supplier: creation 45.70 1195 5484
order to supplier: removal 3.38 102524 405
invoice (draft): creation 46.03 3057 5523
invoice (draft): removal 54.37 776 6524
invoice accept: apply 42.65 16261 5118
invoice accept: cancel 49.53 707 5944
customer reserve: creation 3898.83 162 467860
customer reserve: removal 4776.76 56 573211
realization accept: apply 87.10 178 10452
realization accept: cancel 93.17 98 11180
payment from customer: creation 59.74 130 7169
payment from customer: removal 5.65 74 678
payment to supplier: creation 50.05 129 6006
payment to supplier: removal 3.84 86 461
service: total inventory turnovers 4.58 1232 550
service: total monetary turnovers 6.17 56 740
service: aggregate perf. data 0.89 18028 107
service: refresh index statistics 0.03 231653 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-11-2021 01:40:22 to 18-11-2021 01:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3329
TEST_TIME 2 3942
TEST_TIME 3 4866
TEST_TIME 4 4007
TEST_TIME 5 4502
TEST_TIME 6 4599
TEST_TIME 7 4691
TEST_TIME 8 4779
TEST_TIME 9 4817
TEST_TIME 10 4930
TEST_TIME 11 4989
TEST_TIME 12 5269
TEST_TIME 13 5372
TEST_TIME 14 5381
TEST_TIME 15 5820
TEST_TIME 16 6009
TEST_TIME 17 6093
TEST_TIME 18 6286
TEST_TIME 19 6631
TEST_TIME 20 7210
TEST_TIME 21 7335
TEST_TIME 22 7348
TEST_TIME 23 7886
TEST_TIME 24 8643
TEST_TIME 25 9030
TEST_TIME 26 9366
TEST_TIME 27 10053
TEST_TIME 28 10457
TEST_TIME 29 10657
TEST_TIME 30 10464
TEST_TIME 31 10329
TEST_TIME 32 10155
TEST_TIME 33 10419
TEST_TIME 34 10320
TEST_TIME 35 10196
TEST_TIME 36 10189
TEST_TIME 37 9939
TEST_TIME 38 9914
TEST_TIME 39 9979
TEST_TIME 40 10032
TEST_TIME 41 9854
TEST_TIME 42 9856
TEST_TIME 43 9751
TEST_TIME 44 9931
TEST_TIME 45 9887
TEST_TIME 46 9839
TEST_TIME 47 9741
TEST_TIME 48 9751
TEST_TIME 49 9677
TEST_TIME 50 9648
TEST_TIME 51 9603
TEST_TIME 52 9619
TEST_TIME 53 9538
TEST_TIME 54 9481
TEST_TIME 55 9448
TEST_TIME 56 9411
TEST_TIME 57 9415
TEST_TIME 58 9436
TEST_TIME 59 9528
TEST_TIME 60 9464
TEST_TIME 61 9511
TEST_TIME 62 9509
TEST_TIME 63 9646
TEST_TIME 64 9848
TEST_TIME 65 9830
TEST_TIME 66 9808
TEST_TIME 67 9782
TEST_TIME 68 9912
TEST_TIME 69 9901
TEST_TIME 70 9774
TEST_TIME 71 9718
TEST_TIME 72 9666
TEST_TIME 73 9668
TEST_TIME 74 9673
TEST_TIME 75 9597
TEST_TIME 76 9580
TEST_TIME 77 9565
TEST_TIME 78 9535
TEST_TIME 79 9562
TEST_TIME 80 9533
TEST_TIME 81 9504
TEST_TIME 82 9492
TEST_TIME 83 9554
TEST_TIME 84 9545
TEST_TIME 85 9529
TEST_TIME 86 9624
TEST_TIME 87 9585
TEST_TIME 88 9557
TEST_TIME 89 9649
TEST_TIME 90 9695
TEST_TIME 91 9793
TEST_TIME 92 9726
TEST_TIME 93 9635
TEST_TIME 94 9581
TEST_TIME 95 9576
TEST_TIME 96 9529
TEST_TIME 97 9497
TEST_TIME 98 9551
TEST_TIME 99 9512
TEST_TIME 100 9480
TEST_TIME 101 9515
TEST_TIME 102 9507
TEST_TIME 103 9470
TEST_TIME 104 9480
TEST_TIME 105 9553
TEST_TIME 106 9538
TEST_TIME 107 9563
TEST_TIME 108 9564
TEST_TIME 109 9637
TEST_TIME 110 9649
TEST_TIME 111 9654
TEST_TIME 112 9642
TEST_TIME 113 9571
TEST_TIME 114 9540
TEST_TIME 115 9507
TEST_TIME 116 9514
TEST_TIME 117 9471
TEST_TIME 118 9453
TEST_TIME 119 9463
TEST_TIME 120 9428

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-11-2021 01:40:25 to 18-11-2021 01:40:29.

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 1564356 1564356 0 0.00 0 18677 6 0 0
doc_list_biud 1564356 1564356 0 0.00 0 30071 26 0 0
sp_add_invoice_to_stock 5243 5118 125 2.38 52 175861 16262 0 125
sp_cancel_adding_invoice 5944 5944 0 0.00 5 68460 708 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 12071 314 0 0
sp_cancel_customer_reserve 573211 573211 0 0.00 1 20864 56 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 5989 75 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 7260 87 0 0
sp_cancel_supplier_invoice 6538 6524 14 0.21 1 16064 776 0 14
sp_cancel_supplier_order 405 405 0 0.00 6 1267892 102524 0 0
sp_cancel_write_off 11180 11180 0 0.00 1 17191 98 0 0
sp_client_order 21052 21052 0 0.00 3 21544 338 0 0
sp_customer_reserve 467860 467860 0 0.00 0 79876 162 0 0
sp_fill_shopping_cart 32048 32048 0 0.00 1 19433 34 0 0
sp_fill_shopping_cart_clo_res 467849 467849 0 0.00 0 16918 13 0 0
sp_get_clo_for_invoice 5106 5106 0 0.00 0 28972 313 0 0
sp_kill_cost_storno 18263 18263 0 0.00 0 13457 52 0 0
sp_kill_qstorno_ret_qs2qd 1164531 1164531 0 0.00 0 20858 31 0 0
sp_kill_qty_storno 614703 614703 0 0.00 0 30070 66 0 0
sp_lock_selected_doc 21600 21600 0 0.00 0 7708 4 0 0
sp_make_cost_storno 28733 28733 0 0.00 0 18668 55 0 0
sp_multiply_rows_for_pdistr 28733 28733 0 0.00 0 14410 30 0 0
sp_multiply_rows_for_qdistr 21052 21052 0 0.00 0 14023 142 0 0
sp_pay_from_customer 7169 7169 0 0.00 4 20733 130 0 0
sp_pay_to_supplier 6006 6006 0 0.00 3 18684 130 0 0
sp_payment_common 13175 13175 0 0.00 1 17103 36 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 12062 289 0 0
sp_qd_handle_on_invoice_upd_sts 11050 11050 0 0.00 0 30069 309 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 19459 54 0 0
sp_reserve_write_off 10452 10452 0 0.00 6 19506 178 0 0
sp_supplier_invoice 5523 5523 0 0.00 6 66930 3057 0 0
sp_supplier_order 5484 5484 0 0.00 5 68803 1196 0 0
srv_aggregate_perf_data 107 107 0 0.00 2713 355251 18029 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2331 164756 51588 0 0
srv_find_qd_qs_mism 1082383 1082383 0 0.00 0 15482 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 43 90500 1233 0 0
srv_make_money_saldo 740 740 0 0.00 1 13854 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 90166 360267 231654 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5119 5119 0 0.00 0 23878 199 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 1901 78 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 4629 23 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 830 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 4154 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 4898 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 817 14 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25446 1779 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 11217 19 0 0
t$perf-norm:sp_client_order 21052 21052 0 0.00 0 16078 21 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 1303 5 0 0
t$perf-norm:sp_pay_from_customer 7169 7169 0 0.00 0 7803 15 0 0
t$perf-norm:sp_pay_to_supplier 6006 6006 0 0.00 0 14231 18 0 0
t$perf-norm:sp_reserve_write_off 10452 10452 0 0.00 0 20296 18 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 6219 14 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 5569 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 388 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 5478 33 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 1171 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10458 10208 250 2.39 0 6971 22 0 250
v_all_customers 37932 37932 0 0.00 0 19 0 0 0
v_all_suppliers 30516 30516 0 0.00 0 146 1 0 0
v_all_wares 42104 42104 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 4036 15 0 0
v_cancel_client_order 3762 3762 0 0.00 0 4594 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 5980 12 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 6818 17 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 6947 14 0 0
v_cancel_supplier_invoice 844 816 28 3.31 0 2585 15 0 28
v_cancel_supplier_order 810 810 0 0.00 0 6058 16 0 0
v_cancel_write_off 2110 2110 0 0.00 0 5674 17 0 0
v_max_id_clo_ord 5484 5484 0 0.00 0 190 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 2641 17 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 5593 6 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 526 3 0 0
v_max_non_paid_realizn 3909 3909 0 0.00 0 7190 6 0 0
v_min_id_clo_ord 5484 5484 0 0.00 0 2437 4 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 3065 13 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 15437 9 0 0
v_min_non_paid_invoice 6006 6006 0 0.00 0 12008 25 0 0
v_min_non_paid_realizn 7169 7169 0 0.00 0 17100 31 0 0
v_our_firm 10692 10692 0 0.00 0 34 1 0 0
v_reserve_write_off 20904 20904 0 0.00 0 7015 8 0 0
x_lock_depdocs_on_canc_invoice 12060 12060 0 0.00 0 15371 116 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 7840 297 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 2 68741 1127 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 2 57604 2929 0 0
x_make_qstorno_2100_3300 467860 467860 0 0.00 0 79874 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-11-2021 01:40:30 to 18-11-2021 01:40:34.

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:07:56 3 8192000000 8800648224 8812466176 6708832 0.081 2 2 2 0 6813136 61520 42784
23:08:58 22308 8192000000 9898244368 10209693696 982914128 10.713 100 89 180 0 1012312496 5825744 23572624
23:10:27 30151 8192000000 9959592704 10282438656 1038604032 11.251 100 92 184 0 1070634144 8746208 23283904
23:12:04 9794 8192000000 9983987248 10317885440 1069175616 11.544 100 77 154 0 1097732160 9723120 18833424
23:13:19 15166 8192000000 10046418160 10394296320 1125149552 12.076 100 79 159 0 1155279024 11618352 18511120
23:14:43 7760 8192000000 10077824480 10440343552 1169951856 12.496 100 80 160 0 1200322592 12084640 18286096
23:15:59 6232 8192000000 10126727808 10500239360 1216188528 12.926 100 86 169 3 1248992064 13660912 19142624
23:17:07 3393 8192000000 10167872304 10544742400 1253211072 13.268 100 82 163 1 1282771120 11948096 17611952
23:18:13 2132 8192000000 10191538576 10570588160 1275380464 13.471 100 88 176 1 1308978688 14426224 19172000
23:19:16 3190 8192000000 10216764368 10598502400 1297798304 13.675 100 92 184 0 1331574304 12971504 20804496
23:20:22 13511 8192000000 10200139360 10602201088 1302231520 13.716 100 37 74 1 1318387920 6511856 9644544
23:21:37 3086 8192000000 10216709104 10618478592 1314263792 13.825 100 64 129 1 1337558000 7714592 15579616
23:22:41 6408 8192000000 10211142880 10627772416 1322707728 13.901 100 30 58 2 1336944464 7277088 6959648
23:23:52 68600 8192000000 10223649872 10638766080 1325698704 13.928 100 36 72 2 1339876864 5509040 8669120
23:26:03 3729 8192000000 10229437696 10650435584 1332660864 13.991 100 35 70 1 1347462160 6383888 8417408
23:27:08 4735 8192000000 10224249152 10651140096 1331642944 13.982 100 28 56 1 1344672736 7203136 5826656
23:28:18 16165 8192000000 10207353920 10617376768 1292394448 13.626 98 23 46 1 1307858496 10588208 4875840
23:30:10 5511 8192000000 10180672832 10580385792 1246743504 13.208 97 35 70 1 1260310768 4903328 8663936
23:31:17 11525 8192000000 10155637552 10557296640 1230829120 13.062 97 26 52 2 1242885984 6276752 5780112
23:32:29 7274 8192000000 10145107232 10541592576 1242782480 13.172 99 15 30 0 1251543600 5279840 3481280
23:33:38 79300 8192000000 10155221600 10562887680 1232593744 13.078 97 29 57 1 1244726512 5473456 6659312
23:35:58 8804 8192000000 10182734688 10594279424 1287789232 13.584 100 31 62 0 1304649936 9363824 7496880
23:37:08 11796 8192000000 10208671536 10623565824 1315916096 13.840 100 24 48 1 1326443408 4506432 6020880
23:38:28 13960 8192000000 10242233008 10649239552 1335348848 14.015 100 41 83 1 1351755664 7296640 9110176
23:39:52 103649 8192000000 10271963088 10682327040 1354170464 14.185 100 29 58 0 1368759408 8264512 6324432
23:42:42 6698 8192000000 10281674320 10700955648 1370749040 14.334 100 61 120 3 1398497728 14373232 13375456
23:43:55 32211 8192000000 10297750160 10722201600 1380620448 14.422 100 47 94 2 1411887168 19819120 11447600
23:46:08 12193 8192000000 10329389760 10757746688 1403590704 14.627 100 58 115 1 1445388720 27940736 13857280
23:47:36 4478 8192000000 10359348672 10779287552 1418460176 14.759 100 58 115 2 1463270496 31435664 13374656
23:48:51 2330 8192000000 10376296560 10800181248 1430946464 14.870 100 71 142 2 1484239104 35761344 17531296
23:49:56 5124 8192000000 10378249984 10803073024 1431832256 14.877 100 64 129 1 1484716704 37154672 15729776
23:51:06 3517 8192000000 10388958560 10816544768 1432082688 14.880 99 67 134 0 1489014624 39862112 17069824
23:52:16 5839 8192000000 10392476192 10808098816 1427889552 14.843 100 81 157 0 1495083712 47473360 19720800
23:53:26 2731 8192000000 10398482784 10816413696 1426922800 14.834 99 86 172 1 1494659392 46339664 21396928
23:54:32 7996 8192000000 10401411904 10823286784 1436388304 14.918 100 84 167 1 1505895632 48516640 20990688
23:55:51 2813 8192000000 10370617904 10787438592 1400369600 14.598 99 73 146 2 1477015616 56745136 19900880
23:57:02 4607 8192000000 10380963312 10802610176 1415763712 14.735 100 71 138 1 1489436528 55352960 18319856
23:58:10 12916 8192000000 10325094464 10748354560 1354575264 14.189 99 45 91 1 1423272304 55884880 12812160
23:59:24 17281 8192000000 10308552560 10725158912 1303691744 13.729 96 37 74 2 1374876656 59912512 11272400
00:00:45 11234 8192000000 10305287968 10726498304 1339067888 14.049 100 37 74 0 1408171984 58353888 10750208
00:01:58 17468 8192000000 10302140112 10732019712 1346186512 14.113 100 27 52 1 1406323888 53110608 7026768
00:03:32 22788 8192000000 10282487200 10725912576 1350352144 14.151 100 21 42 0 1400400464 44173200 5875120
00:04:56 54020 8192000000 10235125584 10686287872 1279972848 13.513 95 9 18 0 1304925344 22102432 2850064
00:06:51 77853 8192000000 10260066256 10694086656 1307567040 13.764 97 7 14 0 1320491232 11992240 931952
00:09:09 60994 8192000000 10252501040 10686304256 1306198720 13.752 96 31 61 0 1323892176 11316256 6377200
00:11:15 117327 8192000000 10275853040 10718408704 1368408672 14.313 100 19 38 2 1382042128 9284832 4348624
00:14:13 84525 8192000000 10303031664 10743468032 1380293040 14.419 100 36 72 1 1397507280 8838768 8375472
00:16:40 68871 8192000000 10281395504 10739277824 1349987792 14.147 97 28 56 2 1370713136 13843040 6882304
00:19:00 42133 8192000000 10290913264 10747510784 1373934816 14.362 99 18 37 0 1389481904 11444000 4103088
00:20:42 27358 8192000000 10328021424 10762493952 1389379424 14.500 99 53 103 1 1411642656 9713600 12549632
00:22:10 24795 8192000000 10306983696 10771640320 1405392032 14.643 100 27 54 1 1423126240 11893424 5840784
00:23:35 62446 8192000000 10309380000 10776768512 1407918032 14.665 100 14 28 1 1422896928 11558416 3420480
00:25:38 47641 8192000000 10314265712 10780934144 1407721824 14.664 100 17 34 0 1430886128 18852736 4311568
00:27:32 76237 8192000000 10311832992 10768891904 1403030976 14.622 100 22 44 0 1418938672 11371072 4536624
00:29:48 85543 8192000000 10309633472 10786738176 1413234880 14.713 100 15 30 0 1425656960 9162048 3260032
00:32:19 58630 8192000000 10324263280 10784505856 1403802448 14.629 99 11 22 0 1418640608 11544288 3293872
00:34:19 28838 8192000000 10303586032 10765357056 1374521712 14.368 98 22 44 1 1395997744 16256960 5219072
00:36:18 37931 8192000000 10295455728 10751287296 1368354832 14.312 98 17 34 0 1388279552 15921200 4003520
00:37:58 79328 8192000000 10301225376 10763202560 1360706768 14.244 98 13 26 0 1393738832 29920928 3111136
00:40:31 40529 8192000000 10270282816 10725662720 1338295984 14.042 97 22 44 0 1371878064 28285808 5296272
00:42:14 62551 8192000000 10271394736 10716860416 1315391856 13.835 96 21 42 0 1331554448 11386448 4776144
00:44:33 54735 8192000000 10257767824 10702020608 1305943488 13.749 97 39 79 0 1328623904 14351904 8328512
00:46:30 105863 8192000000 10261534288 10698653696 1332905888 13.993 99 43 85 1 1354246608 11597552 9743168
00:49:19 52751 8192000000 10257205536 10715140096 1354229808 14.186 100 33 66 1 1375977504 14235712 7511984
00:51:22 67484 8192000000 10260384704 10721632256 1354957776 14.192 99 7 14 0 1368030448 11579136 1493536
00:53:29 75132 8192000000 10273834848 10737098752 1349540688 14.143 98 13 26 0 1363076992 10455056 3081248
00:55:45 119637 8192000000 10262118928 10722447360 1309113216 13.778 96 12 24 0 1324953888 12712352 3128320
00:58:46 25529 8192000000 10257802976 10711887872 1334992032 14.012 98 25 48 1 1349735488 9291328 5452128
01:00:17 59317 8192000000 10269941568 10715148288 1330634736 13.973 98 16 32 0 1344437856 9990048 3813072
01:02:17 81499 8192000000 10271854640 10733940736 1333746480 14.001 97 19 38 0 1356027072 18062848 4217744
01:04:46 59945 8192000000 10289140000 10730876928 1339401392 14.052 97 31 62 1 1357916992 11983056 6532544
01:06:46 66633 8192000000 10293259632 10757783552 1384132432 14.453 100 20 38 2 1402570016 14323696 4113888
01:09:06 164756 8192000000 10300741008 10758807552 1376350208 14.384 98 14 28 1 1390111264 10650160 3110896
01:12:54 57821 8192000000 10306049328 10778963968 1392331920 14.527 99 9 16 1 1410497264 15404656 2760688
01:14:55 119095 8192000000 10300751504 10763300864 1376324288 14.384 98 6 12 0 1392297712 14670272 1303152
01:18:08 12181 8192000000 10321746512 10775224320 1407653776 14.663 100 64 127 1 1434459424 12537856 14267792
01:19:30 76635 8192000000 10304582304 10754768896 1355129120 14.194 96 23 46 0 1373980560 13127408 5724032
01:22:09 82898 8192000000 10293709584 10755600384 1370599392 14.332 99 10 19 1 1388442688 15449968 2393328
01:24:41 60073 8192000000 10294854528 10742755328 1313578560 13.819 95 20 40 1 1336021472 17777216 4665696
01:26:47 100993 8192000000 10283890656 10746658816 1315049264 13.832 96 5 10 0 1339409856 23614480 746112
01:29:33 130067 8192000000 10235216080 10687094784 1239161152 13.139 91 22 44 1 1251763296 6635888 5966256
01:33:23 30506 8192000000 10223176592 10678194176 1269485472 13.417 94 19 38 1 1282456528 8282416 4688640
01:34:54 79866 8192000000 10262989840 10717741056 1354092544 14.184 100 10 20 0 1368578960 11982032 2504384
01:37:24 36002 8192000000 9430274624 9751306240 578615056 6.597 42 9 18 0 591069152 10607024 1847072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-11-2021 01:40:36 to 18-11-2021 01:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-11-2021 01:40:51 to 18-11-2021 01:40:53.

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          241725
MON$OLDEST_ACTIVE               241726
MON$OLDEST_SNAPSHOT             241726
MON$NEXT_TRANSACTION            241726
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                       2047584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Nov 18 01:38:14 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

18.11.21 01:40:54. 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
2021-11-18 01:40:57 No severe PSQL-related problems occured
18.11.21 01:40:58. End of report.