Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.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 16901112 kB
MemAvailable 31558340 kB
Buffers 0 kB
Cached 14802760 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-29 01:37:54
DTS_END                         2021-11-29 01:37:54.2130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58448
TRN_ID                          236779
ATT_ID                          694
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: 9212.45 22162 1105494
customer order: creation 171.13 402 20536
customer order: refuse 14.44 400 1733
order to supplier: creation 45.53 1390 5464
order to supplier: removal 3.48 103614 417
invoice (draft): creation 47.18 3439 5662
invoice (draft): removal 50.33 891 6039
invoice accept: apply 42.32 19038 5078
invoice accept: cancel 45.73 828 5487
customer reserve: creation 3971.17 185 476540
customer reserve: removal 4513.48 62 541617
realization accept: apply 82.56 181 9907
realization accept: cancel 94.90 115 11388
payment from customer: creation 59.28 117 7113
payment from customer: removal 5.81 74 697
payment to supplier: creation 49.53 135 5943
payment to supplier: removal 3.46 78 415
service: total inventory turnovers 4.45 1242 534
service: total monetary turnovers 6.78 33 813
service: aggregate perf. data 0.89 16142 107
service: refresh index statistics 0.03 294867 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-11-2021 01:40:40 to 29-11-2021 01:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5228
TEST_TIME 2 5552
TEST_TIME 3 5507
TEST_TIME 4 5318
TEST_TIME 5 5196
TEST_TIME 6 5479
TEST_TIME 7 5363
TEST_TIME 8 5805
TEST_TIME 9 6137
TEST_TIME 10 6291
TEST_TIME 11 6464
TEST_TIME 12 6551
TEST_TIME 13 6957
TEST_TIME 14 7029
TEST_TIME 15 7406
TEST_TIME 16 7408
TEST_TIME 17 7710
TEST_TIME 18 7825
TEST_TIME 19 8068
TEST_TIME 20 8235
TEST_TIME 21 8287
TEST_TIME 22 8446
TEST_TIME 23 8999
TEST_TIME 24 9280
TEST_TIME 25 9709
TEST_TIME 26 9835
TEST_TIME 27 10313
TEST_TIME 28 10733
TEST_TIME 29 11249
TEST_TIME 30 10931
TEST_TIME 31 10675
TEST_TIME 32 10494
TEST_TIME 33 10602
TEST_TIME 34 10494
TEST_TIME 35 10425
TEST_TIME 36 10292
TEST_TIME 37 10216
TEST_TIME 38 10154
TEST_TIME 39 9987
TEST_TIME 40 10037
TEST_TIME 41 10023
TEST_TIME 42 9864
TEST_TIME 43 9701
TEST_TIME 44 9695
TEST_TIME 45 9662
TEST_TIME 46 9552
TEST_TIME 47 9451
TEST_TIME 48 9455
TEST_TIME 49 9352
TEST_TIME 50 9288
TEST_TIME 51 9286
TEST_TIME 52 9219
TEST_TIME 53 9201
TEST_TIME 54 9094
TEST_TIME 55 9234
TEST_TIME 56 9163
TEST_TIME 57 9274
TEST_TIME 58 9332
TEST_TIME 59 9327
TEST_TIME 60 9306
TEST_TIME 61 9429
TEST_TIME 62 9491
TEST_TIME 63 9436
TEST_TIME 64 9527
TEST_TIME 65 9590
TEST_TIME 66 9581
TEST_TIME 67 9582
TEST_TIME 68 9538
TEST_TIME 69 9434
TEST_TIME 70 9442
TEST_TIME 71 9348
TEST_TIME 72 9390
TEST_TIME 73 9350
TEST_TIME 74 9311
TEST_TIME 75 9247
TEST_TIME 76 9173
TEST_TIME 77 9185
TEST_TIME 78 9172
TEST_TIME 79 9205
TEST_TIME 80 9192
TEST_TIME 81 9202
TEST_TIME 82 9198
TEST_TIME 83 9273
TEST_TIME 84 9289
TEST_TIME 85 9294
TEST_TIME 86 9342
TEST_TIME 87 9296
TEST_TIME 88 9308
TEST_TIME 89 9413
TEST_TIME 90 9440
TEST_TIME 91 9402
TEST_TIME 92 9318
TEST_TIME 93 9297
TEST_TIME 94 9308
TEST_TIME 95 9253
TEST_TIME 96 9244
TEST_TIME 97 9223
TEST_TIME 98 9183
TEST_TIME 99 9232
TEST_TIME 100 9304
TEST_TIME 101 9262
TEST_TIME 102 9277
TEST_TIME 103 9305
TEST_TIME 104 9329
TEST_TIME 105 9359
TEST_TIME 106 9348
TEST_TIME 107 9362
TEST_TIME 108 9323
TEST_TIME 109 9436
TEST_TIME 110 9414
TEST_TIME 111 9414
TEST_TIME 112 9355
TEST_TIME 113 9305
TEST_TIME 114 9317
TEST_TIME 115 9308
TEST_TIME 116 9336
TEST_TIME 117 9301
TEST_TIME 118 9282
TEST_TIME 119 9261
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-11-2021 01:40:44 to 29-11-2021 01:40:47.

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 1544184 1544184 0 0.00 0 21310 7 0 0
doc_list_biud 1544184 1544184 0 0.00 0 89371 28 0 0
sp_add_invoice_to_stock 5187 5078 109 2.10 43 229156 19038 0 109
sp_cancel_adding_invoice 5487 5487 0 0.00 5 72944 829 0 0
sp_cancel_client_order 1733 1733 0 0.00 4 14463 400 0 0
sp_cancel_customer_reserve 541617 541617 0 0.00 1 46166 62 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 6719 74 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 9335 79 0 0
sp_cancel_supplier_invoice 6048 6039 9 0.14 2 89381 892 0 9
sp_cancel_supplier_order 417 417 0 0.00 5 1016362 103615 0 0
sp_cancel_write_off 11388 11388 0 0.00 1 10946 116 0 0
sp_client_order 20536 20536 0 0.00 3 22854 403 0 0
sp_customer_reserve 476540 476540 0 0.00 0 72301 186 0 0
sp_fill_shopping_cart 31658 31658 0 0.00 1 25729 42 0 0
sp_fill_shopping_cart_clo_res 476536 476536 0 0.00 0 18180 14 0 0
sp_get_clo_for_invoice 5071 5071 0 0.00 0 27043 321 0 0
sp_kill_cost_storno 17987 17987 0 0.00 0 10008 60 0 0
sp_kill_qstorno_ret_qs2qd 1101078 1101078 0 0.00 0 89367 34 0 0
sp_kill_qty_storno 581653 581653 0 0.00 0 89370 73 0 0
sp_lock_selected_doc 20744 20744 0 0.00 0 12069 6 0 0
sp_make_cost_storno 28028 28028 0 0.00 0 18188 52 0 0
sp_multiply_rows_for_pdistr 28028 28028 0 0.00 0 16288 33 0 0
sp_multiply_rows_for_qdistr 20536 20536 0 0.00 0 13710 171 0 0
sp_pay_from_customer 7113 7113 0 0.00 4 16181 118 0 0
sp_pay_to_supplier 5943 5943 0 0.00 5 16348 135 0 0
sp_payment_common 13056 13056 0 0.00 1 12899 30 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 14418 359 0 0
sp_qd_handle_on_invoice_upd_sts 10552 10552 0 0.00 0 53286 352 0 0
sp_qd_handle_on_reserve_upd_sts 9907 9907 0 0.00 0 13580 57 0 0
sp_reserve_write_off 9907 9907 0 0.00 6 16433 181 0 0
sp_supplier_invoice 5662 5662 0 0.00 6 99723 3440 0 0
sp_supplier_order 5464 5464 0 0.00 4 99444 1391 0 0
srv_aggregate_perf_data 107 107 0 0.00 2608 131730 16143 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2101 174237 46079 0 0
srv_find_qd_qs_mism 1055838 1055838 0 0.00 0 21310 6 0 0
srv_make_invnt_saldo 534 534 0 0.00 24 38186 1243 0 0
srv_make_money_saldo 813 813 0 0.00 1 8628 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 175515 462590 294868 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5078 5078 0 0.00 0 12051 178 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 7017 126 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 2334 11 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 990 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2250 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 216 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2007 19 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 24450 1545 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2479 11 0 0
t$perf-norm:sp_client_order 20536 20536 0 0.00 0 25658 18 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 4983 11 0 0
t$perf-norm:sp_pay_from_customer 7113 7113 0 0.00 0 2783 7 0 0
t$perf-norm:sp_pay_to_supplier 5943 5943 0 0.00 0 5177 11 0 0
t$perf-norm:sp_reserve_write_off 9907 9907 0 0.00 0 5813 10 0 0
t$perf-norm:sp_supplier_invoice 5662 5662 0 0.00 0 11145 21 0 0
t$perf-norm:sp_supplier_order 5464 5464 0 0.00 0 10175 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 27 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 174 6 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 905 22 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 14630 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10348 10130 218 2.10 0 8258 21 0 218
v_all_customers 37816 37816 0 0.00 0 127 1 0 0
v_all_suppliers 30944 30944 0 0.00 0 81 1 0 0
v_all_wares 41072 41072 0 0.00 0 131 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 831 7 0 0
v_cancel_client_order 3466 3466 0 0.00 0 8036 12 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 2336 5 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1360 5 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1707 7 0 0
v_cancel_supplier_invoice 804 786 18 2.23 0 5675 29 0 18
v_cancel_supplier_order 834 834 0 0.00 0 4327 8 0 0
v_cancel_write_off 2008 2008 0 0.00 0 4909 21 0 0
v_max_id_clo_ord 5464 5464 0 0.00 0 845 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1560 6 0 0
v_max_id_ord_sup 5658 5658 0 0.00 0 6340 4 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 5214 7 0 0
v_max_non_paid_realizn 3640 3640 0 0.00 0 7642 7 0 0
v_min_id_clo_ord 5464 5464 0 0.00 0 1578 4 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 3516 18 0 0
v_min_id_ord_sup 5658 5658 0 0.00 0 7669 10 0 0
v_min_non_paid_invoice 5943 5943 0 0.00 0 12895 19 0 0
v_min_non_paid_realizn 7113 7113 0 0.00 0 11003 21 0 0
v_our_firm 10202 10202 0 0.00 0 17 1 0 0
v_reserve_write_off 19814 19814 0 0.00 0 8361 7 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 32325 125 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 10201 363 0 0
x_make_qstorno_1000_1200 5464 5464 0 0.00 1 99422 1314 0 0
x_make_qstorno_1200_2000 5662 5662 0 0.00 2 88625 3281 0 0
x_make_qstorno_2100_3300 476540 476540 0 0.00 0 72269 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2021 01:40:48 to 29-11-2021 01:40:52.

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:57 16 8192000000 8804082800 8816988160 8831152 0.107 2 2 4 0 8985184 67856 86176
23:08:58 37298 8192000000 9917790112 10230190080 991267792 10.794 100 89 178 0 1020950016 6854480 22827744
23:10:40 20229 8192000000 9956740784 10280927232 1036057248 11.227 100 89 178 0 1068454384 8945712 23451424
23:12:06 17532 8192000000 9996707856 10331541504 1074090400 11.591 100 88 175 1 1106368016 10959744 21317872
23:13:38 10318 8192000000 10050240528 10401583104 1132215296 12.142 100 79 156 1 1162999968 12693392 18091280
23:14:51 8807 8192000000 10096679152 10457546752 1178700880 12.578 100 86 172 1 1212669792 14102608 19866304
23:16:01 4821 8192000000 10129807376 10498727936 1217744432 12.941 100 79 157 1 1247137088 11464000 17928656
23:17:08 2907 8192000000 10166702848 10546974720 1258225056 13.314 100 77 154 4 1287001600 11313024 17463520
23:18:12 2024 8192000000 10203711280 10584502272 1282766512 13.538 100 87 172 1 1314626880 13428608 18431760
23:19:15 22329 8192000000 10208810912 10599018496 1294856656 13.648 100 71 142 2 1320886144 10148352 15881136
23:20:39 19040 8192000000 10223684704 10614972416 1304153344 13.733 100 69 133 5 1327138336 8157904 14827088
23:21:59 3202 8192000000 10208589984 10615021568 1312619744 13.810 100 38 76 0 1327673936 6664848 8389344
23:23:06 3996 8192000000 10202622096 10620612608 1320527216 13.881 100 26 52 1 1333860400 7400784 5932400
23:24:11 9041 8192000000 10218654816 10628481024 1328247232 13.951 100 28 56 2 1341013312 6188192 6577888
23:25:22 27057 8192000000 10242786496 10640846848 1321532192 13.891 99 84 171 4 1347141616 5647456 19961968
23:26:49 5559 8192000000 10222073344 10630893568 1319460592 13.872 99 35 70 2 1335819888 9089136 7270160
23:27:57 50322 8192000000 10231722544 10629402624 1276959600 13.485 96 48 97 0 1294530368 5165712 12405056
23:29:50 6479 8192000000 10203199040 10610159616 1299288736 13.689 100 31 62 0 1312843616 6531888 7022992
23:30:59 6429 8192000000 10123833328 10525364224 1199159152 12.769 94 23 46 0 1209599232 5322240 5117840
23:32:06 62934 8192000000 10136075008 10539995136 1208962048 12.859 96 31 62 0 1222035824 5198928 7874848
23:34:10 5803 8192000000 10151546608 10549190656 1247733904 13.217 99 42 84 0 1263876496 7091504 9051088
23:35:21 13905 8192000000 10170117744 10585088000 1285525776 13.563 100 19 38 1 1295462848 5424528 4512544
23:36:36 11401 8192000000 10190162752 10604515328 1300382704 13.699 100 11 20 1 1308033744 5266688 2384352
23:37:51 40713 8192000000 10228713952 10639572992 1324869536 13.921 100 36 70 1 1338343088 6022672 7450880
23:39:40 5824 8192000000 10252588720 10663309312 1347296896 14.123 100 57 114 2 1369817680 9570272 12950512
23:40:53 8083 8192000000 10272324272 10688557056 1362929264 14.264 100 43 85 0 1384874688 12168192 9777232
23:42:11 17149 8192000000 10291719520 10709303296 1376819648 14.388 100 45 88 1 1404030752 16312688 10898416
23:43:32 20815 8192000000 10292326064 10713509888 1381563680 14.431 100 45 91 0 1414999040 22472736 10962624
23:45:02 13346 8192000000 10318734768 10741702656 1398184048 14.579 100 48 96 1 1434681440 23933696 12563696
23:46:26 12685 8192000000 10340168416 10762477568 1408725872 14.673 100 59 118 0 1448101824 24961120 14414832
23:47:43 2101 8192000000 10350587072 10768789504 1405100032 14.640 99 77 153 1 1452435936 28497248 18838656
23:48:49 2769 8192000000 10362302768 10782187520 1419273248 14.766 100 71 142 2 1468290432 32036032 16981152
23:49:55 6354 8192000000 10370622752 10791399424 1412701376 14.708 99 72 142 3 1469696048 39178816 17815856
23:51:07 6834 8192000000 10372738336 10795024384 1421388192 14.785 100 72 144 1 1478262592 39009760 17864640
23:52:19 3515 8192000000 10363987360 10776453120 1401689584 14.610 99 79 153 1 1462210416 41706944 18813888
23:53:27 2542 8192000000 10376399024 10793168896 1420437696 14.777 100 65 130 0 1478805920 41290608 17077616
23:54:34 4663 8192000000 10359847584 10772471808 1395843040 14.558 99 72 142 1 1463763968 49509520 18411408
23:55:44 3900 8192000000 10383520496 10795028480 1407446512 14.661 99 86 172 0 1480184624 49887712 22850400
23:56:53 6605 8192000000 10367025968 10790031360 1396627024 14.565 99 57 114 2 1460699200 48675824 15396352
23:58:09 7750 8192000000 10353788192 10781126656 1401854800 14.612 100 44 88 3 1461994704 48199984 11939920
23:59:41 7531 8192000000 10325439408 10744164352 1357894560 14.218 99 50 100 1 1425829008 53751952 14182496
00:00:53 8116 8192000000 10323733776 10755317760 1365060192 14.283 100 35 68 1 1428439152 53166640 10212320
00:02:10 20573 8192000000 10296254592 10721095680 1314472688 13.827 97 32 64 0 1370061744 46500016 9089040
00:03:53 26943 8192000000 10294740048 10738536448 1347024032 14.121 100 21 42 1 1403662128 50777632 5860464
00:05:23 52079 8192000000 10275173056 10719481856 1316993056 13.849 98 16 32 1 1357851616 36267072 4591488
00:07:16 106323 8192000000 10278901216 10710761472 1357851792 14.218 100 34 67 0 1380540048 15219808 7468448
00:10:02 19447 8192000000 10303873504 10735828992 1377002768 14.390 100 55 109 2 1407008688 17584176 12421744
00:11:23 25096 8192000000 10276939216 10731634688 1360337952 14.240 99 14 28 1 1375692624 12427008 2927664
00:13:16 19817 8192000000 10296653456 10732740608 1379645232 14.413 100 41 80 1 1398985536 9506288 9834016
00:14:38 64993 8192000000 10276619584 10729390080 1374067072 14.363 100 11 20 1 1388601840 12376176 2158592
00:17:11 79409 8192000000 10278365280 10735038464 1369352768 14.321 99 20 42 1 1391597376 17235952 5008656
00:19:31 101190 8192000000 10297151536 10747125760 1371587168 14.341 99 20 40 0 1386929792 10586384 4756240
00:22:19 62215 8192000000 10270154640 10722582528 1350147248 14.149 98 24 48 0 1369030432 13146480 5736704
00:24:23 44399 8192000000 10265673216 10721546240 1371382080 14.339 100 21 42 0 1394396416 18282096 4732240
00:26:07 58199 8192000000 10276795392 10726797312 1373673872 14.360 100 11 22 1 1387687328 11131360 2882096
00:28:22 41476 8192000000 10276126528 10736578560 1386674480 14.476 100 9 18 0 1397408448 8779168 1954800
00:30:08 48953 8192000000 10285910608 10754400256 1392638912 14.529 100 10 20 0 1405365456 10664720 2061824
00:31:58 14062 8192000000 10292608480 10739159040 1376897728 14.389 99 55 107 1 1400156320 11158960 12099632
00:33:16 61919 8192000000 10285998032 10745348096 1370738928 14.334 99 16 32 0 1389141328 14946048 3456352
00:35:18 85186 8192000000 10290171184 10741350400 1350237168 14.150 97 17 34 0 1364278752 10119648 3921936
00:37:45 114858 8192000000 10299892784 10768814080 1361381600 14.250 97 13 26 1 1378572208 13951392 3239216
00:40:43 96286 8192000000 10289847056 10739552256 1314098608 13.823 94 16 32 0 1338160976 19854944 4207424
00:43:25 32518 8192000000 10302626944 10751746048 1385325120 14.464 99 27 55 1 1398935040 7643296 5966624
00:45:03 128359 8192000000 10290699136 10742038528 1331258624 13.979 95 26 52 1 1354021264 16738448 6024192
00:48:14 44305 8192000000 10279623280 10701811712 1327706144 13.946 97 67 131 2 1352336944 9467456 15163344
00:50:00 44212 8192000000 10226413504 10678484992 1288314720 13.589 95 12 24 0 1297332544 6226384 2791440
00:51:44 89177 8192000000 10252296576 10696974336 1298638176 13.683 96 10 20 0 1310118576 9242416 2237984
00:54:14 64225 8192000000 10226017424 10669305856 1284578656 13.555 96 8 16 1 1297693968 11575600 1539712
00:56:21 49038 8192000000 10232295760 10677518336 1299259984 13.689 96 14 28 0 1310483792 8428560 2795248
00:58:14 24097 8192000000 10272800304 10714693632 1349529568 14.143 99 31 62 2 1365428368 9016544 6882256
00:59:38 60172 8192000000 10280714944 10723852288 1363763408 14.271 100 24 48 2 1381853584 12958544 5131632
01:01:48 58471 8192000000 10277631376 10725715968 1345450720 14.107 98 30 60 0 1362547392 10137888 6958784
01:03:53 65005 8192000000 10281030384 10710757376 1301049088 13.705 94 26 51 2 1319190000 12899840 5241072
01:06:15 174237 8192000000 10294037744 10747080704 1368870624 14.317 99 13 26 0 1382888768 11617552 2400592
01:11:37 77970 8192000000 10290555552 10755129344 1361269232 14.249 98 7 14 0 1377518272 15461392 787648
01:13:55 22390 8192000000 10303785824 10757836800 1395335008 14.553 100 21 42 0 1405426784 5294224 4797552
01:15:18 62082 8192000000 10302540768 10760208384 1372919440 14.353 98 28 56 1 1396341360 16397504 7024416
01:17:23 72019 8192000000 10312503008 10764402688 1388370416 14.491 99 17 34 0 1407263120 14570064 4322640
01:19:40 63843 8192000000 10325363168 10778718208 1396697792 14.566 100 31 60 1 1411753392 8384416 6671184
01:22:03 97252 8192000000 10318550672 10766413824 1354950176 14.192 96 15 30 1 1370519168 12540800 3028192
01:24:41 50125 8192000000 10313566688 10761797632 1382556528 14.439 99 23 47 1 1400083344 12734736 4792080
01:26:44 54025 8192000000 10302093696 10756222976 1354212064 14.185 97 25 49 0 1374146464 14190800 5743600
01:28:38 38799 8192000000 10304754160 10743590912 1365930192 14.291 99 28 56 1 1382186896 10219168 6037536
01:30:20 83295 8192000000 10296369600 10753196032 1362601648 14.261 98 24 48 0 1381675840 13618256 5455936
01:32:50 38583 8192000000 10287495568 10747932672 1376084720 14.382 99 26 46 3 1394211472 12396144 5730608
01:34:35 53421 8192000000 10268686368 10722263040 1338554896 14.044 97 12 24 1 1349688080 8534064 2599120
01:36:30 76653 8192000000 10277186736 10733162496 1344991952 14.102 97 10 20 1 1358215632 10826912 2396768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2021 01:40:54 to 29-11-2021 01:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2021 01:41:10 to 29-11-2021 01:41:12.

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          237216
MON$OLDEST_ACTIVE               237217
MON$OLDEST_SNAPSHOT             237217
MON$NEXT_TRANSACTION            237217
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                       2012480
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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'

29.11.21 01:41:13. 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-29 01:41:15 No severe PSQL-related problems occured
29.11.21 01:41:16. End of report.