Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2021 01:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16399244 kB
MemAvailable 31313440 kB
Buffers 0 kB
Cached 15231436 kB
SwapCached 440 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.9
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-12-30 01:37:58
DTS_END                         2021-12-30 01:37:58.4080
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/35176
TRN_ID                          245417
ATT_ID                          676
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: 9593.78 17280 1151253
customer order: creation 179.58 324 21550
customer order: refuse 15.53 307 1864
order to supplier: creation 48.11 1208 5773
order to supplier: removal 3.49 87368 419
invoice (draft): creation 47.96 3232 5755
invoice (draft): removal 54.56 742 6547
invoice accept: apply 43.31 16403 5197
invoice accept: cancel 49.81 750 5977
customer reserve: creation 3984.23 163 478107
customer reserve: removal 4858.98 48 583077
realization accept: apply 87.88 164 10545
realization accept: cancel 86.34 105 10361
payment from customer: creation 61.98 104 7437
payment from customer: removal 5.71 62 685
payment to supplier: creation 50.78 124 6093
payment to supplier: removal 3.37 62 404
service: total inventory turnovers 4.58 1112 549
service: total monetary turnovers 6.69 22 803
service: aggregate perf. data 0.88 18550 106
service: refresh index statistics 0.03 214753 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-12-2021 01:39:05 to 30-12-2021 01:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3835
TEST_TIME 2 3819
TEST_TIME 3 4017
TEST_TIME 4 4865
TEST_TIME 5 5173
TEST_TIME 6 5397
TEST_TIME 7 5299
TEST_TIME 8 5131
TEST_TIME 9 5044
TEST_TIME 10 5226
TEST_TIME 11 5749
TEST_TIME 12 5844
TEST_TIME 13 6067
TEST_TIME 14 6586
TEST_TIME 15 7233
TEST_TIME 16 7362
TEST_TIME 17 7880
TEST_TIME 18 8545
TEST_TIME 19 9328
TEST_TIME 20 9447
TEST_TIME 21 9611
TEST_TIME 22 10212
TEST_TIME 23 10138
TEST_TIME 24 9992
TEST_TIME 25 10094
TEST_TIME 26 9926
TEST_TIME 27 9872
TEST_TIME 28 10151
TEST_TIME 29 10322
TEST_TIME 30 10422
TEST_TIME 31 10507
TEST_TIME 32 10265
TEST_TIME 33 10187
TEST_TIME 34 10159
TEST_TIME 35 10544
TEST_TIME 36 10498
TEST_TIME 37 10413
TEST_TIME 38 10516
TEST_TIME 39 10638
TEST_TIME 40 10466
TEST_TIME 41 10232
TEST_TIME 42 10085
TEST_TIME 43 10097
TEST_TIME 44 10187
TEST_TIME 45 10241
TEST_TIME 46 10145
TEST_TIME 47 10119
TEST_TIME 48 10107
TEST_TIME 49 10155
TEST_TIME 50 10019
TEST_TIME 51 10045
TEST_TIME 52 9900
TEST_TIME 53 9893
TEST_TIME 54 9900
TEST_TIME 55 9786
TEST_TIME 56 9854
TEST_TIME 57 9836
TEST_TIME 58 9703
TEST_TIME 59 9659
TEST_TIME 60 9631
TEST_TIME 61 9567
TEST_TIME 62 9644
TEST_TIME 63 9604
TEST_TIME 64 9740
TEST_TIME 65 9736
TEST_TIME 66 9766
TEST_TIME 67 9829
TEST_TIME 68 9828
TEST_TIME 69 9832
TEST_TIME 70 9945
TEST_TIME 71 9930
TEST_TIME 72 9937
TEST_TIME 73 9927
TEST_TIME 74 9952
TEST_TIME 75 9918
TEST_TIME 76 9861
TEST_TIME 77 9817
TEST_TIME 78 9748
TEST_TIME 79 9720
TEST_TIME 80 9743
TEST_TIME 81 9680
TEST_TIME 82 9661
TEST_TIME 83 9784
TEST_TIME 84 9730
TEST_TIME 85 9686
TEST_TIME 86 9699
TEST_TIME 87 9702
TEST_TIME 88 9734
TEST_TIME 89 9717
TEST_TIME 90 9700
TEST_TIME 91 9681
TEST_TIME 92 9687
TEST_TIME 93 9731
TEST_TIME 94 9726
TEST_TIME 95 9760
TEST_TIME 96 9779
TEST_TIME 97 9738
TEST_TIME 98 9746
TEST_TIME 99 9744
TEST_TIME 100 9789
TEST_TIME 101 9718
TEST_TIME 102 9690
TEST_TIME 103 9709
TEST_TIME 104 9648
TEST_TIME 105 9669
TEST_TIME 106 9662
TEST_TIME 107 9618
TEST_TIME 108 9591
TEST_TIME 109 9579
TEST_TIME 110 9540
TEST_TIME 111 9503
TEST_TIME 112 9495
TEST_TIME 113 9533
TEST_TIME 114 9506
TEST_TIME 115 9489
TEST_TIME 116 9522
TEST_TIME 117 9568
TEST_TIME 118 9628
TEST_TIME 119 9601
TEST_TIME 120 9600

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-12-2021 01:39:09 to 30-12-2021 01:39:13.

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 1593632 1593632 0 0.00 0 12042 5 0 0
doc_list_biud 1593631 1593631 0 0.00 0 59017 23 0 0
sp_add_invoice_to_stock 5290 5197 93 1.75 12 152223 16404 0 93
sp_cancel_adding_invoice 5977 5977 0 0.00 4 118035 751 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 9788 308 0 0
sp_cancel_customer_reserve 583077 583077 0 0.00 1 59022 49 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 6091 63 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 1578 63 0 0
sp_cancel_supplier_invoice 6553 6547 6 0.09 2 32548 742 0 6
sp_cancel_supplier_order 419 419 0 0.00 5 994732 87369 0 0
sp_cancel_write_off 10361 10361 0 0.00 1 16674 105 0 0
sp_client_order 21550 21550 0 0.00 3 57829 325 0 0
sp_customer_reserve 478107 478107 0 0.00 0 55982 163 0 0
sp_fill_shopping_cart 33076 33076 0 0.00 1 11377 37 0 0
sp_fill_shopping_cart_clo_res 478093 478093 0 0.00 0 15740 12 0 0
sp_get_clo_for_invoice 5181 5181 0 0.00 0 31398 277 0 0
sp_kill_cost_storno 17427 17427 0 0.00 0 6862 49 0 0
sp_kill_qstorno_ret_qs2qd 1183481 1183481 0 0.00 0 59011 27 0 0
sp_kill_qty_storno 623969 623969 0 0.00 0 59017 59 0 0
sp_lock_selected_doc 21672 21672 0 0.00 0 7279 6 0 0
sp_make_cost_storno 29254 29254 0 0.00 0 8083 45 0 0
sp_multiply_rows_for_pdistr 29254 29254 0 0.00 0 6137 29 0 0
sp_multiply_rows_for_qdistr 21550 21550 0 0.00 0 57749 149 0 0
sp_pay_from_customer 7437 7437 0 0.00 4 14275 104 0 0
sp_pay_to_supplier 6093 6093 0 0.00 4 8530 124 0 0
sp_payment_common 13530 13530 0 0.00 1 14271 30 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 9781 269 0 0
sp_qd_handle_on_invoice_upd_sts 11156 11156 0 0.00 0 25554 307 0 0
sp_qd_handle_on_reserve_upd_sts 10545 10545 0 0.00 0 27731 50 0 0
sp_reserve_write_off 10545 10545 0 0.00 6 27747 164 0 0
sp_supplier_invoice 5755 5755 0 0.00 6 96890 3233 0 0
sp_supplier_order 5773 5773 0 0.00 5 38346 1208 0 0
srv_aggregate_perf_data 106 106 0 0.00 2366 407546 18550 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1424 143282 47703 0 0
srv_find_qd_qs_mism 1101494 1101494 0 0.00 0 12040 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 32 34973 1113 0 0
srv_make_money_saldo 803 803 0 0.00 0 577 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 106744 292612 214754 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5197 5197 0 0.00 0 13976 139 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 7586 112 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 2177 7 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 2992 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 4722 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 731 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 3807 23 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 34867 1437 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 2395 9 0 0
t$perf-norm:sp_client_order 21550 21550 0 0.00 0 16372 16 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2155 7 0 0
t$perf-norm:sp_pay_from_customer 7437 7437 0 0.00 0 6401 8 0 0
t$perf-norm:sp_pay_to_supplier 6093 6093 0 0.00 0 5645 7 0 0
t$perf-norm:sp_reserve_write_off 10545 10545 0 0.00 0 5022 9 0 0
t$perf-norm:sp_supplier_invoice 5755 5755 0 0.00 0 6594 16 0 0
t$perf-norm:sp_supplier_order 5773 5773 0 0.00 0 3466 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 71 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 310 9 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 1899 21 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 2582 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10540 10354 186 1.76 0 9523 20 0 186
v_all_customers 39314 39314 0 0.00 0 30 0 0 0
v_all_suppliers 31754 31754 0 0.00 0 136 1 0 0
v_all_wares 43100 43100 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1803 11 0 0
v_cancel_client_order 3728 3728 0 0.00 0 2937 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2944 5 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1089 5 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 625 3 0 0
v_cancel_supplier_invoice 782 770 12 1.53 0 7895 23 0 12
v_cancel_supplier_order 838 838 0 0.00 0 1152 4 0 0
v_cancel_write_off 2128 2128 0 0.00 0 3218 18 0 0
v_max_id_clo_ord 5773 5773 0 0.00 0 459 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 3140 23 0 0
v_max_id_ord_sup 5753 5753 0 0.00 0 4898 4 0 0
v_max_non_paid_invoice 1742 1742 0 0.00 0 2028 4 0 0
v_max_non_paid_realizn 3912 3912 0 0.00 0 3572 5 0 0
v_min_id_clo_ord 5773 5773 0 0.00 0 2745 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 4084 10 0 0
v_min_id_ord_sup 5753 5753 0 0.00 0 5021 6 0 0
v_min_non_paid_invoice 6093 6093 0 0.00 0 6175 17 0 0
v_min_non_paid_realizn 7437 7437 0 0.00 0 14269 25 0 0
v_our_firm 10836 10836 0 0.00 0 13 1 0 0
v_reserve_write_off 21090 21090 0 0.00 0 6828 7 0 0
x_lock_depdocs_on_canc_invoice 12139 12139 0 0.00 0 30595 103 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 18766 441 0 0
x_make_qstorno_1000_1200 5773 5773 0 0.00 1 38338 1131 0 0
x_make_qstorno_1200_2000 5755 5755 0 0.00 3 96874 3100 0 0
x_make_qstorno_2100_3300 478107 478107 0 0.00 0 55981 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2021 01:39:14 to 30-12-2021 01:39:18.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:02 5 8192000000 8803586368 8816402432 9105920 0.111 3 1 2 0 9225344 60640 58784
23:09:03 30183 8192000000 9904273920 10217312256 981461840 10.698 100 84 168 0 1010421168 6577472 22381856
23:10:43 28510 8192000000 9946100880 10270482432 1031398592 11.182 100 86 172 2 1062640032 8964048 22277392
23:12:16 10222 8192000000 9989858912 10326769664 1072736352 11.578 100 80 160 1 1103105904 10374768 19994784
23:13:39 14250 8192000000 10040462384 10391924736 1128529792 12.108 100 90 179 1 1162609200 12790256 21289152
23:14:55 5842 8192000000 10091941760 10455891968 1179641968 12.587 100 76 148 1 1209793216 13127200 17024048
23:16:03 4494 8192000000 10137411152 10512752640 1232896192 13.081 100 79 158 2 1262713872 12707360 17110320
23:17:09 2168 8192000000 10179535696 10559946752 1267841808 13.402 100 83 165 3 1299353952 13647552 17864592
23:18:12 1749 8192000000 10213855264 10597871616 1294690352 13.647 100 86 172 2 1325216192 11165520 19360320
23:19:18 1435 8192000000 10224061008 10610176000 1308407840 13.772 100 89 178 2 1340714160 11005536 21300784
23:20:20 4831 8192000000 10198574176 10609037312 1311770384 13.802 100 24 48 0 1323876000 6757408 5348208
23:21:27 11383 8192000000 10203409104 10621030400 1319725552 13.874 100 21 42 2 1329923248 5325776 4871920
23:22:39 11782 8192000000 10214506288 10633801728 1329054448 13.959 100 21 42 1 1340099200 6091296 4953456
23:23:53 29667 8192000000 10246494336 10648092672 1332941184 13.994 100 73 146 2 1355946848 6109536 16896128
23:25:23 6275 8192000000 10232879360 10654674944 1339370464 14.052 100 25 50 2 1351490720 6500944 5619312
23:26:30 10103 8192000000 10237176848 10659966976 1341261424 14.069 100 21 42 1 1355818048 9722160 4834464
23:27:41 38723 8192000000 10186540064 10607652864 1234774736 13.098 93 22 43 1 1244654496 4451536 5428224
23:29:22 6763 8192000000 10169172128 10579206144 1249816800 13.237 97 17 34 1 1258640128 4928816 3894512
23:30:30 5281 8192000000 10130942976 10517282816 1206079856 12.833 97 22 43 0 1217267120 4991200 6196064
23:31:35 68459 8192000000 10153067552 10560696320 1252919632 13.265 99 32 64 1 1265610928 4966384 7724912
23:33:44 8521 8192000000 10168979072 10575060992 1274271104 13.461 100 27 54 1 1286608800 6234512 6103184
23:34:56 8480 8192000000 10183814976 10597416960 1291313744 13.616 100 26 52 0 1303915552 6076064 6525744
23:36:16 18095 8192000000 10185619616 10608336896 1304241680 13.734 100 33 61 3 1317622000 5350112 8030208
23:37:37 15819 8192000000 10205195232 10618974208 1310911072 13.794 100 27 54 2 1323687328 6456400 6319856
23:39:07 10744 8192000000 10246734272 10661371904 1347262240 14.123 100 39 78 1 1363912816 7675280 8975296
23:40:23 32280 8192000000 10275810528 10693390336 1364165984 14.275 100 44 88 0 1383239984 9300160 9773840
23:41:58 11201 8192000000 10297413264 10714374144 1374531360 14.368 100 55 109 2 1402993424 15068848 13393216
23:43:14 10536 8192000000 10318193360 10731180032 1388613536 14.493 100 47 94 3 1419300512 19818288 10868688
23:44:29 36722 8192000000 10316064656 10743001088 1398803520 14.584 100 40 80 0 1429144960 21582208 8759232
23:46:10 10549 8192000000 10331134544 10756583424 1410747376 14.691 100 43 86 0 1443402160 22761952 9892832
23:47:24 7393 8192000000 10350112272 10774921216 1419790960 14.771 100 63 123 2 1462610240 28291664 14527616
23:48:35 5395 8192000000 10361783408 10787397632 1428969696 14.852 100 64 125 1 1474359760 29859392 15530672
23:49:48 6621 8192000000 10353186624 10774646784 1410918336 14.692 100 73 146 1 1465599424 36403232 18277856
23:51:03 2517 8192000000 10362006944 10771742720 1406337360 14.651 100 81 161 2 1465992768 39555152 20100256
23:52:08 2379 8192000000 10338992880 10747834368 1381021696 14.426 99 76 153 0 1438282160 37999712 19260752
23:53:15 1422 8192000000 10349743824 10753613824 1387228960 14.481 100 92 184 2 1449510224 38353648 23927616
23:54:21 7620 8192000000 10325018896 10747707392 1376927888 14.389 100 56 108 1 1433413152 42490848 13994416
23:55:33 16321 8192000000 10283050160 10713489408 1329754960 13.965 98 34 68 1 1377411168 38077344 9578864
23:56:53 23122 8192000000 10267362560 10693283840 1337420736 14.034 99 30 60 1 1369885216 24957376 7507104
23:58:22 5590 8192000000 10260948512 10680082432 1312760800 13.811 98 44 87 0 1353690256 30159984 10769472
23:59:33 9784 8192000000 10270870688 10702934016 1354378576 14.187 100 27 54 2 1383174656 21890416 6905664
00:00:47 59002 8192000000 10262251456 10688176128 1328986640 13.958 98 33 66 1 1356976208 18648448 9341120
00:02:50 35782 8192000000 10262590128 10693431296 1339037408 14.049 99 18 36 1 1361209600 17727040 4445152
00:04:31 46459 8192000000 10271542176 10698932224 1344630704 14.099 100 34 65 1 1371357872 18469392 8257776
00:06:19 15704 8192000000 10267947072 10708316160 1354921520 14.192 100 20 40 0 1372982256 13563760 4496976
00:07:35 48085 8192000000 10276797664 10731016192 1346258304 14.114 99 8 16 0 1365455648 17362592 1834752
00:09:47 26048 8192000000 10322018416 10743189504 1381697232 14.432 100 60 116 3 1408552992 13800096 13055664
00:11:22 112925 8192000000 10296394528 10744918016 1370215760 14.329 99 10 20 1 1392773760 20371408 2186592
00:14:19 107383 8192000000 10312968896 10773598208 1366822800 14.299 98 23 44 1 1390414208 18400160 5191248
00:17:07 58158 8192000000 10323503136 10777010176 1395262512 14.553 100 22 43 1 1425714720 25588000 4864208
00:19:05 41222 8192000000 10312276304 10760122368 1390236336 14.508 99 44 88 1 1416820656 15876352 10707968
00:20:55 74826 8192000000 10307877248 10777112576 1386103136 14.471 99 15 30 1 1409407232 19448992 3855104
00:23:29 20711 8192000000 10316600032 10769752064 1398795264 14.584 99 39 78 1 1419504688 11197120 9512304
00:24:57 24507 8192000000 10314981760 10780692480 1403612256 14.627 100 27 54 0 1426749696 16584912 6552528
00:26:32 30643 8192000000 10306578256 10778267648 1405525440 14.644 100 6 12 1 1418937792 11992512 1419840
00:28:03 33498 8192000000 10296626656 10760716288 1374472800 14.367 98 12 24 0 1385912752 8798800 2641152
00:29:37 59487 8192000000 10290722464 10760036352 1373038592 14.354 98 14 25 0 1385651120 10107392 2505136
00:31:42 46827 8192000000 10306020000 10765938688 1368262304 14.311 97 9 18 1 1383410944 12866112 2282528
00:33:30 48556 8192000000 10310367472 10773176320 1407109344 14.658 100 26 51 1 1426776800 13433888 6233568
00:35:22 91029 8192000000 10279040000 10745356288 1353019392 14.175 97 19 38 0 1367635408 10393968 4222048
00:37:53 40567 8192000000 10295667280 10740604928 1355945344 14.201 98 31 62 1 1370373648 7841968 6586336
00:39:37 51227 8192000000 10265929104 10732630016 1328446384 13.953 96 13 26 1 1344540688 13079360 3014944
00:41:28 52118 8192000000 10279597664 10733170688 1363726880 14.271 99 13 26 0 1382280048 15840192 2712976
00:43:21 29324 8192000000 10295171328 10745118720 1351454560 14.161 97 24 48 1 1369510944 12204448 5851936
00:44:50 39186 8192000000 10267941616 10719522816 1299700304 13.693 94 25 50 0 1316016144 10842672 5473168
00:46:58 103686 8192000000 10284304816 10755563520 1354102640 14.184 99 20 40 0 1375917024 17747552 4066832
00:50:07 70835 8192000000 10278394384 10723606528 1327929312 13.948 97 14 28 0 1343785840 12741808 3114720
00:52:18 86927 8192000000 10271056240 10722795520 1353779152 14.181 99 25 50 0 1374903728 15545664 5578912
00:54:54 42863 8192000000 10280175232 10707943424 1337523328 14.035 98 51 102 1 1362579952 13948592 11108032
00:56:54 89667 8192000000 10288661984 10747330560 1362362064 14.259 99 18 37 0 1383752112 17287648 4102400
00:59:32 57423 8192000000 10268149808 10727374848 1343155920 14.086 98 18 36 1 1364380240 17055840 4168480
01:01:29 88234 8192000000 10273569120 10738544640 1368812480 14.316 100 18 35 1 1386788368 13848544 4127344
01:04:02 64759 8192000000 10274524736 10741182464 1346181264 14.113 98 21 42 0 1361455856 10547632 4726960
01:06:18 47987 8192000000 10284632464 10743652352 1379423504 14.411 100 31 62 1 1396011328 8900448 7687376
01:08:06 32150 8192000000 10316635888 10756177920 1382324480 14.437 100 40 80 1 1399082976 7886064 8872432
01:09:42 56605 8192000000 10302424800 10758557696 1391693888 14.521 100 18 36 1 1409028128 13024704 4309536
01:11:39 143282 8192000000 10287855536 10751946752 1310824128 13.794 94 17 34 0 1323354976 8421536 4109312
01:15:04 91574 8192000000 10290384000 10741899264 1353449024 14.178 97 7 14 0 1365418176 10348560 1620592
01:17:36 124745 8192000000 10294900064 10745397248 1360254320 14.240 98 21 42 0 1379981616 14904544 4822752
01:20:46 80676 8192000000 10285615520 10748444672 1361305344 14.249 98 21 42 1 1381209440 14881632 5022464
01:23:08 67908 8192000000 10245431696 10703880192 1294870128 13.649 94 9 18 0 1303758224 6922448 1965648
01:25:16 49395 8192000000 10248872224 10699358208 1289949344 13.604 95 22 45 0 1301673744 7043200 4681200
01:27:13 76713 8192000000 10264323600 10726191104 1363720384 14.271 100 10 20 0 1377508960 11291120 2497456
01:29:48 45734 8192000000 10256514752 10728714240 1348876496 14.137 99 14 26 1 1366987344 15032784 3078064
01:31:34 69981 8192000000 10274213552 10728919040 1332593152 13.991 97 18 36 2 1348869808 12043440 4233216
01:34:09 72986 8192000000 10250623056 10715418624 1315556464 13.836 96 13 26 0 1335109424 16233104 3319856
01:37:03 47609 8192000000 10268214800 10717327360 1335177136 14.014 98 29 58 0 1353895968 12343760 6375072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-12-2021 01:39:19 to 30-12-2021 01:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-12-2021 01:39:35 to 30-12-2021 01:39:37.

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          244410
MON$OLDEST_ACTIVE               246140
MON$OLDEST_SNAPSHOT             246140
MON$NEXT_TRANSACTION            246140
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                       2039712
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Thu Dec 30 01:38:00 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Thu Dec 30 01:38:00 2021
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Thu Dec 30 01:38:05 2021
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
--- end of diff output ---

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

30.12.21 01:39:38. 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-12-30 01:39:40 No severe PSQL-related problems occured
30.12.21 01:39:42. End of report.