Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2022 19: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 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 15228332 kB
MemAvailable 30504792 kB
Buffers 0 kB
Cached 16467252 kB
SwapCached 2216 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.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-13 19:38:29
DTS_END                         2022-10-13 19:38:28.9990
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/55654
TRN_ID                          247233
ATT_ID                          641
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: 9543.02 18024 1145162
customer order: creation 175.28 333 21033
customer order: refuse 15.22 377 1826
order to supplier: creation 45.82 1202 5498
order to supplier: removal 3.49 99241 419
invoice (draft): creation 47.32 3562 5678
invoice (draft): removal 55.91 729 6709
invoice accept: apply 42.95 17772 5154
invoice accept: cancel 50.42 745 6050
customer reserve: creation 3873.06 180 464767
customer reserve: removal 4921.73 54 590607
realization accept: apply 86.57 183 10388
realization accept: cancel 91.64 105 10997
payment from customer: creation 61.29 126 7355
payment from customer: removal 5.95 66 714
payment to supplier: creation 50.95 167 6114
payment to supplier: removal 3.47 92 416
service: total inventory turnovers 4.30 1104 516
service: total monetary turnovers 6.81 32 817
service: aggregate perf. data 0.83 23527 100
service: refresh index statistics 0.03 210886 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2022 19:39:47 to 13-10-2022 19:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4999
TEST_TIME 2 3754
TEST_TIME 3 4484
TEST_TIME 4 4634
TEST_TIME 5 5160
TEST_TIME 6 5067
TEST_TIME 7 5386
TEST_TIME 8 5347
TEST_TIME 9 6308
TEST_TIME 10 6213
TEST_TIME 11 6454
TEST_TIME 12 6412
TEST_TIME 13 6649
TEST_TIME 14 6826
TEST_TIME 15 7069
TEST_TIME 16 7016
TEST_TIME 17 7249
TEST_TIME 18 7305
TEST_TIME 19 7549
TEST_TIME 20 7594
TEST_TIME 21 7677
TEST_TIME 22 7836
TEST_TIME 23 8371
TEST_TIME 24 8315
TEST_TIME 25 8550
TEST_TIME 26 8831
TEST_TIME 27 8820
TEST_TIME 28 8880
TEST_TIME 29 8855
TEST_TIME 30 9104
TEST_TIME 31 9602
TEST_TIME 32 9733
TEST_TIME 33 10022
TEST_TIME 34 9868
TEST_TIME 35 10109
TEST_TIME 36 10119
TEST_TIME 37 10111
TEST_TIME 38 9963
TEST_TIME 39 9827
TEST_TIME 40 9609
TEST_TIME 41 9648
TEST_TIME 42 9671
TEST_TIME 43 9706
TEST_TIME 44 9575
TEST_TIME 45 9516
TEST_TIME 46 9558
TEST_TIME 47 9470
TEST_TIME 48 9590
TEST_TIME 49 9686
TEST_TIME 50 9611
TEST_TIME 51 9558
TEST_TIME 52 9488
TEST_TIME 53 9436
TEST_TIME 54 9337
TEST_TIME 55 9445
TEST_TIME 56 9414
TEST_TIME 57 9363
TEST_TIME 58 9404
TEST_TIME 59 9259
TEST_TIME 60 9217
TEST_TIME 61 9231
TEST_TIME 62 9196
TEST_TIME 63 9256
TEST_TIME 64 9334
TEST_TIME 65 9322
TEST_TIME 66 9402
TEST_TIME 67 9431
TEST_TIME 68 9488
TEST_TIME 69 9433
TEST_TIME 70 9468
TEST_TIME 71 9582
TEST_TIME 72 9704
TEST_TIME 73 9653
TEST_TIME 74 9662
TEST_TIME 75 9806
TEST_TIME 76 9762
TEST_TIME 77 9674
TEST_TIME 78 9641
TEST_TIME 79 9568
TEST_TIME 80 9608
TEST_TIME 81 9571
TEST_TIME 82 9516
TEST_TIME 83 9493
TEST_TIME 84 9469
TEST_TIME 85 9409
TEST_TIME 86 9359
TEST_TIME 87 9390
TEST_TIME 88 9444
TEST_TIME 89 9473
TEST_TIME 90 9526
TEST_TIME 91 9521
TEST_TIME 92 9504
TEST_TIME 93 9545
TEST_TIME 94 9622
TEST_TIME 95 9653
TEST_TIME 96 9672
TEST_TIME 97 9641
TEST_TIME 98 9679
TEST_TIME 99 9707
TEST_TIME 100 9683
TEST_TIME 101 9720
TEST_TIME 102 9807
TEST_TIME 103 9717
TEST_TIME 104 9672
TEST_TIME 105 9665
TEST_TIME 106 9643
TEST_TIME 107 9644
TEST_TIME 108 9602
TEST_TIME 109 9567
TEST_TIME 110 9567
TEST_TIME 111 9529
TEST_TIME 112 9505
TEST_TIME 113 9479
TEST_TIME 114 9553
TEST_TIME 115 9537
TEST_TIME 116 9570
TEST_TIME 117 9595
TEST_TIME 118 9572
TEST_TIME 119 9542
TEST_TIME 120 9543

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-10-2022 19:39:50 to 13-10-2022 19:39:54.

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 1577758 1577758 0 0.00 0 61406 6 0 0
doc_list_biud 1577745 1577745 0 0.00 0 24213 26 0 0
sp_add_invoice_to_stock 5266 5154 112 2.12 28 206944 17772 0 112
sp_cancel_adding_invoice 6050 6050 0 0.00 3 60426 745 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 15369 377 0 0
sp_cancel_customer_reserve 590607 590607 0 0.00 0 24215 55 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 2 1483 66 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 2 6730 92 0 0
sp_cancel_supplier_invoice 6717 6709 8 0.11 1 22779 729 0 8
sp_cancel_supplier_order 419 419 0 0.00 5 850157 99241 0 0
sp_cancel_write_off 10997 10997 0 0.00 1 20496 105 0 0
sp_client_order 21033 21033 0 0.00 3 13928 333 0 0
sp_customer_reserve 464767 464767 0 0.00 0 62318 180 0 0
sp_fill_shopping_cart 32187 32187 0 0.00 1 17304 38 0 0
sp_fill_shopping_cart_clo_res 464763 464763 0 0.00 0 11322 13 0 0
sp_get_clo_for_invoice 5150 5150 0 0.00 0 23990 296 0 0
sp_kill_cost_storno 18177 18177 0 0.00 0 20012 56 0 0
sp_kill_qstorno_ret_qs2qd 1199339 1199339 0 0.00 0 23678 30 0 0
sp_kill_qty_storno 632146 632146 0 0.00 0 24213 65 0 0
sp_lock_selected_doc 21460 21460 0 0.00 0 8237 4 0 0
sp_make_cost_storno 29007 29007 0 0.00 0 61365 59 0 0
sp_multiply_rows_for_pdistr 29007 29007 0 0.00 0 19591 33 0 0
sp_multiply_rows_for_qdistr 21033 21033 0 0.00 0 11091 140 0 0
sp_pay_from_customer 7355 7355 0 0.00 4 23981 126 0 0
sp_pay_to_supplier 6114 6114 0 0.00 4 19985 167 0 0
sp_payment_common 13457 13457 0 0.00 1 23945 43 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 15325 351 0 0
sp_qd_handle_on_invoice_upd_sts 11200 11200 0 0.00 0 19217 339 0 0
sp_qd_handle_on_reserve_upd_sts 10388 10388 0 0.00 0 22512 65 0 0
sp_reserve_write_off 10388 10388 0 0.00 6 34741 183 0 0
sp_supplier_invoice 5678 5678 0 0.00 5 82945 3562 0 0
sp_supplier_order 5498 5498 0 0.00 5 70979 1202 0 0
srv_aggregate_perf_data 100 100 0 0.00 3009 286884 23528 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1273 125638 40623 0 0
srv_find_qd_qs_mism 1097665 1097665 0 0.00 0 19469 5 0 0
srv_make_invnt_saldo 516 516 0 0.00 32 48934 1105 0 0
srv_make_money_saldo 817 817 0 0.00 1 2680 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 136098 393141 210886 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 10380 176 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3089 96 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 5144 21 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 720 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 225 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 4524 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 2694 33 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 22198 1786 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 2485 9 0 0
t$perf-norm:sp_client_order 21033 21033 0 0.00 0 14897 16 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 5135 14 0 0
t$perf-norm:sp_pay_from_customer 7355 7355 0 0.00 0 3507 9 0 0
t$perf-norm:sp_pay_to_supplier 6114 6114 0 0.00 0 4025 10 0 0
t$perf-norm:sp_reserve_write_off 10388 10388 0 0.00 0 8012 10 0 0
t$perf-norm:sp_supplier_invoice 5678 5678 0 0.00 0 12910 24 0 0
t$perf-norm:sp_supplier_order 5498 5498 0 0.00 0 4498 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 233 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7180 113 0 0
t$perf-norm:srv_make_invnt_saldo 516 516 0 0.00 0 776 21 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 3196 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10520 10296 224 2.12 0 6763 17 0 224
v_all_customers 38120 38120 0 0.00 0 53 1 0 0
v_all_suppliers 30892 30892 0 0.00 0 28 1 0 0
v_all_wares 42046 42046 0 0.00 0 57 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1228 8 0 0
v_cancel_client_order 3652 3652 0 0.00 0 1671 4 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1123 5 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 3420 8 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3430 12 0 0
v_cancel_supplier_invoice 850 834 16 1.88 0 979 16 0 16
v_cancel_supplier_order 838 838 0 0.00 0 2211 8 0 0
v_cancel_write_off 2098 2098 0 0.00 0 618 11 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 393 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 1808 15 0 0
v_max_id_ord_sup 5668 5668 0 0.00 0 4476 3 0 0
v_max_non_paid_invoice 1824 1824 0 0.00 0 7523 7 0 0
v_max_non_paid_realizn 4002 4002 0 0.00 0 11680 8 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 3275 6 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 5598 10 0 0
v_min_id_ord_sup 5668 5668 0 0.00 0 6627 8 0 0
v_min_non_paid_invoice 6108 6108 0 0.00 0 19612 38 0 0
v_min_non_paid_realizn 7349 7349 0 0.00 0 19912 28 0 0
v_our_firm 10620 10620 0 0.00 0 21 1 0 0
v_reserve_write_off 20776 20776 0 0.00 0 3473 4 0 0
x_lock_depdocs_on_canc_invoice 12342 12342 0 0.00 0 11952 125 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 6134 382 0 0
x_make_qstorno_1000_1200 5498 5498 0 0.00 1 70945 1128 0 0
x_make_qstorno_1200_2000 5678 5678 0 0.00 2 82934 3420 0 0
x_make_qstorno_2100_3300 464767 464767 0 0.00 0 62317 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2022 19:39:55 to 13-10-2022 19:39:59.

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
17:08:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:34 33078 8192000000 9894991440 10203910144 979304528 10.677 100 90 180 0 1010222336 6366672 24551136
17:11:22 16840 8192000000 9940004096 10258890752 1021131904 11.083 100 90 180 0 1053072672 8415408 23525360
17:12:43 15811 8192000000 9963741872 10294804480 1046760912 11.330 100 82 164 0 1077146160 9470208 20915040
17:14:02 11042 8192000000 10040456256 10388312064 1124043712 12.065 100 76 150 0 1151794112 10231472 17518928
17:15:15 5325 8192000000 10104817824 10466111488 1186170816 12.648 100 86 170 1 1218041664 12290496 19580352
17:16:23 3173 8192000000 10136576592 10505961472 1225287184 13.011 100 82 162 1 1256561840 13335120 17939536
17:17:28 1224 8192000000 10173731792 10551037952 1261147344 13.341 100 92 180 1 1294479952 13803872 19528736
17:18:32 907 8192000000 10199411216 10583875584 1289960160 13.604 100 77 154 0 1320679296 13424528 17294608
17:19:35 35558 8192000000 10207577680 10608840704 1307052384 13.759 100 47 94 0 1325428960 8164896 10211680
17:21:11 9191 8192000000 10197265232 10613972992 1313722880 13.820 100 18 36 0 1323599312 5963632 3912800
17:22:20 7298 8192000000 10213319728 10627584000 1324201904 13.915 100 24 47 1 1335910176 6614160 5094112
17:23:30 7356 8192000000 10218598736 10640191488 1334497808 14.008 100 19 38 1 1344107200 5695712 3913680
17:24:40 69427 8192000000 10227226736 10653855744 1338138560 14.041 100 24 48 0 1349322816 5510320 5673936
17:26:52 4117 8192000000 10237045616 10656051200 1339086880 14.049 100 16 32 0 1347995616 5131616 3777120
17:27:57 6016 8192000000 10216039232 10633760768 1313339360 13.816 99 24 49 0 1325087120 6391472 5356288
17:29:07 17857 8192000000 10154586896 10540220416 1191273072 12.695 93 18 36 1 1202040992 5696176 5071744
17:30:28 1412 8192000000 10161684512 10537455616 1237209552 13.121 98 77 160 0 1266658384 10346144 19102688
17:31:29 5244 8192000000 10156878528 10553950208 1247313520 13.214 99 32 62 1 1261057152 6837472 6906160
17:32:39 5339 8192000000 10148612000 10546561024 1235408304 13.104 98 27 54 2 1247410192 5618656 6383232
17:33:46 60905 8192000000 10169319936 10579542016 1264449184 13.371 99 23 46 0 1274830384 4929488 5451712
17:35:47 5405 8192000000 10191596016 10604371968 1298616256 13.683 100 22 44 0 1310143152 6638656 4888240
17:36:54 14742 8192000000 10200306160 10616442880 1309463376 13.781 100 18 33 0 1318961968 4967248 4531344
17:38:15 9952 8192000000 10219370208 10635767808 1327031824 13.940 100 22 44 0 1337105696 4957504 5116368
17:39:32 14633 8192000000 10258735568 10669551616 1351008336 14.157 100 44 89 2 1368949040 8566496 9374208
17:40:48 26472 8192000000 10251614032 10670493696 1339710368 14.055 99 35 70 1 1358957312 11114208 8132736
17:42:20 70604 8192000000 10274464848 10705838080 1358823008 14.227 99 38 76 0 1380857632 13151536 8883088
17:44:33 2466 8192000000 10299109504 10715951104 1385786032 14.468 100 58 117 3 1412916176 12308128 14822016
17:45:55 4052 8192000000 10283616064 10695852032 1364139248 14.275 100 64 125 1 1394863520 16327984 14396288
17:47:03 8926 8192000000 10289842640 10709336064 1375608544 14.377 100 59 119 1 1409993152 19896208 14488400
17:48:21 11440 8192000000 10294831968 10715557888 1381122544 14.427 100 42 81 2 1403213216 12564848 9525824
17:49:38 16369 8192000000 10303597872 10722656256 1383024192 14.444 100 58 116 0 1412435040 15542416 13868432
17:51:01 5374 8192000000 10293835680 10709311488 1362872224 14.263 99 59 118 0 1395223088 18490560 13860304
17:52:10 5248 8192000000 10288411552 10700124160 1355049232 14.193 99 61 122 2 1389304688 20058640 14196816
17:53:25 9240 8192000000 10287843136 10709278720 1362471264 14.260 99 44 88 0 1393169072 19424480 11273328
17:54:40 11921 8192000000 10289742800 10706808832 1356975056 14.210 99 43 86 0 1392742480 24628368 11139056
17:55:56 1700 8192000000 10304542640 10711318528 1369664816 14.324 100 74 145 0 1409618192 22421360 17532016
17:57:03 8990 8192000000 10282855408 10700234752 1349847280 14.146 99 53 107 0 1389950336 26652224 13450832
17:58:20 5305 8192000000 10297006464 10707144704 1360500048 14.242 99 67 133 0 1402073760 24916032 16657680
17:59:32 4285 8192000000 10306831312 10722381824 1367151872 14.302 100 59 114 1 1412552976 30326560 15074544
18:00:47 1270 8192000000 10339160752 10742169600 1382206752 14.436 100 94 189 2 1439116224 33686016 23223456
18:01:58 7679 8192000000 10315244160 10743496704 1383247856 14.446 100 33 66 0 1418668016 26387200 9032960
18:03:20 2982 8192000000 10332107520 10744758272 1372100816 14.346 99 69 136 1 1419748000 30509488 17137696
18:04:34 11602 8192000000 10315893792 10744492032 1381330080 14.428 100 43 86 0 1422040016 29271312 11438624
18:06:02 34847 8192000000 10321974736 10760531968 1389980096 14.506 100 26 52 0 1429412720 31999024 7433600
18:07:39 7937 8192000000 10315139648 10743689216 1363940576 14.273 99 31 62 0 1408308656 36237904 8130176
18:08:50 24980 8192000000 10320213776 10754244608 1376419632 14.385 99 25 50 0 1418020352 35085488 6515232
18:10:19 38596 8192000000 10315633232 10760200192 1390193760 14.508 100 18 34 2 1424132976 29815504 4123712
18:12:18 31448 8192000000 10297194544 10738515968 1362495296 14.260 98 27 58 1 1384660464 15480560 6684608
18:14:06 78645 8192000000 10303659936 10760818688 1398255472 14.579 100 11 22 1 1412468208 11375520 2837216
18:16:29 18012 8192000000 10339479360 10767114240 1407427504 14.661 100 50 97 0 1428146000 9237568 11480928
18:17:50 77079 8192000000 10332506912 10769760256 1403420496 14.625 100 40 81 1 1424719008 11651440 9647072
18:20:07 51652 8192000000 10299615712 10751016960 1350319536 14.150 96 36 72 2 1370157088 11519680 8317872
18:22:05 91912 8192000000 10300531648 10750832640 1370279520 14.330 98 26 50 0 1383835584 8391776 5164288
18:24:38 31578 8192000000 10321347376 10782081024 1407790448 14.664 100 15 30 0 1423667552 12807408 3069696
18:26:16 46637 8192000000 10313135632 10778488832 1385481200 14.466 98 35 66 2 1408335184 14139888 8714096
18:28:15 44246 8192000000 10297369200 10757545984 1385045648 14.462 99 10 20 0 1397981856 10414032 2522176
18:30:06 34535 8192000000 10298459312 10759467008 1383574688 14.448 99 12 24 0 1397481376 11016944 2889744
18:31:53 91156 8192000000 10305436576 10758983680 1377146096 14.391 99 22 44 0 1396995680 14810128 5039456
18:34:50 57330 8192000000 10274613088 10724675584 1350565760 14.153 97 18 34 0 1364461968 10068528 3827680
18:39:07 67518 8192000000 10265157376 10722918400 1296319472 13.662 93 15 30 0 1308065696 8525952 3220272
18:41:18 39941 8192000000 10297342656 10742349824 1351776032 14.163 98 21 44 0 1371576896 14727008 5073856
18:43:03 18683 8192000000 10264691648 10711724032 1326445280 13.935 97 22 44 0 1338188272 6788736 4954256
18:44:25 60534 8192000000 10262274480 10711724032 1269105008 13.413 93 13 26 0 1283893136 12077520 2710608
18:46:26 58011 8192000000 10274440208 10729189376 1347441008 14.124 99 24 48 0 1368670928 15885712 5344208
18:48:31 60394 8192000000 10277843360 10726989824 1329042000 13.958 97 25 50 0 1360045632 24563344 6440288
18:50:47 36230 8192000000 10270365488 10712268800 1350294416 14.150 99 13 26 0 1361107008 8316080 2496512
18:52:34 35227 8192000000 10296224704 10737610752 1352548528 14.170 99 36 72 0 1375553344 14805328 8199488
18:54:12 125637 8192000000 10267665744 10715475968 1336451888 14.025 98 23 45 0 1355258368 14055712 4750768
18:57:20 21100 8192000000 10267649376 10706378752 1343572464 14.090 99 39 78 0 1361606768 9724320 8309984
18:58:42 52505 8192000000 10260821600 10720632832 1344177840 14.095 98 11 22 0 1355322912 8640288 2504784
19:00:36 70951 8192000000 10291252160 10749640704 1379221488 14.410 100 12 24 0 1391953408 10316016 2415904
19:03:11 41712 8192000000 10289087568 10742689792 1368812128 14.316 99 11 22 0 1387732560 16156992 2763440
19:04:57 45952 8192000000 10286474992 10754453504 1367270320 14.303 99 11 22 0 1389493648 19594256 2629072
19:06:52 71058 8192000000 10294881744 10765496320 1370227520 14.329 99 12 22 0 1390910112 18080544 2602048
19:10:42 44243 8192000000 10296819456 10755805184 1357244400 14.213 97 25 50 0 1376775120 13935440 5595280
19:12:26 77406 8192000000 10293364208 10751250432 1364321872 14.276 98 9 18 0 1382452160 15980160 2150128
19:15:03 49743 8192000000 10317564432 10758115328 1392173024 14.525 100 26 52 0 1410235568 12445008 5617536
19:16:56 76933 8192000000 10294762640 10744340480 1328482480 13.953 95 16 32 0 1341636176 9932064 3221632
19:19:15 87985 8192000000 10303725440 10772160512 1338292832 14.042 96 8 16 0 1362549488 22510000 1746656
19:21:43 59601 8192000000 10292786752 10741903360 1369969472 14.327 99 26 54 0 1388720384 12952496 5798416
19:23:48 41550 8192000000 10288808608 10749538304 1387484032 14.483 100 13 26 0 1401766208 11777664 2504512
19:25:34 67947 8192000000 10267892144 10726076416 1339643792 14.054 97 16 32 1 1351481616 8679776 3158048
19:27:42 36346 8192000000 10286329856 10742906880 1383148160 14.445 100 24 49 0 1398788784 10024352 5616272
19:29:20 58788 8192000000 10283820288 10739597312 1361871280 14.254 99 20 40 0 1382590144 15459008 5259856
19:31:37 64586 8192000000 10270751088 10747420672 1366836576 14.299 99 15 30 1 1381167344 10988320 3342448
19:33:53 56089 8192000000 10249383392 10698960896 1304388432 13.735 96 16 32 0 1318050480 10300560 3361488
19:36:17 81932 8192000000 10269230272 10710392832 1289179232 13.597 95 22 44 1 1314815136 19942704 5693200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2022 19:40:01 to 13-10-2022 19:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2022 19:40:17 to 13-10-2022 19:40:19.

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          245226
MON$OLDEST_ACTIVE               247856
MON$OLDEST_SNAPSHOT             247856
MON$NEXT_TRANSACTION            247856
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                       2057888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:299: 
:300: mscs-test-161.budzdorov.ru    Thu Oct 13 19:38:41 2022
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
:303: 
:304: mscs-test-161.budzdorov.ru    Thu Oct 13 19:38:43 2022
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:306: 
--- end of diff output ---

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

13.10.22 19:40:20. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-13 19:40:23 No severe PSQL-related problems occured
13.10.22 19:40:24. End of report.