Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2021 19: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16045392 kB
MemAvailable 30796500 kB
Buffers 0 kB
Cached 15645100 kB
SwapCached 2436 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-05 19:37:57
DTS_END                         2021-11-05 19:37:57.5270
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/47666
TRN_ID                          243556
ATT_ID                          685
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: 9484.38 21693 1138125
customer order: creation 176.28 344 21154
customer order: refuse 15.17 309 1820
order to supplier: creation 47.23 1252 5667
order to supplier: removal 3.62 98015 434
invoice (draft): creation 47.04 3053 5645
invoice (draft): removal 53.10 805 6372
invoice accept: apply 43.44 17250 5213
invoice accept: cancel 48.37 749 5804
customer reserve: creation 3999.93 172 479991
customer reserve: removal 4744.86 59 569383
realization accept: apply 86.88 183 10426
realization accept: cancel 83.50 102 10020
payment from customer: creation 62.45 110 7494
payment from customer: removal 5.90 92 708
payment to supplier: creation 51.08 145 6129
payment to supplier: removal 3.50 63 420
service: total inventory turnovers 4.74 1153 569
service: total monetary turnovers 6.45 50 774
service: aggregate perf. data 0.82 21009 98
service: refresh index statistics 0.03 288952 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2021 19:39:11 to 05-11-2021 19:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2140
TEST_TIME 2 3104
TEST_TIME 3 4225
TEST_TIME 4 4082
TEST_TIME 5 4863
TEST_TIME 6 5038
TEST_TIME 7 5378
TEST_TIME 8 5881
TEST_TIME 9 5898
TEST_TIME 10 5846
TEST_TIME 11 5538
TEST_TIME 12 6143
TEST_TIME 13 6322
TEST_TIME 14 6274
TEST_TIME 15 6677
TEST_TIME 16 6840
TEST_TIME 17 7046
TEST_TIME 18 7320
TEST_TIME 19 7473
TEST_TIME 20 7544
TEST_TIME 21 7898
TEST_TIME 22 8078
TEST_TIME 23 8260
TEST_TIME 24 8399
TEST_TIME 25 8551
TEST_TIME 26 8902
TEST_TIME 27 9545
TEST_TIME 28 9604
TEST_TIME 29 10286
TEST_TIME 30 10352
TEST_TIME 31 10336
TEST_TIME 32 10262
TEST_TIME 33 10284
TEST_TIME 34 10340
TEST_TIME 35 10200
TEST_TIME 36 9966
TEST_TIME 37 9890
TEST_TIME 38 9790
TEST_TIME 39 9705
TEST_TIME 40 9776
TEST_TIME 41 9753
TEST_TIME 42 9741
TEST_TIME 43 9717
TEST_TIME 44 9655
TEST_TIME 45 9623
TEST_TIME 46 9552
TEST_TIME 47 9560
TEST_TIME 48 9492
TEST_TIME 49 9391
TEST_TIME 50 9295
TEST_TIME 51 9237
TEST_TIME 52 9344
TEST_TIME 53 9399
TEST_TIME 54 9337
TEST_TIME 55 9330
TEST_TIME 56 9436
TEST_TIME 57 9541
TEST_TIME 58 9598
TEST_TIME 59 9551
TEST_TIME 60 9610
TEST_TIME 61 9563
TEST_TIME 62 9672
TEST_TIME 63 9736
TEST_TIME 64 9610
TEST_TIME 65 9554
TEST_TIME 66 9523
TEST_TIME 67 9461
TEST_TIME 68 9387
TEST_TIME 69 9459
TEST_TIME 70 9454
TEST_TIME 71 9435
TEST_TIME 72 9450
TEST_TIME 73 9385
TEST_TIME 74 9510
TEST_TIME 75 9592
TEST_TIME 76 9607
TEST_TIME 77 9616
TEST_TIME 78 9715
TEST_TIME 79 9686
TEST_TIME 80 9676
TEST_TIME 81 9805
TEST_TIME 82 9778
TEST_TIME 83 9708
TEST_TIME 84 9666
TEST_TIME 85 9616
TEST_TIME 86 9630
TEST_TIME 87 9625
TEST_TIME 88 9569
TEST_TIME 89 9519
TEST_TIME 90 9541
TEST_TIME 91 9477
TEST_TIME 92 9462
TEST_TIME 93 9505
TEST_TIME 94 9487
TEST_TIME 95 9518
TEST_TIME 96 9501
TEST_TIME 97 9517
TEST_TIME 98 9558
TEST_TIME 99 9599
TEST_TIME 100 9602
TEST_TIME 101 9630
TEST_TIME 102 9671
TEST_TIME 103 9676
TEST_TIME 104 9727
TEST_TIME 105 9711
TEST_TIME 106 9687
TEST_TIME 107 9722
TEST_TIME 108 9647
TEST_TIME 109 9599
TEST_TIME 110 9614
TEST_TIME 111 9577
TEST_TIME 112 9547
TEST_TIME 113 9521
TEST_TIME 114 9507
TEST_TIME 115 9475
TEST_TIME 116 9462
TEST_TIME 117 9489
TEST_TIME 118 9498
TEST_TIME 119 9469
TEST_TIME 120 9486

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2021 19:39:15 to 05-11-2021 19:39:19.

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 1581028 1581028 0 0.00 0 16687 6 0 0
doc_list_biud 1581028 1581028 0 0.00 0 25082 27 0 0
sp_add_invoice_to_stock 5388 5213 175 3.24 37 190567 17250 0 175
sp_cancel_adding_invoice 5804 5804 0 0.00 5 60399 749 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 10563 309 0 0
sp_cancel_customer_reserve 569383 569383 0 0.00 1 19804 59 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 6011 92 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2421 63 0 0
sp_cancel_supplier_invoice 6382 6372 10 0.15 2 19008 806 0 10
sp_cancel_supplier_order 434 434 0 0.00 10 1080228 98016 0 0
sp_cancel_write_off 10020 10020 0 0.00 1 5377 103 0 0
sp_client_order 21154 21154 0 0.00 3 25055 344 0 0
sp_customer_reserve 479991 479991 0 0.00 0 65678 172 0 0
sp_fill_shopping_cart 32462 32462 0 0.00 1 18794 37 0 0
sp_fill_shopping_cart_clo_res 479987 479987 0 0.00 0 11081 13 0 0
sp_get_clo_for_invoice 5209 5209 0 0.00 0 20407 265 0 0
sp_kill_cost_storno 16952 16952 0 0.00 0 6725 53 0 0
sp_kill_qstorno_ret_qs2qd 1155592 1155592 0 0.00 0 19801 32 0 0
sp_kill_qty_storno 609467 609467 0 0.00 0 25079 69 0 0
sp_lock_selected_doc 21577 21577 0 0.00 0 10473 6 0 0
sp_make_cost_storno 29257 29257 0 0.00 0 16682 54 0 0
sp_multiply_rows_for_pdistr 29257 29257 0 0.00 0 6809 26 0 0
sp_multiply_rows_for_qdistr 21154 21154 0 0.00 0 8518 161 0 0
sp_pay_from_customer 7494 7494 0 0.00 4 15910 111 0 0
sp_pay_to_supplier 6129 6129 0 0.00 4 17960 146 0 0
sp_payment_common 13623 13623 0 0.00 1 14286 30 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 10548 291 0 0
sp_qd_handle_on_invoice_upd_sts 11012 11012 0 0.00 0 25077 305 0 0
sp_qd_handle_on_reserve_upd_sts 10426 10426 0 0.00 0 23794 63 0 0
sp_reserve_write_off 10426 10426 0 0.00 6 23924 184 0 0
sp_supplier_invoice 5645 5645 0 0.00 5 79666 3054 0 0
sp_supplier_order 5667 5667 0 0.00 5 35400 1253 0 0
srv_aggregate_perf_data 98 98 0 0.00 3196 191190 21009 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1847 136491 43120 0 0
srv_find_qd_qs_mism 1087875 1087875 0 0.00 0 9813 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 41 144006 1154 0 0
srv_make_money_saldo 774 774 0 0.00 1 21051 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 184170 363370 288952 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5213 5213 0 0.00 0 8777 148 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 23522 150 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 3547 17 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 532 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 2544 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2980 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 2559 21 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 33459 1803 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1692 4 0 0
t$perf-norm:sp_client_order 21154 21154 0 0.00 0 10773 18 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 3794 6 0 0
t$perf-norm:sp_pay_from_customer 7494 7494 0 0.00 0 4955 7 0 0
t$perf-norm:sp_pay_to_supplier 6129 6129 0 0.00 0 4091 8 0 0
t$perf-norm:sp_reserve_write_off 10426 10426 0 0.00 0 6283 13 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 22106 20 0 0
t$perf-norm:sp_supplier_order 5667 5667 0 0.00 0 5350 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 365 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 143 3 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 1339 23 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 3500 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10760 10410 350 3.25 0 9725 16 0 350
v_all_customers 38748 38748 0 0.00 0 22 0 0 0
v_all_suppliers 31348 31348 0 0.00 0 27 1 0 0
v_all_wares 42308 42308 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 4202 12 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2305 4 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4731 10 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 6549 11 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 238 3 0 0
v_cancel_supplier_invoice 882 862 20 2.26 0 6047 23 0 20
v_cancel_supplier_order 868 868 0 0.00 0 1383 7 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3300 16 0 0
v_max_id_clo_ord 5666 5666 0 0.00 0 7851 3 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 2505 18 0 0
v_max_id_ord_sup 5642 5642 0 0.00 0 3459 4 0 0
v_max_non_paid_invoice 1763 1763 0 0.00 0 2161 4 0 0
v_max_non_paid_realizn 4037 4037 0 0.00 0 3727 3 0 0
v_min_id_clo_ord 5666 5666 0 0.00 0 1870 5 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 5895 25 0 0
v_min_id_ord_sup 5642 5642 0 0.00 0 6169 8 0 0
v_min_non_paid_invoice 6129 6129 0 0.00 0 14270 22 0 0
v_min_non_paid_realizn 7494 7494 0 0.00 0 11215 21 0 0
v_our_firm 10474 10474 0 0.00 0 9 1 0 0
v_reserve_write_off 20852 20852 0 0.00 0 6700 6 0 0
x_lock_depdocs_on_canc_invoice 11745 11745 0 0.00 0 27540 120 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 11964 328 0 0
x_make_qstorno_1000_1200 5667 5667 0 0.00 1 35390 1178 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 2 79658 2917 0 0
x_make_qstorno_2100_3300 479990 479990 0 0.00 0 65675 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2021 19:39:20 to 05-11-2021 19:39:24.

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:01 49 8192000000 8830101984 8852185088 26165888 0.318 9 4 4 0 26358768 85760 107120
17:09:02 21106 8192000000 9911039984 10225352704 993693344 10.817 100 89 178 0 1022206528 5626688 22886496
17:10:30 22919 8192000000 9960571488 10284687360 1038074000 11.246 100 94 188 0 1074891424 13186384 23631040
17:12:10 19565 8192000000 9988847408 10326806528 1065112336 11.505 100 81 161 1 1095491040 10467792 19910912
17:13:36 8289 8192000000 10042266832 10393350144 1127995744 12.102 100 74 148 0 1156644352 11094304 17554304
17:14:47 7415 8192000000 10096377072 10460463104 1183900976 12.627 100 85 168 2 1217585504 13614208 20070320
17:15:56 5635 8192000000 10133961888 10514714624 1230985808 13.063 100 73 146 1 1259860784 12768704 16106272
17:17:04 3907 8192000000 10175918112 10557452288 1269178912 13.414 100 88 176 0 1300512784 11900096 19433776
17:18:10 2945 8192000000 10204305088 10586644480 1291046896 13.614 100 89 178 0 1324695920 12721696 20927328
17:19:15 1508 8192000000 10217955584 10606194688 1301129120 13.706 100 85 171 0 1334939680 14786928 19023632
17:20:18 4122 8192000000 10208100016 10609934336 1310011920 13.786 100 40 80 0 1327541088 8435808 9093360
17:21:28 6516 8192000000 10208781248 10617643008 1318106784 13.860 100 20 40 0 1328367360 5862224 4398352
17:22:35 78676 8192000000 10218648992 10642030592 1330442272 13.971 100 29 59 1 1342615088 5105584 7067232
17:24:55 8263 8192000000 10219886784 10645340160 1334041504 14.004 100 25 50 1 1346612880 6282064 6289312
17:26:05 8841 8192000000 10231538992 10652426240 1337622368 14.036 100 19 38 1 1347278048 5656432 3999248
17:27:15 33696 8192000000 10200799072 10619912192 1266469824 13.389 95 29 56 1 1279341264 5051168 7820272
17:29:07 7336 8192000000 10169026912 10567847936 1257271488 13.305 98 33 66 0 1271091952 6574096 7246368
17:30:15 10431 8192000000 10125123280 10525483008 1184689440 12.634 94 15 30 1 1197821584 9281776 3850368
17:31:26 7759 8192000000 10145262064 10539499520 1204739904 12.820 96 19 38 2 1214883776 5682512 4461360
17:32:39 25653 8192000000 10141690912 10554425344 1250738016 13.245 99 26 52 0 1266609136 9475456 6395664
17:34:05 8113 8192000000 10173470176 10581327872 1283182752 13.542 100 23 46 1 1294085552 5689248 5213552
17:35:15 8035 8192000000 10194288848 10598092800 1292199776 13.624 100 22 44 4 1301575248 4569120 4806352
17:36:26 8402 8192000000 10198500864 10608873472 1305489232 13.745 100 29 56 1 1318506960 5815952 7201776
17:37:36 23105 8192000000 10199004608 10614190080 1307708944 13.765 100 21 42 2 1317862656 5553552 4600160
17:39:06 29521 8192000000 10250066096 10655977472 1339803936 14.056 100 52 104 0 1361685824 10521152 11360736
17:40:49 34835 8192000000 10257195936 10678484992 1354775840 14.190 100 40 79 1 1373183584 9080720 9327024
17:42:31 3876 8192000000 10290700352 10699579392 1371699216 14.342 100 65 125 2 1396538256 10000288 14838752
17:43:38 20072 8192000000 10287600864 10709782528 1378533296 14.403 100 56 112 2 1405133136 13603296 12996544
17:45:02 7918 8192000000 10305588144 10720129024 1370535280 14.332 99 64 126 0 1404807760 20169936 14102544
17:46:14 10612 8192000000 10317500640 10733998080 1384979392 14.461 99 66 132 1 1417566560 17232048 15355120
17:47:27 16317 8192000000 10319430384 10745376768 1405150992 14.641 100 46 90 1 1434920816 18143968 11625856
17:48:47 6846 8192000000 10347050768 10762776576 1408939760 14.675 100 64 128 1 1454398864 29822656 15636448
17:49:57 3412 8192000000 10340733216 10750058496 1399859424 14.594 99 76 149 2 1443286960 24744832 18682704
17:51:06 3658 8192000000 10329463296 10740596736 1386467392 14.474 100 77 154 0 1435092480 28778288 19846800
17:52:21 5819 8192000000 10347308560 10762805248 1401402304 14.607 100 66 131 2 1451884944 34117120 16365520
17:53:31 4944 8192000000 10362871312 10777284608 1409979168 14.684 100 70 138 1 1466052768 38763696 17309904
17:54:44 12120 8192000000 10372392784 10793971712 1423095648 14.800 100 63 126 0 1480431840 40966896 16369296
17:56:06 1898 8192000000 10364184720 10777542656 1406954960 14.657 100 79 158 4 1469069568 42313840 19800768
17:57:13 1845 8192000000 10381023520 10791108608 1416474000 14.741 100 89 174 1 1484429456 46392304 21563152
17:58:21 8666 8192000000 10361798176 10788175872 1408571376 14.671 100 49 98 1 1471742112 49406704 13764032
17:59:31 17730 8192000000 10346342288 10773094400 1387338144 14.482 99 36 72 0 1446538144 48996960 10203040
18:00:54 18522 8192000000 10321448560 10745999360 1342619104 14.081 97 27 54 1 1399629728 49218976 7791648
18:02:14 23374 8192000000 10314460432 10743840768 1362833232 14.263 99 28 56 1 1418515200 48067904 7614064
18:03:40 14662 8192000000 10302886480 10734497792 1354097008 14.184 100 29 58 1 1410327344 47794928 8435408
18:04:56 27876 8192000000 10281555696 10718834688 1329017904 13.958 98 18 36 0 1374221712 40198176 5005632
18:06:33 85840 8192000000 10253510528 10686304256 1299504272 13.691 96 29 57 2 1323380480 17776496 6099712
18:09:15 50305 8192000000 10254161520 10685943808 1303758640 13.729 95 30 60 1 1318427088 7772912 6895536
18:11:24 90232 8192000000 10278837776 10728194048 1346093584 14.112 98 12 24 3 1361148896 12571712 2483600
18:14:07 19799 8192000000 10284238016 10715607040 1351161120 14.158 99 39 77 2 1367261216 7183264 8916832
18:15:29 53317 8192000000 10296388160 10731847680 1354054208 14.184 98 27 54 2 1370184640 10242240 5888192
18:17:30 29986 8192000000 10297567696 10736078848 1368577696 14.314 99 39 77 1 1389535856 11267024 9691136
18:19:01 72003 8192000000 10295890928 10749341696 1374068528 14.363 99 13 26 1 1386166672 8902352 3195792
18:21:22 41293 8192000000 10301244832 10756448256 1395272288 14.553 100 6 13 0 1406772000 10064784 1434928
18:23:04 52077 8192000000 10288275008 10751418368 1374122304 14.364 98 19 38 0 1391017312 12073104 4821904
18:24:59 23104 8192000000 10299930592 10747441152 1379983040 14.416 99 48 97 0 1396140736 6009280 10148416
18:26:22 70648 8192000000 10292390064 10755604480 1384588944 14.458 99 20 40 0 1399263456 10467792 4206720
18:28:34 60127 8192000000 10302689040 10769629184 1389115744 14.498 99 14 26 3 1403890256 11769648 3004864
18:30:36 25293 8192000000 10279307744 10739716096 1376388656 14.384 99 16 32 2 1389033344 8604000 4040688
18:32:02 47541 8192000000 10287745232 10739068928 1366081232 14.292 98 19 38 3 1381939696 11302560 4555904
18:33:59 75903 8192000000 10304573472 10772197376 1371904272 14.344 98 13 26 0 1393685008 18621424 3159312
18:36:15 28949 8192000000 10294108528 10738520064 1344924992 14.102 97 26 52 0 1360039568 9157696 5956880
18:37:48 67850 8192000000 10300201520 10745450496 1353518832 14.179 98 20 40 1 1377889680 19638720 4732128
18:40:07 48933 8192000000 10283093056 10730483712 1331304784 13.979 96 15 30 1 1343498736 9196176 2997776
18:41:56 84555 8192000000 10262773872 10707456000 1326585200 13.936 97 34 68 0 1342537200 8160912 7791088
18:44:22 58689 8192000000 10260890176 10715410432 1344825104 14.101 99 10 20 0 1357158816 10288864 2044848
18:46:21 41797 8192000000 10255827408 10722992128 1310115680 13.787 96 8 16 2 1325457424 13179936 2161808
18:48:03 41104 8192000000 10260056016 10711982080 1338291392 14.042 99 18 36 0 1356495248 13392048 4811808
18:49:48 42556 8192000000 10277466832 10727854080 1328155312 13.950 97 17 34 0 1351594336 19033568 4405456
18:51:47 62772 8192000000 10269754896 10721796096 1359153104 14.230 100 12 24 1 1375553600 13798832 2601664
18:53:50 52622 8192000000 10296446176 10744889344 1368737856 14.316 100 29 58 0 1394098400 19067504 6293040
18:55:44 136491 8192000000 10276815728 10725163008 1296098384 13.660 95 16 31 2 1324323248 24058192 4166672
18:59:01 89566 8192000000 10271383216 10712944640 1326319824 13.934 97 32 64 0 1344004144 10854944 6829376
19:01:39 58126 8192000000 10275963296 10730012672 1361535568 14.251 99 18 36 1 1372506800 6974384 3996848
19:03:40 64315 8192000000 10259946976 10729517056 1342565824 14.081 98 11 22 0 1356411360 11587312 2258224
19:05:47 60555 8192000000 10269731296 10725949440 1357768336 14.217 99 14 28 0 1372620304 11464256 3387712
19:07:59 90768 8192000000 10288907136 10747240448 1381444128 14.429 100 16 31 2 1393593328 8698336 3450864
19:10:31 67743 8192000000 10291046336 10746793984 1368143216 14.310 98 20 40 1 1380584144 8480464 3960464
19:12:39 33963 8192000000 10285532800 10739965952 1365106608 14.283 98 23 46 2 1378269824 7415488 5747728
19:14:13 29014 8192000000 10299226128 10750857216 1387530048 14.484 100 36 72 0 1406855264 11378432 7946784
19:15:43 69362 8192000000 10293356400 10746011648 1361984256 14.255 98 13 26 1 1379148416 13854336 3309824
19:18:07 36399 8192000000 10297483888 10752208896 1365989600 14.291 98 26 51 1 1383167152 11393296 5784256
19:20:21 72449 8192000000 10296897984 10756108288 1354217984 14.185 98 21 42 0 1377015200 18166528 4630688
19:23:16 22781 8192000000 10297741312 10738450432 1361519888 14.251 99 39 77 0 1379461136 9330304 8610944
19:24:41 100158 8192000000 10276750512 10736721920 1360348528 14.240 99 24 48 0 1383400496 17642560 5409408
19:27:29 15412 8192000000 10277603840 10743472128 1361306432 14.249 99 14 28 0 1387557840 23237088 3014320
19:28:46 65647 8192000000 10273261616 10744299520 1370690352 14.333 99 8 16 1 1382562144 10062192 1809600
19:31:22 85292 8192000000 10280176448 10743795712 1353733968 14.181 98 23 46 1 1375917408 17130000 5053440
19:33:57 65987 8192000000 10263482256 10712465408 1287839136 13.585 94 16 32 1 1303018320 11276416 3902768
19:36:24 56898 8192000000 10269464688 10720686080 1316466512 13.845 96 27 54 0 1336408784 13453472 6488800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 19:39:26 to 05-11-2021 19:39:41.

Exceptions occured during test was in run

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

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

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          242716
MON$OLDEST_ACTIVE               244987
MON$OLDEST_SNAPSHOT             244987
MON$NEXT_TRANSACTION            244987
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                       2029280
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Fri Nov  5 19:37:58 2021
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Fri Nov  5 19:37:59 2021
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Fri Nov  5 19:38:01 2021
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Fri Nov  5 19:38:03 2021
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Fri Nov  5 19:38:05 2021
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Fri Nov  5 19:38:05 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Fri Nov  5 19:38:06 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
--- end of diff output ---

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

05.11.21 19:39:45. 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-05 19:39:47 No severe PSQL-related problems occured
05.11.21 19:39:48. End of report.