Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 22: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 16302764 kB
MemAvailable 31355900 kB
Buffers 0 kB
Cached 15334244 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-24 22:37:58
DTS_END                         2021-12-24 22:37:58.0110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39064
TRN_ID                          248540
ATT_ID                          649
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: 9670.44 20346 1160453
customer order: creation 183.66 344 22039
customer order: refuse 15.46 388 1855
order to supplier: creation 48.42 1252 5810
order to supplier: removal 3.55 90556 426
invoice (draft): creation 48.89 3311 5867
invoice (draft): removal 54.80 716 6576
invoice accept: apply 44.15 16687 5298
invoice accept: cancel 49.28 832 5914
customer reserve: creation 4055.91 165 486709
customer reserve: removal 4852.96 52 582355
realization accept: apply 87.62 184 10514
realization accept: cancel 88.79 104 10655
payment from customer: creation 63.20 122 7584
payment from customer: removal 5.67 94 680
payment to supplier: creation 51.51 138 6181
payment to supplier: removal 3.64 85 437
service: total inventory turnovers 4.82 1335 578
service: total monetary turnovers 7.22 37 866
service: aggregate perf. data 0.88 20577 105
service: refresh index statistics 0.03 269947 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-12-2021 22:38:55 to 24-12-2021 22:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3838
TEST_TIME 2 3977
TEST_TIME 3 3346
TEST_TIME 4 3818
TEST_TIME 5 4275
TEST_TIME 6 4610
TEST_TIME 7 5223
TEST_TIME 8 5304
TEST_TIME 9 5677
TEST_TIME 10 5693
TEST_TIME 11 6205
TEST_TIME 12 6229
TEST_TIME 13 6448
TEST_TIME 14 6555
TEST_TIME 15 6788
TEST_TIME 16 7174
TEST_TIME 17 7391
TEST_TIME 18 7662
TEST_TIME 19 8110
TEST_TIME 20 8127
TEST_TIME 21 8545
TEST_TIME 22 8983
TEST_TIME 23 9627
TEST_TIME 24 9719
TEST_TIME 25 10091
TEST_TIME 26 10217
TEST_TIME 27 10765
TEST_TIME 28 10779
TEST_TIME 29 10702
TEST_TIME 30 10515
TEST_TIME 31 10454
TEST_TIME 32 10464
TEST_TIME 33 10439
TEST_TIME 34 10360
TEST_TIME 35 10256
TEST_TIME 36 10415
TEST_TIME 37 10356
TEST_TIME 38 10505
TEST_TIME 39 10480
TEST_TIME 40 10319
TEST_TIME 41 10227
TEST_TIME 42 10085
TEST_TIME 43 10178
TEST_TIME 44 10133
TEST_TIME 45 10044
TEST_TIME 46 9994
TEST_TIME 47 9931
TEST_TIME 48 9943
TEST_TIME 49 9909
TEST_TIME 50 10035
TEST_TIME 51 9918
TEST_TIME 52 9848
TEST_TIME 53 9742
TEST_TIME 54 9683
TEST_TIME 55 9756
TEST_TIME 56 9826
TEST_TIME 57 9744
TEST_TIME 58 9747
TEST_TIME 59 9789
TEST_TIME 60 9724
TEST_TIME 61 9761
TEST_TIME 62 9825
TEST_TIME 63 9918
TEST_TIME 64 9946
TEST_TIME 65 9888
TEST_TIME 66 9972
TEST_TIME 67 9977
TEST_TIME 68 9919
TEST_TIME 69 9915
TEST_TIME 70 9996
TEST_TIME 71 10040
TEST_TIME 72 10107
TEST_TIME 73 9985
TEST_TIME 74 9976
TEST_TIME 75 9945
TEST_TIME 76 9879
TEST_TIME 77 9897
TEST_TIME 78 9840
TEST_TIME 79 9804
TEST_TIME 80 9733
TEST_TIME 81 9705
TEST_TIME 82 9676
TEST_TIME 83 9614
TEST_TIME 84 9668
TEST_TIME 85 9746
TEST_TIME 86 9730
TEST_TIME 87 9747
TEST_TIME 88 9766
TEST_TIME 89 9728
TEST_TIME 90 9702
TEST_TIME 91 9797
TEST_TIME 92 9816
TEST_TIME 93 9846
TEST_TIME 94 9828
TEST_TIME 95 9892
TEST_TIME 96 9954
TEST_TIME 97 9940
TEST_TIME 98 9890
TEST_TIME 99 9922
TEST_TIME 100 9849
TEST_TIME 101 9823
TEST_TIME 102 9807
TEST_TIME 103 9775
TEST_TIME 104 9747
TEST_TIME 105 9725
TEST_TIME 106 9694
TEST_TIME 107 9652
TEST_TIME 108 9614
TEST_TIME 109 9585
TEST_TIME 110 9540
TEST_TIME 111 9625
TEST_TIME 112 9594
TEST_TIME 113 9618
TEST_TIME 114 9650
TEST_TIME 115 9703
TEST_TIME 116 9702
TEST_TIME 117 9693
TEST_TIME 118 9701
TEST_TIME 119 9705
TEST_TIME 120 9672

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-12-2021 22:38:59 to 24-12-2021 22:39:03.

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 1611532 1611532 0 0.00 0 22515 6 0 0
doc_list_biud 1611531 1611531 0 0.00 0 33462 24 0 0
sp_add_invoice_to_stock 5395 5298 97 1.79 43 165170 16687 0 97
sp_cancel_adding_invoice 5914 5914 0 0.00 4 59621 832 0 0
sp_cancel_client_order 1855 1855 0 0.00 4 17075 388 0 0
sp_cancel_customer_reserve 582355 582355 0 0.00 1 33527 52 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 7934 95 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 6952 85 0 0
sp_cancel_supplier_invoice 6583 6576 7 0.10 1 19889 717 0 7
sp_cancel_supplier_order 426 426 0 0.00 5 1118345 90556 0 0
sp_cancel_write_off 10655 10655 0 0.00 1 18463 105 0 0
sp_client_order 22039 22039 0 0.00 3 17112 344 0 0
sp_customer_reserve 486709 486709 0 0.00 0 133967 165 0 0
sp_fill_shopping_cart 33702 33702 0 0.00 1 12096 37 0 0
sp_fill_shopping_cart_clo_res 486707 486707 0 0.00 0 16593 13 0 0
sp_get_clo_for_invoice 5296 5296 0 0.00 0 25240 279 0 0
sp_kill_cost_storno 17686 17686 0 0.00 0 12154 52 0 0
sp_kill_qstorno_ret_qs2qd 1182367 1182367 0 0.00 0 33397 28 0 0
sp_kill_qty_storno 623590 623590 0 0.00 0 33462 62 0 0
sp_lock_selected_doc 21828 21828 0 0.00 0 7930 5 0 0
sp_make_cost_storno 29575 29575 0 0.00 0 20198 53 0 0
sp_multiply_rows_for_pdistr 29574 29574 0 0.00 0 19054 32 0 0
sp_multiply_rows_for_qdistr 22039 22039 0 0.00 0 12132 153 0 0
sp_pay_from_customer 7584 7584 0 0.00 4 16928 123 0 0
sp_pay_to_supplier 6181 6181 0 0.00 5 15150 138 0 0
sp_payment_common 13765 13765 0 0.00 2 13414 43 0 0
sp_qd_handle_on_cancel_clo 1855 1855 0 0.00 0 17069 360 0 0
sp_qd_handle_on_invoice_upd_sts 11210 11210 0 0.00 0 17938 327 0 0
sp_qd_handle_on_reserve_upd_sts 10513 10513 0 0.00 0 17798 67 0 0
sp_reserve_write_off 10514 10514 0 0.00 6 18875 184 0 0
sp_supplier_invoice 5867 5867 0 0.00 6 96702 3311 0 0
sp_supplier_order 5810 5810 0 0.00 5 46804 1253 0 0
srv_aggregate_perf_data 105 105 0 0.00 2879 124953 20577 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1480 186726 49565 0 0
srv_find_qd_qs_mism 1110127 1110127 0 0.00 0 19839 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 41 135054 1335 0 0
srv_make_money_saldo 866 866 0 0.00 1 5618 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 185799 356670 269948 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5299 5299 0 0.00 0 16429 178 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 1 18558 165 0 0
t$perf-norm:sp_cancel_client_order 1855 1855 0 0.00 0 12093 18 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 2274 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 843 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2263 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 1624 25 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 31478 1724 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 3001 15 0 0
t$perf-norm:sp_client_order 22039 22039 0 0.00 0 21923 25 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 1743 6 0 0
t$perf-norm:sp_pay_from_customer 7584 7584 0 0.00 0 17107 17 0 0
t$perf-norm:sp_pay_to_supplier 6181 6181 0 0.00 0 17129 16 0 0
t$perf-norm:sp_reserve_write_off 10514 10514 0 0.00 0 18610 22 0 0
t$perf-norm:sp_supplier_invoice 5867 5867 0 0.00 0 13824 19 0 0
t$perf-norm:sp_supplier_order 5810 5810 0 0.00 0 9220 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 78 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 25 1 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 938 20 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 2068 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10786 10592 194 1.79 0 8208 18 0 194
v_all_customers 40088 40088 0 0.00 0 125 1 0 0
v_all_suppliers 32370 32370 0 0.00 0 125 1 0 0
v_all_wares 44078 44078 0 0.00 0 268 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 836 6 0 0
v_cancel_client_order 3710 3710 0 0.00 0 4735 5 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2610 8 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 986 4 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 6939 17 0 0
v_cancel_supplier_invoice 906 892 14 1.54 0 901 12 0 14
v_cancel_supplier_order 852 852 0 0.00 0 311 3 0 0
v_cancel_write_off 2076 2076 0 0.00 0 7570 20 0 0
v_max_id_clo_ord 5809 5809 0 0.00 0 716 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1383 8 0 0
v_max_id_ord_sup 5854 5854 0 0.00 0 3725 3 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 3915 10 0 0
v_max_non_paid_realizn 4075 4075 0 0.00 0 2655 5 0 0
v_min_id_clo_ord 5809 5809 0 0.00 0 4296 10 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 826 3 0 0
v_min_id_ord_sup 5854 5854 0 0.00 0 6350 10 0 0
v_min_non_paid_invoice 6181 6181 0 0.00 0 13410 36 0 0
v_min_non_paid_realizn 7584 7584 0 0.00 0 10234 32 0 0
v_our_firm 11008 11008 0 0.00 0 15 1 0 0
v_reserve_write_off 21026 21026 0 0.00 0 8004 6 0 0
x_lock_depdocs_on_canc_invoice 12044 12044 0 0.00 0 20574 113 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 13640 365 0 0
x_make_qstorno_1000_1200 5810 5810 0 0.00 1 46751 1172 0 0
x_make_qstorno_1200_2000 5867 5867 0 0.00 3 92990 3179 0 0
x_make_qstorno_2100_3300 486709 486709 0 0.00 0 133967 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 22:39:04 to 24-12-2021 22:39:08.

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
20:08:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:02 26264 8192000000 9914294736 10228289536 984279328 10.726 100 84 168 0 1012586864 5780272 22527264
20:10:37 21235 8192000000 9949210880 10272817152 1030967184 11.178 100 86 172 0 1061395232 7946272 22481776
20:12:04 15676 8192000000 9981460576 10317221888 1068774064 11.540 100 76 152 0 1096661984 8648384 19239536
20:13:21 12928 8192000000 10035046128 10383806464 1121329952 12.040 100 77 154 0 1149726544 9967056 18429536
20:14:39 9851 8192000000 10090835056 10449887232 1175583552 12.549 100 82 164 0 1206666736 12546272 18536912
20:15:55 5735 8192000000 10134349296 10503319552 1218662976 12.949 100 85 169 0 1250813344 13673232 18477136
20:17:03 2900 8192000000 10169297952 10542678016 1250075280 13.239 100 90 178 1 1283935904 14839936 19020688
20:18:08 1618 8192000000 10192716544 10573770752 1274376304 13.462 100 88 179 1 1307193888 12980560 19837024
20:19:11 15778 8192000000 10202291584 10593067008 1291225952 13.615 100 72 141 3 1319096848 12017664 15853232
20:20:27 4607 8192000000 10188521760 10595360768 1295314144 13.653 100 49 98 0 1315909648 9997792 10597712
20:21:33 29488 8192000000 10223632272 10615623680 1303132704 13.724 100 76 152 1 1326205184 5760624 17311856
20:23:03 5753 8192000000 10201205664 10616786944 1309028176 13.777 100 27 54 1 1320724000 5862432 5833392
20:24:10 5572 8192000000 10203406512 10626117632 1319658032 13.874 100 25 50 1 1331752288 5739952 6354304
20:25:18 22282 8192000000 10209421120 10630975488 1321896640 13.894 100 20 39 2 1330744960 5238960 3609360
20:27:09 3978 8192000000 10208761488 10627096576 1317054272 13.850 100 28 56 0 1329911888 6578080 6279536
20:28:16 5093 8192000000 10199563440 10615898112 1304514384 13.736 99 19 38 1 1314467616 5640608 4312624
20:29:22 6610 8192000000 10149166704 10546688000 1198209248 12.760 94 22 44 2 1208273296 4934080 5129968
20:30:29 35886 8192000000 10135074208 10541260800 1169560752 12.493 93 30 60 0 1181753456 4799760 7392944
20:32:10 7976 8192000000 10143476288 10541649920 1210653264 12.875 96 21 42 0 1225767920 10386784 4727872
20:33:18 7070 8192000000 10157857888 10560249856 1244930368 13.192 98 36 73 0 1258771568 5098608 8742592
20:34:27 49373 8192000000 10172953840 10581929984 1282632016 13.537 100 25 50 0 1293506992 4984704 5890272
20:36:17 7005 8192000000 10189264384 10595954688 1291607424 13.619 100 24 49 0 1303668336 6917248 5143664
20:37:26 7849 8192000000 10202947952 10612875264 1302625552 13.719 100 24 47 1 1317932208 9764352 5542304
20:38:40 11994 8192000000 10243942016 10646294528 1331101856 13.977 100 44 86 0 1347813392 7745728 8965808
20:39:59 105537 8192000000 10258200032 10679173120 1353185616 14.176 100 40 78 3 1373076912 10490320 9400976
20:43:01 14961 8192000000 10277966832 10697211904 1367996592 14.309 100 32 64 3 1387545216 11551312 7997312
20:44:20 12722 8192000000 10284222160 10709450752 1366375792 14.295 99 39 78 0 1390769296 15644480 8749024
20:45:38 22043 8192000000 10328120912 10749263872 1398995264 14.586 100 55 110 0 1436624432 24347008 13282160
20:47:06 11967 8192000000 10326409664 10753675264 1395351952 14.554 99 49 96 0 1431733632 25195744 11185936
20:48:24 2694 8192000000 10346624544 10760585216 1405789040 14.647 100 81 163 3 1455310192 29747760 19773392
20:49:31 3311 8192000000 10341380080 10749652992 1397895232 14.576 100 79 156 2 1444360720 28064464 18401024
20:50:44 3326 8192000000 10345824224 10764062720 1407696400 14.663 100 66 131 0 1452004672 28069840 16238432
20:51:54 2260 8192000000 10339026576 10752278528 1396324208 14.562 100 74 148 0 1449419920 33764128 19331584
20:53:00 1478 8192000000 10349341744 10755780608 1394975296 14.550 100 83 166 1 1453127264 38117200 20034768
20:54:07 4649 8192000000 10354082336 10763108352 1398237296 14.579 100 80 158 1 1457784176 39142176 20404704
20:55:19 6389 8192000000 10333082336 10748149760 1375973296 14.381 99 60 120 2 1433799712 41695792 16130624
20:56:29 7071 8192000000 10330970400 10751676416 1384817856 14.460 100 52 104 0 1436570848 37687616 14065376
20:57:44 3013 8192000000 10329472832 10735321088 1364505904 14.278 99 84 166 0 1431361984 45360512 21495568
20:58:50 18612 8192000000 10302942080 10718674944 1334796880 14.010 97 34 68 2 1388465264 44199584 9468800
21:00:15 17652 8192000000 10308486160 10746818560 1358439280 14.223 99 30 60 1 1409025920 41979136 8607504
21:01:38 18515 8192000000 10312796384 10749816832 1380310720 14.419 100 34 67 3 1420260944 30767344 9182880
21:03:01 18061 8192000000 10274895968 10708680704 1336777472 14.028 98 24 48 0 1365040192 21727776 6534944
21:04:27 55266 8192000000 10251398304 10701606912 1319521904 13.872 98 11 22 0 1333243024 11398464 2322656
21:07:23 75620 8192000000 10292696928 10722455552 1364550416 14.278 100 20 41 0 1383799808 14337344 4912048
21:09:42 73887 8192000000 10295217840 10742460416 1375673056 14.378 99 12 24 0 1386964848 9176752 2115040
21:11:56 43397 8192000000 10300371424 10743853056 1366600144 14.297 98 10 20 1 1376443056 8054048 1788864
21:13:41 186726 8192000000 10301530416 10745966592 1336138816 14.023 95 9 18 0 1354580624 16381344 2060464
21:18:00 112410 8192000000 10326199280 10772152320 1403328400 14.625 100 29 59 2 1420151136 9484272 7338464
21:20:56 38519 8192000000 10309323184 10756952064 1377143296 14.391 98 36 72 1 1396449248 10858080 8447872
21:22:39 19851 8192000000 10317254656 10763235328 1399522160 14.591 100 33 66 0 1414680528 7340000 7818368
21:24:03 5934 8192000000 10311599744 10758586368 1400443744 14.599 100 50 100 2 1421024032 8369552 12210736
21:25:12 23250 8192000000 10315345520 10769817600 1392995520 14.533 99 25 49 1 1412750288 13913840 5840928
21:26:37 67887 8192000000 10315826752 10788716544 1393533264 14.537 99 12 24 0 1417442080 20918768 2990048
21:28:48 99174 8192000000 10295896704 10748420096 1315303312 13.834 94 10 20 0 1333143520 15169184 2671024
21:31:28 77671 8192000000 10278767888 10727194624 1344687136 14.100 97 22 42 0 1363253120 12593952 5972032
21:33:48 52294 8192000000 10276448368 10735403008 1352285504 14.168 98 9 18 1 1368188672 14107072 1796096
21:35:47 65257 8192000000 10264864736 10729037824 1313583024 13.819 96 8 16 1 1330953520 15207392 2163104
21:37:54 53326 8192000000 10283037264 10725416960 1341186352 14.068 98 44 88 1 1364739984 13566784 9986848
21:39:52 63828 8192000000 10272125664 10726207488 1333852576 14.002 97 10 20 1 1353255824 17203824 2199424
21:42:31 12792 8192000000 10302921872 10733436928 1364507008 14.278 99 59 114 2 1385824464 7048592 14268864
21:43:46 72931 8192000000 10266917040 10718220288 1314525376 13.827 96 17 35 0 1327330496 9194208 3610912
21:46:29 40347 8192000000 10295593088 10734219264 1349713280 14.145 99 39 78 0 1373473696 15312992 8447424
21:48:43 90495 8192000000 10259799280 10716184576 1302125040 13.715 95 10 21 0 1318140432 13994800 2020592
21:51:41 105063 8192000000 10266096864 10726641664 1294596432 13.646 95 25 50 0 1317510400 17221792 5692176
21:54:27 58670 8192000000 10255748240 10714558464 1334529776 14.008 98 22 44 0 1352650176 13299488 4820912
21:56:31 60066 8192000000 10262801680 10717036544 1350489040 14.152 99 13 27 1 1362910416 9295168 3126208
21:58:36 62142 8192000000 10285138704 10741383168 1375241888 14.374 100 12 24 0 1394039520 15767072 3030560
22:00:54 71431 8192000000 10273463040 10742030336 1358917520 14.228 99 10 20 0 1377895168 16630256 2347392
22:03:05 78020 8192000000 10280157456 10729447424 1344007840 14.094 98 13 25 2 1359291680 12314144 2969696
22:06:02 13365 8192000000 10286930464 10726318080 1360910848 14.246 99 43 85 1 1376876464 6657696 9307920
22:07:19 20757 8192000000 10312190848 10756517888 1388414832 14.492 100 35 68 2 1409377440 13501824 7460784
22:08:41 94083 8192000000 10288233744 10741039104 1351833056 14.164 97 8 16 0 1362352160 8999200 1519904
22:11:15 97572 8192000000 10302756144 10768236544 1356979792 14.210 97 19 38 0 1373076832 11991104 4105936
22:13:54 113455 8192000000 10321674736 10766016512 1348877856 14.137 96 23 46 0 1369612656 15749904 4984896
22:16:54 128078 8192000000 10269957984 10724831232 1331199856 13.978 96 26 52 0 1350916832 13461840 6255136
22:20:21 64505 8192000000 10264928336 10729361408 1350964672 14.156 98 12 24 1 1362462560 8512144 2985744
22:22:33 75395 8192000000 10266214832 10730700800 1357960240 14.219 99 14 28 0 1372642560 11503472 3178848
22:24:53 28430 8192000000 10258951552 10709565440 1345538544 14.107 98 21 42 0 1359886656 9427200 4920912
22:26:25 48860 8192000000 10277481488 10735824896 1354522144 14.188 98 21 42 1 1377810944 17860128 5428672
22:28:15 90930 8192000000 10273763456 10732572672 1345101184 14.103 98 16 32 1 1359879616 11187024 3591408
22:30:47 53005 8192000000 10287981664 10752000000 1374893616 14.371 100 19 36 1 1393826544 14791168 4141760
22:32:46 37175 8192000000 10288388240 10733948928 1339834288 14.056 97 23 46 1 1354661600 9350496 5476816
22:34:31 25803 8192000000 10277102432 10713980928 1314888432 13.830 96 33 66 1 1330014416 6761264 8364720
22:35:57 79463 8192000000 10283722560 10729156608 1314434208 13.826 96 20 38 2 1328565920 9755856 4375856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 22:39:09 to 24-12-2021 22:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 22:39:25 to 24-12-2021 22:39:27.

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          246861
MON$OLDEST_ACTIVE               248864
MON$OLDEST_SNAPSHOT             248864
MON$NEXT_TRANSACTION            248864
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                       2058656
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:00 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:12 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:14 2021
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:17 2021
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:17 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Fri Dec 24 22:38:18 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

24.12.21 22:39:28. 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-24 22:39:30 No severe PSQL-related problems occured
24.12.21 22:39:32. End of report.