Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.11.2021 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 16868992 kB
MemAvailable 31559532 kB
Buffers 0 kB
Cached 14776628 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-19 01:37:49
DTS_END                         2021-11-19 01:37:49.8840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42898
TRN_ID                          238511
ATT_ID                          711
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: 9407.32 20923 1128878
customer order: creation 173.15 342 20778
customer order: refuse 14.63 303 1755
order to supplier: creation 45.78 1252 5493
order to supplier: removal 3.53 95646 423
invoice (draft): creation 47.09 3195 5651
invoice (draft): removal 56.93 718 6831
invoice accept: apply 40.93 16937 4912
invoice accept: cancel 50.67 731 6080
customer reserve: creation 3745.07 170 449408
customer reserve: removal 4923.33 53 590800
realization accept: apply 83.45 183 10014
realization accept: cancel 93.78 99 11254
payment from customer: creation 59.33 99 7120
payment from customer: removal 6.35 69 762
payment to supplier: creation 48.34 124 5801
payment to supplier: removal 3.51 84 421
service: total inventory turnovers 4.41 1091 529
service: total monetary turnovers 6.10 22 732
service: aggregate perf. data 0.92 15910 110
service: refresh index statistics 0.03 281433 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-11-2021 01:38:42 to 19-11-2021 01:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5227
TEST_TIME 2 5565
TEST_TIME 3 5699
TEST_TIME 4 5730
TEST_TIME 5 5305
TEST_TIME 6 5780
TEST_TIME 7 5386
TEST_TIME 8 5182
TEST_TIME 9 5185
TEST_TIME 10 5236
TEST_TIME 11 5111
TEST_TIME 12 5185
TEST_TIME 13 5339
TEST_TIME 14 5752
TEST_TIME 15 6307
TEST_TIME 16 6662
TEST_TIME 17 6963
TEST_TIME 18 7022
TEST_TIME 19 7178
TEST_TIME 20 7471
TEST_TIME 21 7788
TEST_TIME 22 8203
TEST_TIME 23 8829
TEST_TIME 24 8833
TEST_TIME 25 8892
TEST_TIME 26 9616
TEST_TIME 27 10698
TEST_TIME 28 10664
TEST_TIME 29 10643
TEST_TIME 30 10577
TEST_TIME 31 10488
TEST_TIME 32 10498
TEST_TIME 33 10283
TEST_TIME 34 10205
TEST_TIME 35 10024
TEST_TIME 36 9918
TEST_TIME 37 9991
TEST_TIME 38 9945
TEST_TIME 39 9933
TEST_TIME 40 9813
TEST_TIME 41 9811
TEST_TIME 42 9726
TEST_TIME 43 9698
TEST_TIME 44 9728
TEST_TIME 45 9607
TEST_TIME 46 9689
TEST_TIME 47 9632
TEST_TIME 48 9533
TEST_TIME 49 9412
TEST_TIME 50 9431
TEST_TIME 51 9460
TEST_TIME 52 9441
TEST_TIME 53 9506
TEST_TIME 54 9474
TEST_TIME 55 9634
TEST_TIME 56 9607
TEST_TIME 57 9569
TEST_TIME 58 9783
TEST_TIME 59 9728
TEST_TIME 60 9870
TEST_TIME 61 9909
TEST_TIME 62 9945
TEST_TIME 63 9789
TEST_TIME 64 9709
TEST_TIME 65 9693
TEST_TIME 66 9695
TEST_TIME 67 9672
TEST_TIME 68 9604
TEST_TIME 69 9554
TEST_TIME 70 9554
TEST_TIME 71 9551
TEST_TIME 72 9705
TEST_TIME 73 9640
TEST_TIME 74 9653
TEST_TIME 75 9680
TEST_TIME 76 9642
TEST_TIME 77 9751
TEST_TIME 78 9762
TEST_TIME 79 9827
TEST_TIME 80 9866
TEST_TIME 81 9765
TEST_TIME 82 9663
TEST_TIME 83 9615
TEST_TIME 84 9669
TEST_TIME 85 9606
TEST_TIME 86 9577
TEST_TIME 87 9538
TEST_TIME 88 9479
TEST_TIME 89 9456
TEST_TIME 90 9411
TEST_TIME 91 9438
TEST_TIME 92 9503
TEST_TIME 93 9588
TEST_TIME 94 9588
TEST_TIME 95 9567
TEST_TIME 96 9587
TEST_TIME 97 9610
TEST_TIME 98 9584
TEST_TIME 99 9611
TEST_TIME 100 9628
TEST_TIME 101 9674
TEST_TIME 102 9703
TEST_TIME 103 9673
TEST_TIME 104 9662
TEST_TIME 105 9592
TEST_TIME 106 9605
TEST_TIME 107 9566
TEST_TIME 108 9544
TEST_TIME 109 9516
TEST_TIME 110 9470
TEST_TIME 111 9439
TEST_TIME 112 9424
TEST_TIME 113 9417
TEST_TIME 114 9400
TEST_TIME 115 9403
TEST_TIME 116 9399
TEST_TIME 117 9401
TEST_TIME 118 9383
TEST_TIME 119 9372
TEST_TIME 120 9409

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-11-2021 01:38:45 to 19-11-2021 01:38:49.

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 1546417 1546417 0 0.00 0 26636 6 0 0
doc_list_biud 1546417 1546417 0 0.00 0 69918 26 0 0
sp_add_invoice_to_stock 4965 4912 53 1.06 33 171070 16938 0 53
sp_cancel_adding_invoice 6080 6080 0 0.00 5 60573 731 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 9519 303 0 0
sp_cancel_customer_reserve 590800 590800 0 0.00 1 69921 54 0 0
sp_cancel_pay_from_customer 762 762 0 0.00 2 8013 70 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 6719 84 0 0
sp_cancel_supplier_invoice 6832 6831 1 0.01 2 20709 718 0 1
sp_cancel_supplier_order 423 423 0 0.00 7 890136 95647 0 0
sp_cancel_write_off 11254 11254 0 0.00 1 13935 100 0 0
sp_client_order 20778 20778 0 0.00 3 61153 343 0 0
sp_customer_reserve 449408 449408 0 0.00 0 107932 171 0 0
sp_fill_shopping_cart 31918 31918 0 0.00 1 11576 34 0 0
sp_fill_shopping_cart_clo_res 449402 449402 0 0.00 0 10042 13 0 0
sp_get_clo_for_invoice 4906 4906 0 0.00 0 23367 279 0 0
sp_kill_cost_storno 18517 18517 0 0.00 0 8007 49 0 0
sp_kill_qstorno_ret_qs2qd 1200108 1200108 0 0.00 0 69894 29 0 0
sp_kill_qty_storno 632063 632063 0 0.00 0 69917 63 0 0
sp_lock_selected_doc 20790 20790 0 0.00 0 9393 5 0 0
sp_make_cost_storno 27841 27841 0 0.00 0 14015 53 0 0
sp_multiply_rows_for_pdistr 27841 27841 0 0.00 0 7483 29 0 0
sp_multiply_rows_for_qdistr 20778 20778 0 0.00 0 61145 170 0 0
sp_pay_from_customer 7120 7120 0 0.00 4 11087 99 0 0
sp_pay_to_supplier 5801 5801 0 0.00 4 12578 125 0 0
sp_payment_common 12921 12921 0 0.00 2 11076 25 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 8169 274 0 0
sp_qd_handle_on_invoice_upd_sts 10986 10986 0 0.00 0 19812 282 0 0
sp_qd_handle_on_reserve_upd_sts 10014 10014 0 0.00 0 11880 61 0 0
sp_reserve_write_off 10014 10014 0 0.00 7 17371 184 0 0
sp_supplier_invoice 5651 5651 0 0.00 6 82453 3196 0 0
sp_supplier_order 5493 5493 0 0.00 5 50489 1253 0 0
srv_aggregate_perf_data 110 110 0 0.00 3465 97245 15911 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 999 159170 48305 0 0
srv_find_qd_qs_mism 1082191 1082191 0 0.00 0 26414 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 42 51568 1091 0 0
srv_make_money_saldo 732 732 0 0.00 1 895 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 89048 375904 281433 0 0
t$perf-abend:sp_add_invoice_to_stock 53 53 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4912 4912 0 0.00 0 9064 147 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 6349 104 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 3277 12 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 4498 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 762 762 0 0.00 0 1046 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 6468 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 571 8 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 19596 1537 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 351 2 0 0
t$perf-norm:sp_client_order 20778 20778 0 0.00 0 11751 15 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 2985 5 0 0
t$perf-norm:sp_pay_from_customer 7120 7120 0 0.00 0 6750 13 0 0
t$perf-norm:sp_pay_to_supplier 5801 5801 0 0.00 0 7479 11 0 0
t$perf-norm:sp_reserve_write_off 10014 10014 0 0.00 0 4673 8 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 7901 18 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 5018 13 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 350 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 345 11 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 832 15 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 5566 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2583 649 0 0
v_add_invoice_to_stock 9918 9812 106 1.06 0 10957 18 0 106
v_all_customers 37872 37872 0 0.00 0 328 0 0 0
v_all_suppliers 30828 30828 0 0.00 0 99 1 0 0
v_all_wares 41556 41556 0 0.00 0 328 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 488 7 0 0
v_cancel_client_order 3510 3510 0 0.00 0 4261 7 0 0
v_cancel_customer_prepayment 1524 1524 0 0.00 0 2120 4 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1139 3 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 340 3 0 0
v_cancel_supplier_invoice 802 800 2 0.24 0 1833 13 0 2
v_cancel_supplier_order 846 846 0 0.00 0 3378 7 0 0
v_cancel_write_off 2104 2104 0 0.00 0 4371 15 0 0
v_max_id_clo_ord 5493 5493 0 0.00 0 2230 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 2102 9 0 0
v_max_id_ord_sup 5647 5647 0 0.00 0 4208 4 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 1000 3 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 6751 6 0 0
v_min_id_clo_ord 5493 5493 0 0.00 0 1303 4 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 1077 3 0 0
v_min_id_ord_sup 5647 5647 0 0.00 0 6215 6 0 0
v_min_non_paid_invoice 5801 5801 0 0.00 0 5939 18 0 0
v_min_non_paid_realizn 7120 7120 0 0.00 0 8305 19 0 0
v_our_firm 10524 10524 0 0.00 0 17 1 0 0
v_reserve_write_off 20028 20028 0 0.00 0 4810 6 0 0
x_lock_depdocs_on_canc_invoice 12511 12511 0 0.00 0 17772 118 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 12860 313 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 2 50480 1185 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 3 78402 3076 0 0
x_make_qstorno_2100_3300 449408 449408 0 0.00 0 107931 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-11-2021 01:38:50 to 19-11-2021 01:38:54.

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:53 2 8192000000 8797934432 8808988672 4622400 0.056 2 1 2 0 4721888 56704 42784
23:08:54 23378 8192000000 9896379072 10203303936 970265808 10.589 100 92 185 0 1000544064 5638000 24640256
23:10:24 20219 8192000000 9951766528 10274066432 1026858704 11.138 100 88 176 1 1057911632 7757584 23295344
23:11:46 16657 8192000000 9994782656 10333159424 1070274256 11.555 100 72 144 0 1097373760 9340672 17758832
23:13:12 11018 8192000000 10040945680 10393784320 1125463984 12.079 100 75 151 0 1155208784 11087664 18657136
23:14:26 11661 8192000000 10094212704 10458865664 1171890064 12.514 100 77 154 2 1202988192 12396864 18701264
23:15:40 6163 8192000000 10126386672 10495254528 1212831120 12.895 100 73 146 1 1241592992 12628032 16133840
23:16:48 4202 8192000000 10157091168 10534596608 1243921168 13.182 100 79 158 0 1273717392 12759376 17036848
23:17:52 2408 8192000000 10185601840 10569232384 1264812192 13.374 100 86 172 1 1297102592 12869104 19421296
23:18:56 26547 8192000000 10206301248 10592727040 1282463072 13.535 100 68 136 1 1308717440 10096832 16157536
23:20:23 8075 8192000000 10182552784 10596040704 1293068256 13.632 100 14 28 0 1302389168 5917440 3403472
23:21:32 59090 8192000000 10214847840 10621227008 1301100832 13.705 100 35 70 1 1314997808 5385568 8511408
23:23:32 6173 8192000000 10213342672 10631151616 1310407232 13.790 100 18 34 1 1319364464 5226496 3730736
23:24:41 14525 8192000000 10216129536 10635714560 1314805040 13.830 100 12 24 0 1323157712 5847536 2505136
23:25:56 22588 8192000000 10216210736 10634375168 1309424928 13.781 99 27 54 1 1321394832 5555824 6414080
23:27:32 8645 8192000000 10218003856 10637942784 1306673968 13.756 99 24 48 0 1317376576 5708768 4993840
23:28:42 6211 8192000000 10205910912 10620198912 1283595552 13.546 98 19 38 0 1293260208 4882400 4782256
23:29:49 6867 8192000000 10151735008 10557112320 1241443088 13.160 99 25 50 0 1253430176 6147440 5839648
23:30:58 49604 8192000000 10145054560 10540437504 1222611520 12.986 97 37 73 1 1235859904 4345168 8903216
23:32:48 6909 8192000000 10150853696 10544918528 1249727440 13.236 100 40 80 2 1266547984 7764080 9056464
23:33:55 6395 8192000000 10164713248 10575618048 1277357024 13.489 100 22 44 0 1288810592 6385456 5068112
23:35:02 58662 8192000000 10193580720 10602074112 1296524944 13.664 100 33 66 0 1310206240 5305776 8375520
23:37:05 8136 8192000000 10204583904 10620563456 1314801152 13.830 100 25 50 1 1325603216 5772944 5029120
23:38:18 1833 8192000000 10240858208 10645270528 1333222016 13.996 100 62 120 2 1353480352 6088960 14169376
23:39:24 28930 8192000000 10261004192 10673307648 1357096960 14.211 100 52 104 0 1378828928 9749792 11982176
23:40:57 8021 8192000000 10274534416 10689232896 1361762896 14.253 100 56 111 3 1385332592 10365136 13204560
23:42:11 32742 8192000000 10287854160 10710900736 1377242704 14.392 100 36 71 0 1397009360 11823008 7943648
23:43:47 7332 8192000000 10298058176 10726195200 1392195344 14.525 100 42 84 1 1416296160 12880176 11220640
23:44:57 23866 8192000000 10317550224 10739204096 1387726416 14.486 99 51 101 1 1416888560 17719264 11442880
23:46:45 23831 8192000000 10308870496 10741841920 1398472608 14.581 100 36 72 0 1428089328 20868224 8748496
23:48:15 7611 8192000000 10336304784 10754174976 1403447184 14.626 100 73 147 2 1445919456 25048144 17424128
23:49:28 999 8192000000 10330498592 10744643584 1374933728 14.371 97 78 155 1 1423775328 29346592 19495008
23:50:31 5697 8192000000 10352999104 10775113728 1410843360 14.691 100 60 120 1 1462412560 36157568 15411632
23:51:46 5612 8192000000 10377368976 10795016192 1425949568 14.825 100 73 146 0 1489311184 45057040 18304576
23:52:58 5725 8192000000 10376853344 10794639360 1421091680 14.782 100 67 134 1 1477314112 39539808 16682624
23:54:09 6887 8192000000 10389123248 10803621888 1428643232 14.849 100 83 166 1 1490607040 41781024 20182784
23:55:20 7793 8192000000 10380038880 10801315840 1428487072 14.848 100 61 121 1 1488443360 45045520 14910768
23:56:34 11557 8192000000 10386310624 10815811584 1435006976 14.906 100 48 96 2 1494481568 46396928 13077664
23:57:52 14909 8192000000 10355821824 10778357760 1393241664 14.535 99 50 97 0 1454592256 47637472 13713120
23:59:10 11092 8192000000 10361726768 10800070656 1406201360 14.650 100 33 66 0 1465870864 49897184 9772320
00:00:25 16267 8192000000 10333646032 10768457728 1374805744 14.370 98 32 64 3 1419780528 36204816 8769968
00:01:46 26479 8192000000 10311062320 10747731968 1348916256 14.138 98 23 46 3 1404027376 48342000 6769120
00:03:36 30319 8192000000 10309546112 10777042944 1363299856 14.267 100 23 46 2 1431728864 61194464 7234544
00:05:06 64107 8192000000 10254444016 10711023616 1312319776 13.807 97 16 32 0 1335932656 19762816 3850064
00:08:29 125384 8192000000 10250032288 10692308992 1323341680 13.907 98 22 44 1 1343234016 15205280 4687056
00:11:40 106691 8192000000 10269972464 10709372928 1325852496 13.930 97 24 47 0 1344063712 12785568 5425648
00:14:28 52979 8192000000 10290363616 10747359232 1349441216 14.142 98 22 44 0 1373977264 18902496 5633552
00:16:21 47805 8192000000 10284126816 10739838976 1363505968 14.269 99 9 18 1 1380269408 14559376 2204064
00:18:21 98534 8192000000 10283151488 10742833152 1367563744 14.305 99 17 34 2 1387142080 15216032 4362304
00:23:10 73879 8192000000 10277848368 10740006912 1374029600 14.363 99 16 31 0 1384909232 7214800 3664832
00:25:25 97792 8192000000 10288712960 10753667072 1379394112 14.411 99 9 18 0 1392583968 11011008 2178848
00:28:03 53381 8192000000 10298596656 10757636096 1383449104 14.447 99 13 26 1 1398129600 11619088 3061408
00:30:05 42554 8192000000 10333688368 10784043008 1409881248 14.683 100 23 46 1 1427148336 12107888 5159200
00:31:57 37658 8192000000 10321870816 10780237824 1396608736 14.565 99 25 50 1 1413601728 11350272 5642720
00:33:48 49221 8192000000 10336493968 10789048320 1420110624 14.774 100 28 56 1 1436452192 9127072 7214496
00:35:48 103771 8192000000 10327684544 10796724224 1383045376 14.444 97 14 28 0 1408254720 21902800 3306544
00:38:34 159170 8192000000 10332697024 10793005056 1407535424 14.662 99 10 20 0 1421053104 11586128 1931552
00:42:38 61447 8192000000 10314187200 10782638080 1374213840 14.365 97 7 14 0 1389702688 13990240 1498608
00:45:33 57722 8192000000 10280360256 10728730624 1297532672 13.673 93 21 43 0 1312380576 9570848 5277056
00:47:35 64025 8192000000 10274555632 10717220864 1292076352 13.623 94 22 44 0 1313989616 16572080 5341184
00:49:43 45000 8192000000 10270922960 10716258304 1321468160 13.890 97 7 13 1 1336207232 13224704 1514368
00:51:50 46368 8192000000 10269135968 10695340032 1297507056 13.673 96 44 88 0 1320144608 13106496 9531056
00:53:40 22197 8192000000 10267988032 10693984256 1311137024 13.796 97 44 88 1 1332673904 11708272 9828608
00:55:04 38985 8192000000 10252660672 10691506176 1308621488 13.774 97 5 10 0 1320054080 10211568 1221024
00:56:46 145606 8192000000 10260022944 10711425024 1308924368 13.776 97 10 20 0 1334262784 23378272 1960144
01:00:14 60082 8192000000 10253372800 10705625088 1339947168 14.057 99 35 70 0 1355966336 7957184 8061984
01:02:21 57425 8192000000 10249372368 10711027712 1346141328 14.113 99 7 14 0 1357081728 9401776 1538624
01:04:19 52887 8192000000 10272680576 10721886208 1368822096 14.316 100 16 32 0 1381247952 8499392 3926464
01:06:12 73360 8192000000 10281375952 10737987584 1354671728 14.189 98 17 35 1 1375525344 16923904 3929712
01:08:35 89099 8192000000 10286783504 10755612672 1341928416 14.075 97 14 28 1 1364756288 19981392 2846480
01:11:07 62261 8192000000 10270551424 10727895040 1337532672 14.035 97 13 26 1 1350953568 10503552 2917344
01:13:09 57126 8192000000 10278215968 10733887488 1315097808 13.832 95 22 44 0 1330018144 10010544 4909792
01:15:21 40707 8192000000 10297236800 10739032064 1373876336 14.362 100 34 67 0 1389615968 8255776 7483856
01:17:06 77005 8192000000 10305565536 10761543680 1356479648 14.206 98 19 38 1 1375432576 15035056 3917872
01:19:28 42336 8192000000 10303980672 10746146816 1382177312 14.436 100 35 70 0 1398022688 7898848 7946528
01:21:16 118721 8192000000 10296550208 10748375040 1366482640 14.296 98 31 62 1 1388519392 15291616 6745136
01:24:23 19197 8192000000 10291924448 10752835584 1383502224 14.448 100 20 38 3 1400227888 12496880 4228784
01:25:48 51085 8192000000 10284422688 10757591040 1390244544 14.508 100 9 18 0 1401575824 9386480 1944800
01:28:09 67409 8192000000 10289217184 10757935104 1390881904 14.514 100 13 26 2 1404204256 10166512 3155840
01:30:18 32331 8192000000 10293031008 10756788224 1384971792 14.461 99 20 40 1 1396915792 7510496 4433504
01:31:51 63320 8192000000 10289422672 10756464640 1346394112 14.115 96 15 30 0 1357011616 7399008 3218496
01:34:01 40162 8192000000 10267128192 10714021888 1316076448 13.841 96 21 42 0 1327527520 6347136 5103936
01:35:41 44020 8192000000 10258703984 10706092032 1335999552 14.021 98 23 45 1 1351249680 10126624 5123504
01:37:33 26188 8192000000 8981994320 9164263424 140381328 1.684 11 2 4 0 141545136 925408 238400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-11-2021 01:38:56 to 19-11-2021 01:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 53 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-11-2021 01:39:11 to 19-11-2021 01:39:13.

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          239023
MON$OLDEST_ACTIVE               239024
MON$OLDEST_SNAPSHOT             239024
MON$NEXT_TRANSACTION            239024
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                       2018240
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 ---
--- end of diff output ---

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

19.11.21 01:39:14. 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-19 01:39:17 No severe PSQL-related problems occured
19.11.21 01:39:18. End of report.