Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.04.2022 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 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 16768140 kB
MemAvailable 31471708 kB
Buffers 0 kB
Cached 14929520 kB
SwapCached 2712 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.10
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-04-18 19:38:12
DTS_END                         2022-04-18 19:38:12.6070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59868
TRN_ID                          234405
ATT_ID                          701
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: 9453.47 25081 1134416
customer order: creation 167.13 358 20055
customer order: refuse 13.43 335 1612
order to supplier: creation 44.32 1158 5318
order to supplier: removal 3.63 97272 435
invoice (draft): creation 43.50 2786 5220
invoice (draft): removal 56.62 692 6794
invoice accept: apply 40.94 16865 4913
invoice accept: cancel 50.83 666 6100
customer reserve: creation 3801.34 166 456161
customer reserve: removal 4926.56 56 591187
realization accept: apply 79.09 163 9491
realization accept: cancel 101.59 97 12191
payment from customer: creation 56.74 105 6809
payment from customer: removal 5.43 62 652
payment to supplier: creation 48.12 106 5774
payment to supplier: removal 3.43 50 411
service: total inventory turnovers 4.54 1038 545
service: total monetary turnovers 5.50 31 660
service: aggregate perf. data 0.70 22667 84
service: refresh index statistics 0.03 356940 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-04-2022 19:39:14 to 18-04-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5177
TEST_TIME 2 6369
TEST_TIME 3 6072
TEST_TIME 4 5257
TEST_TIME 5 5399
TEST_TIME 6 5701
TEST_TIME 7 5541
TEST_TIME 8 5248
TEST_TIME 9 5675
TEST_TIME 10 5742
TEST_TIME 11 6037
TEST_TIME 12 6088
TEST_TIME 13 6928
TEST_TIME 14 7424
TEST_TIME 15 7956
TEST_TIME 16 8252
TEST_TIME 17 8777
TEST_TIME 18 9360
TEST_TIME 19 9444
TEST_TIME 20 9793
TEST_TIME 21 9789
TEST_TIME 22 9697
TEST_TIME 23 10002
TEST_TIME 24 10880
TEST_TIME 25 11540
TEST_TIME 26 11165
TEST_TIME 27 10770
TEST_TIME 28 10598
TEST_TIME 29 10472
TEST_TIME 30 10618
TEST_TIME 31 10555
TEST_TIME 32 10444
TEST_TIME 33 10394
TEST_TIME 34 10235
TEST_TIME 35 10270
TEST_TIME 36 10074
TEST_TIME 37 10009
TEST_TIME 38 10063
TEST_TIME 39 9975
TEST_TIME 40 10035
TEST_TIME 41 10010
TEST_TIME 42 10031
TEST_TIME 43 9951
TEST_TIME 44 10001
TEST_TIME 45 10065
TEST_TIME 46 10171
TEST_TIME 47 10249
TEST_TIME 48 10213
TEST_TIME 49 10201
TEST_TIME 50 10156
TEST_TIME 51 10109
TEST_TIME 52 9998
TEST_TIME 53 9902
TEST_TIME 54 9879
TEST_TIME 55 9820
TEST_TIME 56 9833
TEST_TIME 57 9783
TEST_TIME 58 9744
TEST_TIME 59 9672
TEST_TIME 60 9619
TEST_TIME 61 9588
TEST_TIME 62 9638
TEST_TIME 63 9610
TEST_TIME 64 9657
TEST_TIME 65 9777
TEST_TIME 66 9727
TEST_TIME 67 9766
TEST_TIME 68 9679
TEST_TIME 69 9742
TEST_TIME 70 9790
TEST_TIME 71 9766
TEST_TIME 72 9806
TEST_TIME 73 9883
TEST_TIME 74 9851
TEST_TIME 75 9935
TEST_TIME 76 9830
TEST_TIME 77 9795
TEST_TIME 78 9764
TEST_TIME 79 9715
TEST_TIME 80 9669
TEST_TIME 81 9640
TEST_TIME 82 9619
TEST_TIME 83 9661
TEST_TIME 84 9611
TEST_TIME 85 9545
TEST_TIME 86 9506
TEST_TIME 87 9487
TEST_TIME 88 9513
TEST_TIME 89 9503
TEST_TIME 90 9480
TEST_TIME 91 9475
TEST_TIME 92 9482
TEST_TIME 93 9509
TEST_TIME 94 9599
TEST_TIME 95 9571
TEST_TIME 96 9695
TEST_TIME 97 9685
TEST_TIME 98 9638
TEST_TIME 99 9696
TEST_TIME 100 9704
TEST_TIME 101 9688
TEST_TIME 102 9674
TEST_TIME 103 9780
TEST_TIME 104 9710
TEST_TIME 105 9625
TEST_TIME 106 9610
TEST_TIME 107 9586
TEST_TIME 108 9551
TEST_TIME 109 9511
TEST_TIME 110 9499
TEST_TIME 111 9463
TEST_TIME 112 9439
TEST_TIME 113 9394
TEST_TIME 114 9444
TEST_TIME 115 9427
TEST_TIME 116 9414
TEST_TIME 117 9415
TEST_TIME 118 9480
TEST_TIME 119 9489
TEST_TIME 120 9461

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-04-2022 19:39:18 to 18-04-2022 19:39:21.

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 1556909 1556909 0 0.00 0 22871 6 0 0
doc_list_biud 1556908 1556908 0 0.00 0 24460 27 0 0
sp_add_invoice_to_stock 5116 4913 203 3.96 28 159523 16865 0 203
sp_cancel_adding_invoice 6100 6100 0 0.00 4 89147 667 0 0
sp_cancel_client_order 1612 1612 0 0.00 4 9507 336 0 0
sp_cancel_customer_reserve 591187 591187 0 0.00 1 24463 56 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 4343 63 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 2 3030 51 0 0
sp_cancel_supplier_invoice 6812 6794 18 0.26 2 10563 692 0 18
sp_cancel_supplier_order 435 435 0 0.00 8 933080 97273 0 0
sp_cancel_write_off 12191 12191 0 0.00 1 11888 97 0 0
sp_client_order 20055 20055 0 0.00 3 20661 358 0 0
sp_customer_reserve 456161 456161 0 0.00 0 134731 167 0 0
sp_fill_shopping_cart 30585 30585 0 0.00 1 12087 32 0 0
sp_fill_shopping_cart_clo_res 456148 456148 0 0.00 0 12125 13 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 14567 316 0 0
sp_kill_cost_storno 19354 19354 0 0.00 0 11861 51 0 0
sp_kill_qstorno_ret_qs2qd 1201794 1201794 0 0.00 0 24455 30 0 0
sp_kill_qty_storno 632710 632710 0 0.00 0 24460 65 0 0
sp_lock_selected_doc 19932 19932 0 0.00 0 8790 5 0 0
sp_make_cost_storno 26974 26974 0 0.00 0 9207 46 0 0
sp_multiply_rows_for_pdistr 26974 26974 0 0.00 0 9190 25 0 0
sp_multiply_rows_for_qdistr 20055 20055 0 0.00 0 15122 174 0 0
sp_pay_from_customer 6809 6809 0 0.00 4 9447 105 0 0
sp_pay_to_supplier 5774 5774 0 0.00 5 9203 106 0 0
sp_payment_common 12583 12583 0 0.00 1 9157 27 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 0 9464 310 0 0
sp_qd_handle_on_invoice_upd_sts 11000 11000 0 0.00 0 14831 293 0 0
sp_qd_handle_on_reserve_upd_sts 9491 9491 0 0.00 0 19425 53 0 0
sp_reserve_write_off 9491 9491 0 0.00 6 19443 164 0 0
sp_supplier_invoice 5220 5220 0 0.00 6 75508 2786 0 0
sp_supplier_order 5318 5318 0 0.00 5 37345 1158 0 0
srv_aggregate_perf_data 84 84 0 0.00 2783 318926 22668 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 957 188865 52004 0 0
srv_find_qd_qs_mism 1087997 1087997 0 0.00 0 22870 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 29 38932 1038 0 0
srv_make_money_saldo 660 660 0 0.00 1 6007 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 219338 523987 356940 0 0
t$perf-abend:sp_add_invoice_to_stock 203 203 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 14068 162 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 1784 66 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 6316 15 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 3440 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 1985 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1167 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2000 24 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 20201 1452 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 1720 8 0 0
t$perf-norm:sp_client_order 20055 20055 0 0.00 0 8464 16 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 4044 10 0 0
t$perf-norm:sp_pay_from_customer 6809 6809 0 0.00 0 6708 10 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 21656 12 0 0
t$perf-norm:sp_reserve_write_off 9491 9491 0 0.00 0 11669 15 0 0
t$perf-norm:sp_supplier_invoice 5220 5220 0 0.00 0 6551 19 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 3313 13 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 308 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 9 0 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 1540 16 0 0
t$perf-norm:srv_make_money_saldo 660 660 0 0.00 0 2556 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10200 9794 406 3.98 0 11590 21 0 406
v_all_customers 36684 36684 0 0.00 0 22 0 0 0
v_all_suppliers 29798 29798 0 0.00 0 17 1 0 0
v_all_wares 40108 40108 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 648 7 0 0
v_cancel_client_order 3224 3224 0 0.00 0 5017 5 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4323 11 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1970 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 267 2 0 0
v_cancel_supplier_invoice 818 782 36 4.40 0 2370 18 0 36
v_cancel_supplier_order 870 870 0 0.00 0 1012 4 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3148 17 0 0
v_max_id_clo_ord 5318 5318 0 0.00 0 188 1 0 0
v_max_id_clo_res 970 970 0 0.00 0 3948 20 0 0
v_max_id_ord_sup 5213 5213 0 0.00 0 4492 3 0 0
v_max_non_paid_invoice 1406 1406 0 0.00 0 256 2 0 0
v_max_non_paid_realizn 3436 3436 0 0.00 0 524 3 0 0
v_min_id_clo_ord 5318 5318 0 0.00 0 1911 4 0 0
v_min_id_clo_res 970 970 0 0.00 0 6867 15 0 0
v_min_id_ord_sup 5213 5213 0 0.00 0 5108 5 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 8253 21 0 0
v_min_non_paid_realizn 6809 6809 0 0.00 0 9152 22 0 0
v_our_firm 10170 10170 0 0.00 0 15 1 0 0
v_reserve_write_off 18982 18982 0 0.00 0 6370 7 0 0
x_lock_depdocs_on_canc_invoice 12503 12503 0 0.00 0 26041 104 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 14732 310 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 1 37335 1083 0 0
x_make_qstorno_1200_2000 5220 5220 0 0.00 3 75448 2676 0 0
x_make_qstorno_2100_3300 456161 456161 0 0.00 0 134729 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 19:39:22 to 18-04-2022 19:39:26.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:16 37023 8192000000 9912895536 10224726016 992181584 10.803 100 91 182 0 1022606800 6557824 23867392
17:10:57 19207 8192000000 9948804848 10271883264 1026015520 11.130 100 87 174 0 1057135264 9025744 22094000
17:12:25 16727 8192000000 9991068768 10327355392 1073312304 11.584 100 76 152 0 1102742624 10503920 18926400
17:13:47 8242 8192000000 10043527152 10393665536 1128559200 12.108 100 79 158 0 1158383536 11117056 18707280
17:15:00 12162 8192000000 10092713472 10456690688 1185199520 12.639 100 68 136 1 1212358704 12247504 14911680
17:16:15 4969 8192000000 10136765952 10506207232 1220433184 12.966 100 88 175 1 1254215168 14599488 19182496
17:17:21 2889 8192000000 10182056688 10559705088 1259420624 13.325 100 81 159 0 1290305184 12349392 18535168
17:18:26 1817 8192000000 10199872144 10582716416 1284091184 13.550 100 94 189 0 1319317936 14476224 20750528
17:19:32 2121 8192000000 10215701168 10600484864 1296249856 13.661 100 92 184 1 1330657296 13276000 21131440
17:20:35 4367 8192000000 10186943392 10594611200 1298134848 13.678 100 41 83 2 1315219696 7470640 9614208
17:21:42 3077 8192000000 10200974240 10611957760 1311816224 13.803 100 39 78 0 1328905360 8291168 8797968
17:22:49 6049 8192000000 10202922992 10622255104 1320990736 13.886 100 30 60 0 1334923376 6706096 7226544
17:24:00 46367 8192000000 10233366128 10642898944 1329732400 13.965 100 35 70 2 1344009232 5475376 8801456
17:25:48 3982 8192000000 10239237248 10645938176 1332649600 13.991 100 47 93 2 1349813536 6460224 10703712
17:26:54 3667 8192000000 10227991056 10643611648 1330971376 13.976 100 29 58 1 1344185392 5852560 7361456
17:28:01 48859 8192000000 10199409440 10606927872 1287129536 13.578 97 26 52 0 1298396336 4830592 6436208
17:29:50 6903 8192000000 10177316880 10586210304 1278904208 13.503 99 36 72 1 1294566576 6948288 8714080
17:30:59 5744 8192000000 10142433840 10544029696 1235373344 13.104 98 35 70 1 1249909968 6280128 8256496
17:32:07 8276 8192000000 10152169344 10552172544 1244057744 13.184 99 27 51 3 1255130192 4621936 6450512
17:33:18 15061 8192000000 10159182048 10563330048 1267399680 13.398 100 32 64 1 1280238544 5845360 6993504
17:34:36 11179 8192000000 10185558608 10588815360 1275049696 13.468 99 28 56 0 1287403712 5933072 6420944
17:35:50 13136 8192000000 10197241936 10610876416 1304725184 13.738 100 17 34 1 1318757824 9870784 4161856
17:37:05 66322 8192000000 10211198032 10627178496 1316038256 13.841 100 26 52 1 1326711856 4610224 6063376
17:39:17 34984 8192000000 10250203472 10661281792 1340241904 14.060 100 50 100 1 1361104224 10061712 10800608
17:40:56 3145 8192000000 10275314768 10689011712 1363689776 14.270 100 48 96 0 1386224800 10996784 11538240
17:42:04 11501 8192000000 10292821136 10707283968 1376045088 14.381 100 40 79 1 1400096464 15148624 8902752
17:43:21 7310 8192000000 10303241984 10721767424 1386028656 14.470 100 55 109 0 1417477600 18398288 13050656
17:44:39 25134 8192000000 10309798304 10734391296 1395756160 14.557 100 40 80 2 1424645664 18750016 10139488
17:46:10 8406 8192000000 10325519952 10749313024 1395285360 14.553 99 42 84 0 1431210736 24728928 11196448
17:47:24 9014 8192000000 10344704016 10767532032 1419684160 14.770 100 68 135 0 1460776944 24742384 16350400
17:48:45 3094 8192000000 10370596992 10784681984 1429108096 14.853 100 71 142 0 1473486352 26719632 17658624
17:49:56 2307 8192000000 10382607712 10792312832 1427279072 14.837 100 90 181 1 1482137424 32946192 21912160
17:51:03 4293 8192000000 10371519136 10788134912 1417072736 14.747 99 78 154 1 1472802320 36070416 19659168
17:52:14 3233 8192000000 10370011184 10790412288 1415550288 14.733 100 70 139 2 1472041376 38425040 18066048
17:53:20 954 8192000000 10387879968 10797572096 1422346848 14.794 100 89 178 0 1488591856 43377936 22867072
17:54:25 4584 8192000000 10347988800 10776829952 1400302400 14.598 100 57 114 1 1465786592 50524448 14959744
17:55:33 9792 8192000000 10341749136 10768482304 1394207632 14.543 100 46 92 1 1452915568 45829600 12878336
17:56:58 22018 8192000000 10306614752 10749964288 1376190464 14.382 99 35 70 0 1416488544 30724624 9573456
17:58:27 13638 8192000000 10328131552 10759827456 1385264992 14.464 100 38 76 0 1431833200 36394736 10173472
17:59:42 18029 8192000000 10320906848 10760720384 1374269504 14.365 99 24 46 1 1417762928 37097120 6396304
18:01:30 27026 8192000000 10305310368 10755084288 1361243552 14.249 100 19 38 1 1420515456 53426960 5844944
18:03:10 55884 8192000000 10258770784 10711965696 1340204080 14.059 99 8 16 0 1365056416 22560128 2292208
18:05:06 64486 8192000000 10257495104 10690568192 1335440256 14.016 99 44 86 1 1353318896 8082640 9796000
18:07:11 38530 8192000000 10248836896 10674036736 1298202624 13.679 97 43 86 0 1318813808 11448960 9162224
18:08:53 188865 8192000000 10237098496 10682753024 1257854624 13.310 93 11 23 1 1270780560 10705536 2220400
18:13:08 123378 8192000000 10202431168 10645037056 1256430384 13.297 94 33 64 1 1269100192 5872656 6797152
18:16:13 93994 8192000000 10244034080 10691743744 1308289104 13.771 97 9 18 0 1323375136 12661312 2424720
18:18:48 45230 8192000000 10265203056 10702774272 1345220304 14.104 99 22 44 1 1363886096 13694432 4971360
18:20:39 50209 8192000000 10278990928 10719240192 1342711216 14.082 98 32 64 0 1363751712 13886656 7153840
18:22:29 109630 8192000000 10302368096 10737868800 1375636032 14.378 100 21 41 0 1402292352 22151504 4504816
18:25:23 50638 8192000000 10297740320 10731814912 1369395536 14.322 99 36 69 1 1385670656 8249888 8025232
18:27:15 58745 8192000000 10300250608 10746482688 1363922704 14.273 98 29 58 1 1382166912 12233376 6010832
18:29:14 94743 8192000000 10310802032 10752831488 1394121632 14.543 100 38 76 0 1414625424 11402608 9101184
18:31:51 40311 8192000000 10299394016 10758201344 1383729008 14.450 99 17 34 1 1400738736 12995264 4014464
18:33:35 85895 8192000000 10276944448 10744061952 1363599728 14.270 98 9 17 1 1379515888 14443680 1472480
18:36:01 63485 8192000000 10283126128 10749566976 1378441648 14.403 99 10 20 1 1394379376 13458288 2479440
18:38:13 51148 8192000000 10302086336 10769195008 1404256528 14.633 100 16 32 0 1418008944 10194320 3558096
18:40:07 38720 8192000000 10292629952 10755784704 1371298272 14.339 98 11 22 2 1386283920 12716080 2269568
18:41:52 58774 8192000000 10309470048 10765815808 1403737744 14.628 100 20 40 0 1421662976 12912912 5012320
18:43:51 52775 8192000000 10289912032 10745020416 1324207184 13.915 95 16 32 0 1341213504 13260464 3745856
18:45:45 71076 8192000000 10310167808 10747953152 1345893744 14.111 97 19 38 0 1367992016 17864960 4233312
18:48:04 101889 8192000000 10269509600 10717700096 1214782176 12.913 88 20 40 0 1235669744 16639168 4248400
18:50:53 77222 8192000000 10280971424 10726219776 1299497344 13.691 95 19 38 0 1320678864 17144784 4036736
18:53:18 75678 8192000000 10256852704 10705240064 1337192880 14.032 98 15 31 1 1352408624 12614704 2601040
18:56:21 67202 8192000000 10269750864 10710310912 1307597200 13.764 96 24 48 2 1320877280 8097376 5182704
18:58:36 55642 8192000000 10243633824 10690883584 1323547360 13.909 97 14 28 1 1340977648 14236880 3193408
19:00:32 37177 8192000000 10237536112 10691493888 1323695296 13.910 98 12 23 0 1340669328 14505856 2468176
19:02:10 66413 8192000000 10254884320 10701795328 1317370928 13.853 97 17 34 0 1331293632 9859648 4063056
19:04:16 67148 8192000000 10258463216 10706399232 1339031264 14.049 99 26 51 3 1362499664 17390912 6077488
19:06:36 75334 8192000000 10234263824 10690670592 1304416384 13.735 97 5 10 0 1316405504 11082080 907040
19:08:52 27436 8192000000 10257237248 10705268736 1354145824 14.185 99 41 82 0 1371289120 8124864 9018432
19:10:22 93081 8192000000 10283983760 10736779264 1345825504 14.110 98 18 36 1 1366725248 17044368 3855376
19:13:36 93914 8192000000 10281152432 10746814464 1329265744 13.961 96 17 34 0 1357218432 23543104 4409584
19:16:18 28890 8192000000 10303741744 10739937280 1380182512 14.418 100 40 80 1 1398456896 9413040 8861344
19:17:53 81778 8192000000 10316166288 10772193280 1393184336 14.534 100 9 18 0 1416141376 21396832 1560208
19:20:15 46883 8192000000 10322963344 10778128384 1393066208 14.533 99 28 56 2 1420030368 20618336 6345824
19:22:09 160310 8192000000 10276425904 10735652864 1348261296 14.132 97 19 38 0 1366579104 13691120 4626688
19:25:53 46322 8192000000 10277193120 10742980608 1369095776 14.319 99 10 20 0 1381902336 10717296 2089264
19:27:40 102995 8192000000 10272544224 10738581504 1368611584 14.315 98 12 22 1 1385271584 13551168 3108832
19:30:24 58476 8192000000 10276934464 10735259648 1349612864 14.144 97 14 29 2 1369954432 16776592 3564976
19:32:25 36205 8192000000 10260794896 10709323776 1302961392 13.722 95 21 42 0 1318340240 10440928 4937920
19:34:10 41368 8192000000 10289255136 10739470336 1379020896 14.408 100 19 38 0 1395783440 12479152 4283392
19:35:55 62160 8192000000 10298117936 10767548416 1381496432 14.430 100 20 40 0 1402080656 15801984 4782240
19:37:58 23693 8192000000 8947068672 9129590784 115194896 1.386 9 3 6 0 128961744 12967776 799072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-04-2022 19:39:28 to 18-04-2022 19:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 203 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-04-2022 19:39:44 to 18-04-2022 19:39:46.

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          236642
MON$OLDEST_ACTIVE               236643
MON$OLDEST_SNAPSHOT             236643
MON$NEXT_TRANSACTION            236643
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                       2005824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:619: 
:620: mscs-test-161.budzdorov.ru    Mon Apr 18 19:38:35 2022
:621:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:622: 
--- end of diff output ---

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

18.04.22 19:39:47. 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-04-18 19:39:49 No severe PSQL-related problems occured
18.04.22 19:39:50. End of report.