Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15889104 kB
MemAvailable 30744392 kB
Buffers 0 kB
Cached 15822964 kB
SwapCached 4696 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-10-04 01:37:51
DTS_END                         2021-10-04 01:37:51.3960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43944
TRN_ID                          243469
ATT_ID                          704
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: 9698.54 21324 1163825
customer order: creation 176.20 326 21144
customer order: refuse 14.77 342 1772
order to supplier: creation 46.73 1182 5607
order to supplier: removal 3.33 107065 400
invoice (draft): creation 47.06 3579 5647
invoice (draft): removal 54.91 852 6589
invoice accept: apply 43.92 18388 5270
invoice accept: cancel 50.28 802 6034
customer reserve: creation 4036.29 183 484355
customer reserve: removal 4916.00 55 589920
realization accept: apply 85.93 176 10312
realization accept: cancel 92.28 97 11073
payment from customer: creation 60.11 115 7213
payment from customer: removal 5.71 88 685
payment to supplier: creation 49.63 124 5956
payment to supplier: removal 3.23 66 388
service: total inventory turnovers 4.63 1019 556
service: total monetary turnovers 6.63 49 795
service: aggregate perf. data 0.88 21625 105
service: refresh index statistics 0.03 270338 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2021 01:39:56 to 04-10-2021 01:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2468
TEST_TIME 2 3011
TEST_TIME 3 3542
TEST_TIME 4 4215
TEST_TIME 5 4576
TEST_TIME 6 5247
TEST_TIME 7 5250
TEST_TIME 8 5950
TEST_TIME 9 6161
TEST_TIME 10 6197
TEST_TIME 11 6620
TEST_TIME 12 7316
TEST_TIME 13 7171
TEST_TIME 14 7474
TEST_TIME 15 7624
TEST_TIME 16 7628
TEST_TIME 17 7909
TEST_TIME 18 8247
TEST_TIME 19 8235
TEST_TIME 20 7950
TEST_TIME 21 8128
TEST_TIME 22 8826
TEST_TIME 23 8925
TEST_TIME 24 9093
TEST_TIME 25 8999
TEST_TIME 26 9020
TEST_TIME 27 9721
TEST_TIME 28 9660
TEST_TIME 29 10326
TEST_TIME 30 10543
TEST_TIME 31 10539
TEST_TIME 32 10483
TEST_TIME 33 10864
TEST_TIME 34 10812
TEST_TIME 35 10705
TEST_TIME 36 10560
TEST_TIME 37 10303
TEST_TIME 38 10300
TEST_TIME 39 10256
TEST_TIME 40 10143
TEST_TIME 41 10078
TEST_TIME 42 10044
TEST_TIME 43 10082
TEST_TIME 44 10192
TEST_TIME 45 10189
TEST_TIME 46 10099
TEST_TIME 47 10059
TEST_TIME 48 9921
TEST_TIME 49 9741
TEST_TIME 50 9830
TEST_TIME 51 9863
TEST_TIME 52 9740
TEST_TIME 53 9858
TEST_TIME 54 9754
TEST_TIME 55 9781
TEST_TIME 56 9774
TEST_TIME 57 9763
TEST_TIME 58 9759
TEST_TIME 59 9928
TEST_TIME 60 9849
TEST_TIME 61 9917
TEST_TIME 62 9978
TEST_TIME 63 9919
TEST_TIME 64 9944
TEST_TIME 65 10023
TEST_TIME 66 10102
TEST_TIME 67 10136
TEST_TIME 68 10119
TEST_TIME 69 10158
TEST_TIME 70 10105
TEST_TIME 71 10008
TEST_TIME 72 9906
TEST_TIME 73 9975
TEST_TIME 74 9992
TEST_TIME 75 9925
TEST_TIME 76 9937
TEST_TIME 77 9868
TEST_TIME 78 9779
TEST_TIME 79 9797
TEST_TIME 80 9839
TEST_TIME 81 9771
TEST_TIME 82 9713
TEST_TIME 83 9743
TEST_TIME 84 9700
TEST_TIME 85 9682
TEST_TIME 86 9649
TEST_TIME 87 9738
TEST_TIME 88 9773
TEST_TIME 89 9780
TEST_TIME 90 9745
TEST_TIME 91 9853
TEST_TIME 92 9873
TEST_TIME 93 9863
TEST_TIME 94 9886
TEST_TIME 95 9938
TEST_TIME 96 9953
TEST_TIME 97 9922
TEST_TIME 98 9870
TEST_TIME 99 9862
TEST_TIME 100 9787
TEST_TIME 101 9780
TEST_TIME 102 9792
TEST_TIME 103 9784
TEST_TIME 104 9737
TEST_TIME 105 9707
TEST_TIME 106 9654
TEST_TIME 107 9686
TEST_TIME 108 9702
TEST_TIME 109 9670
TEST_TIME 110 9668
TEST_TIME 111 9708
TEST_TIME 112 9742
TEST_TIME 113 9759
TEST_TIME 114 9739
TEST_TIME 115 9716
TEST_TIME 116 9720
TEST_TIME 117 9726
TEST_TIME 118 9723
TEST_TIME 119 9746
TEST_TIME 120 9700
TEST_TIME 121 9620

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2021 01:40:00 to 04-10-2021 01:40:03.

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 1612106 1612106 0 0.00 0 41425 6 0 0
doc_list_biud 1612106 1612106 0 0.00 0 41635 26 0 0
sp_add_invoice_to_stock 5393 5270 123 2.28 37 196422 18388 0 123
sp_cancel_adding_invoice 6034 6034 0 0.00 5 58491 803 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 15364 343 0 0
sp_cancel_customer_reserve 589920 589920 0 0.00 1 41112 56 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 4413 89 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 3235 67 0 0
sp_cancel_supplier_invoice 6599 6589 10 0.15 2 41642 852 0 10
sp_cancel_supplier_order 400 400 0 0.00 5 833709 107066 0 0
sp_cancel_write_off 11073 11073 0 0.00 1 11132 98 0 0
sp_client_order 21144 21144 0 0.00 3 43911 326 0 0
sp_customer_reserve 484355 484355 0 0.00 0 167558 184 0 0
sp_fill_shopping_cart 32368 32368 0 0.00 1 13588 33 0 0
sp_fill_shopping_cart_clo_res 484353 484353 0 0.00 0 13865 12 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 17875 259 0 0
sp_kill_cost_storno 18180 18180 0 0.00 0 41406 54 0 0
sp_kill_qstorno_ret_qs2qd 1197902 1197902 0 0.00 0 41631 31 0 0
sp_kill_qty_storno 631362 631362 0 0.00 0 41633 67 0 0
sp_lock_selected_doc 21341 21341 0 0.00 0 10179 4 0 0
sp_make_cost_storno 28743 28743 0 0.00 0 24885 49 0 0
sp_multiply_rows_for_pdistr 28743 28743 0 0.00 0 6800 30 0 0
sp_multiply_rows_for_qdistr 21144 21144 0 0.00 0 22303 140 0 0
sp_pay_from_customer 7213 7213 0 0.00 3 19303 115 0 0
sp_pay_to_supplier 5956 5956 0 0.00 5 14076 125 0 0
sp_payment_common 13169 13169 0 0.00 1 19299 35 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 15355 317 0 0
sp_qd_handle_on_invoice_upd_sts 11296 11296 0 0.00 0 28518 347 0 0
sp_qd_handle_on_reserve_upd_sts 10312 10312 0 0.00 0 23977 59 0 0
sp_reserve_write_off 10312 10312 0 0.00 5 23989 176 0 0
sp_supplier_invoice 5647 5647 0 0.00 5 93936 3580 0 0
sp_supplier_order 5607 5607 0 0.00 5 41894 1183 0 0
srv_aggregate_perf_data 105 105 0 0.00 2915 206408 21626 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1303 187287 46577 0 0
srv_find_qd_qs_mism 1114629 1114629 0 0.00 0 25982 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 45 31440 1020 0 0
srv_make_money_saldo 795 795 0 0.00 1 20807 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 183554 326328 270339 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5270 5270 0 0.00 0 11340 156 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 6676 91 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 2351 11 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 3589 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1599 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 116 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 3159 31 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 22958 1758 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 2256 8 0 0
t$perf-norm:sp_client_order 21144 21144 0 0.00 0 5796 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 1240 8 0 0
t$perf-norm:sp_pay_from_customer 7213 7213 0 0.00 0 3766 9 0 0
t$perf-norm:sp_pay_to_supplier 5956 5956 0 0.00 0 3196 9 0 0
t$perf-norm:sp_reserve_write_off 10312 10312 0 0.00 0 10908 15 0 0
t$perf-norm:sp_supplier_invoice 5647 5647 0 0.00 0 7421 17 0 0
t$perf-norm:sp_supplier_order 5607 5607 0 0.00 0 9635 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 109 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 457 9 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1474 26 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 1055 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10770 10524 246 2.28 0 5590 19 0 246
v_all_customers 38612 38612 0 0.00 0 20 1 0 0
v_all_suppliers 31260 31260 0 0.00 0 50 1 0 0
v_all_wares 42288 42288 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 1815 9 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2956 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2121 12 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 5707 14 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 2305 8 0 0
v_cancel_supplier_invoice 790 770 20 2.53 0 5022 21 0 20
v_cancel_supplier_order 800 800 0 0.00 0 2016 7 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2327 18 0 0
v_max_id_clo_ord 5607 5607 0 0.00 0 2368 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 2148 14 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 11154 5 0 0
v_max_non_paid_invoice 1549 1549 0 0.00 0 664 3 0 0
v_max_non_paid_realizn 3795 3795 0 0.00 0 5941 4 0 0
v_min_id_clo_ord 5607 5607 0 0.00 0 1292 4 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 634 3 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 6630 5 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 6419 25 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 19296 23 0 0
v_our_firm 10512 10512 0 0.00 0 13 1 0 0
v_reserve_write_off 20624 20624 0 0.00 0 4509 8 0 0
x_lock_depdocs_on_canc_invoice 12238 12238 0 0.00 0 16915 110 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 5655 357 0 0
x_make_qstorno_1000_1200 5607 5607 0 0.00 2 41883 1107 0 0
x_make_qstorno_1200_2000 5647 5647 0 0.00 3 90770 3451 0 0
x_make_qstorno_2100_3300 484355 484355 0 0.00 0 167556 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2021 01:40:04 to 04-10-2021 01:40:08.

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:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:56 9100 8192000000 10362269872 10677338112 1423528976 14.804 100 87 174 0 1457277200 5601888 28146336
23:10:06 24589 8192000000 10445479776 10773889024 1495359296 15.436 100 88 176 0 1531695424 8157616 28178512
23:11:33 20019 8192000000 10489815968 10830864384 1545731168 15.873 100 86 170 1 1581940192 9901440 26307584
23:12:56 14542 8192000000 10552462160 10907860992 1606199584 16.392 100 74 148 3 1638872144 10145184 22527376
23:14:18 9206 8192000000 10644447696 11008962560 1703254352 17.212 100 79 158 0 1737774080 11722224 22797504
23:15:31 6315 8192000000 10700365008 11077476352 1758347040 17.671 100 78 156 0 1793398896 13177936 21873920
23:16:41 3706 8192000000 10728374720 11123879936 1805765152 18.061 100 66 131 0 1836091216 12582160 17743904
23:17:47 1461 8192000000 10816317728 11201671168 1868263776 18.570 100 89 178 2 1906617552 12861936 25491840
23:18:50 35809 8192000000 10825497296 11223650304 1883456800 18.693 100 57 114 0 1911611824 12536320 15618704
23:20:27 4828 8192000000 10800491008 11224879104 1888584224 18.734 100 24 46 2 1901108880 5828464 6696192
23:21:33 12966 8192000000 10806554432 11238572032 1899285376 18.821 100 16 32 1 1912461008 9048368 4127264
23:22:47 5453 8192000000 10813667616 11250204672 1908723152 18.896 100 23 46 0 1921685904 6016416 6946336
23:23:53 56055 8192000000 10865062736 11272601600 1921753920 19.001 100 58 118 0 1944240048 6075936 16410192
23:25:49 6856 8192000000 10829180800 11267342336 1915295024 18.949 100 25 50 1 1928465728 6394000 6776704
23:26:57 8531 8192000000 10817774160 11257516032 1879366704 18.660 98 19 38 1 1895020992 10457824 5196464
23:28:05 39605 8192000000 10794147936 11219369984 1773260928 17.794 93 21 42 0 1783727856 5167296 5299632
23:29:50 5985 8192000000 10744878448 11172352000 1837372624 18.319 100 37 73 3 1854760144 6024320 11363200
23:30:56 7737 8192000000 10722662784 11137605632 1774939744 17.808 98 28 55 1 1789649632 6526000 8183888
23:32:07 63500 8192000000 10736950528 11151527936 1770734816 17.773 97 39 78 3 1787645056 5884832 11025408
23:34:13 8782 8192000000 10773590656 11186122752 1842994464 18.365 100 29 57 0 1856338576 5442736 7901376
23:35:24 13563 8192000000 10777209056 11203239936 1862059504 18.520 100 27 54 0 1875947040 5608960 8278576
23:36:38 11771 8192000000 10793185952 11225554944 1874234896 18.619 100 16 32 0 1889211776 9973680 5003200
23:37:56 41014 8192000000 10831236304 11252662272 1905090288 18.867 100 47 94 0 1925147008 5102944 14953776
23:39:43 22382 8192000000 10873836752 11299487744 1940910352 19.154 100 49 98 1 1963526512 9275424 13340736
23:41:09 16714 8192000000 10896564288 11319926784 1959301344 19.300 100 48 95 0 1985052000 11531136 14219520
23:42:37 23588 8192000000 10904098288 11343114240 1974665056 19.422 100 33 66 1 1994985424 10668832 9651536
23:44:04 11282 8192000000 10931213488 11362611200 1989920848 19.543 100 51 100 3 2021300576 15475632 15904096
23:45:24 9390 8192000000 10921652528 11360686080 1987883632 19.527 100 47 94 1 2022792368 20774128 14134608
23:46:38 10104 8192000000 10935709232 11372711936 1992192240 19.561 100 49 98 0 2026023824 18936144 14895440
23:48:05 3991 8192000000 10962170528 11390029824 2004257696 19.656 100 63 126 1 2046473968 23055408 19160864
23:49:14 7714 8192000000 10955047520 11385020416 1981031104 19.473 99 63 126 0 2026956080 26235792 19689184
23:50:29 12250 8192000000 10935454192 11372503040 1982360528 19.483 100 53 102 1 2019005616 21006064 15639024
23:51:45 5539 8192000000 10949102784 11375329280 1989276752 19.538 100 74 147 0 2034025088 23075168 21673168
23:52:56 2912 8192000000 10957490544 11372961792 1984251952 19.498 100 85 167 2 2038275776 28248032 25775792
23:54:06 1758 8192000000 10974434992 11383357440 1972252736 19.403 99 84 168 1 2029132208 30953760 25925712
23:55:13 1302 8192000000 10983592752 11398578176 2000784864 19.629 100 91 183 1 2061196736 33203360 27208512
23:56:18 23692 8192000000 10930741712 11373412352 1961539344 19.318 99 37 74 0 2003930400 30152736 12238320
23:57:52 1983 8192000000 10970718912 11378417664 1963920480 19.337 99 89 179 4 2036452176 43981984 28549712
23:58:58 10345 8192000000 10954253664 11404349440 1967893600 19.369 99 47 95 0 2038568016 55089984 15584432
00:00:11 8526 8192000000 10925197600 11354775552 1947713312 19.208 100 42 85 0 2002500384 40890512 13896560
00:01:27 7929 8192000000 10923697472 11360227328 1959256448 19.300 100 37 74 2 2006986816 35799520 11930848
00:02:41 14911 8192000000 10921775648 11363729408 1951448976 19.238 100 34 66 3 2003718432 41020352 11249104
00:04:34 37205 8192000000 10926912064 11375697920 1954052928 19.259 100 22 44 0 2004830560 42846032 7931600
00:06:16 50601 8192000000 10891997168 11356880896 1932931808 19.090 99 16 33 0 1983961120 45018096 6011216
00:08:07 40220 8192000000 10908523792 11372109824 1948598592 19.215 99 7 14 0 1967383440 17078656 1706192
00:09:48 43184 8192000000 10912779680 11376320512 1973025888 19.409 100 17 34 0 1994571952 16518672 5027392
00:11:32 30256 8192000000 10901279168 11352485888 1939663264 19.144 98 11 22 2 1949724912 7415120 2646528
00:13:03 128914 8192000000 10910540944 11359109120 1949101888 19.219 99 36 72 1 1970497136 11177744 10217504
00:16:15 187287 8192000000 10926692656 11399081984 1955719392 19.272 98 29 59 0 1972148928 8232016 8197520
00:20:23 56024 8192000000 10902046224 11374051328 1968561184 19.374 99 27 54 3 1993610992 17251376 7798432
00:22:28 24576 8192000000 10886724848 11375194112 1952388704 19.245 99 15 30 1 1975149456 17823824 4936928
00:23:53 47874 8192000000 10891062112 11368439808 1959507712 19.302 99 18 36 0 1975504272 11321152 4675408
00:25:41 89997 8192000000 10916260160 11387760640 1988650320 19.533 100 16 34 1 2009570576 15955376 4964880
00:28:19 25887 8192000000 10907944928 11373490176 1976574928 19.438 100 23 46 0 1997416096 13827632 7013536
00:29:48 44214 8192000000 10919159248 11401945088 1998129776 19.608 100 32 64 2 2023240400 16006064 9104560
00:31:42 78669 8192000000 10923714080 11414687744 1984846656 19.503 99 8 16 1 2002151904 15135536 2169712
00:34:01 50077 8192000000 10924940640 11379953664 1957519776 19.286 98 26 52 0 1979248944 13879216 7849952
00:35:53 96429 8192000000 10877606368 11339444224 1835719136 18.306 93 8 17 0 1848970096 11449184 1801776
00:38:30 55138 8192000000 10869087328 11331780608 1907079920 18.883 97 12 24 0 1926470432 16517424 2873088
00:40:26 80141 8192000000 10924072432 11394408448 1974786416 19.423 100 16 32 0 2000524096 21677120 4060560
00:42:48 42123 8192000000 10907448528 11386683392 1954916128 19.266 99 8 16 1 1974375856 17174640 2285088
00:44:44 103649 8192000000 10883910192 11342680064 1855459216 18.466 94 19 38 1 1881637984 20863008 5315760
00:47:29 32917 8192000000 10861683408 11331817472 1857409872 18.482 95 14 28 0 1878833104 17455872 3967360
00:49:03 118279 8192000000 10859781472 11329191936 1905954400 18.874 98 27 54 1 1932346032 17988064 8403568
00:52:05 26610 8192000000 10870585904 11323064320 1905208688 18.868 98 40 81 1 1926501680 9043072 12249920
00:53:37 73092 8192000000 10867325424 11324952576 1879890368 18.664 97 15 30 0 1897692704 13991600 3810736
00:55:50 49396 8192000000 10841376432 11313963008 1886526016 18.718 98 17 33 1 1909016656 17337264 5153376
00:58:19 101006 8192000000 10861790416 11329794048 1918660496 18.976 99 19 39 0 1938944432 14779232 5504704
01:01:01 46863 8192000000 10861621088 11322179584 1916109024 18.956 99 20 40 0 1931530880 9910384 5511472
01:03:04 59663 8192000000 10881415840 11333439488 1907689488 18.888 98 27 53 1 1928162464 12714528 7758448
01:05:03 67156 8192000000 10865383200 11335143424 1882622160 18.686 97 12 24 0 1897269872 11819376 2828336
01:07:13 36154 8192000000 10894805296 11350753280 1934532784 19.103 99 31 62 1 1955873232 13021504 8318944
01:08:52 110636 8192000000 10882033808 11346665472 1860328000 18.506 95 11 20 1 1872997888 9779136 2890752
01:11:43 107464 8192000000 10917563728 11382439936 1949294800 19.221 99 18 36 0 1972843744 18725088 4823856
01:14:33 32923 8192000000 10928796608 11375333376 1964521456 19.342 99 41 82 2 1989396576 13031248 11843872
01:16:23 60228 8192000000 10920220224 11379343360 1966116048 19.355 99 29 56 1 1983656432 9731712 7808672
01:18:25 72724 8192000000 10912138096 11381059584 1956151488 19.275 98 28 56 2 1976746464 12921712 7673264
01:20:38 66033 8192000000 10883424928 11370188800 1931456464 19.079 98 7 14 0 1948679184 15132080 2090640
01:22:45 57719 8192000000 10888615040 11365023744 1945843184 19.193 99 10 20 0 1957958560 9266960 2848416
01:25:02 66721 8192000000 10873341408 11341762560 1927548080 19.047 99 13 26 1 1942048160 10793072 3707008
01:27:09 78340 8192000000 10884560288 11359727616 1942791856 19.169 99 24 48 0 1961375008 12346688 6236464
01:29:28 65185 8192000000 10880247824 11344072704 1870984288 18.592 95 18 36 0 1885950304 9654928 5311088
01:31:33 29423 8192000000 10865547968 11319971840 1886901168 18.721 97 25 47 1 1901871568 7657648 7312752
01:33:09 21098 8192000000 10890845808 11337289728 1943143680 19.172 100 38 75 3 1964323344 10681792 10497872
01:34:36 25068 8192000000 10876428944 11323510784 1885155664 18.707 97 41 81 3 1906645232 10728496 10761072
01:36:09 21245 8192000000 10839414000 11288961024 1841690608 18.355 96 36 70 0 1864365184 13204656 9469920
01:37:30 51727 8192000000 9086260352 9324056576 255035456 3.019 14 3 4 1 257216880 1505456 675968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 01:40:10 to 04-10-2021 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2021 01:40:26 to 04-10-2021 01:40:28.

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          243754
MON$OLDEST_ACTIVE               243755
MON$OLDEST_SNAPSHOT             243755
MON$NEXT_TRANSACTION            243755
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                       2045024
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Mon Oct  4 01:38:25 2021
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Mon Oct  4 01:38:25 2021
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Mon Oct  4 01:38:34 2021
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:176: 
--- end of diff output ---

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

04.10.21 01:40:29. 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-10-04 01:40:31 No severe PSQL-related problems occured
04.10.21 01:40:33. End of report.