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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15109240 kB
MemAvailable 30409568 kB
Buffers 0 kB
Cached 16571428 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-20 19:38:40
DTS_END                         2023-02-20 19:38:40.5590
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/49380
TRN_ID                          243265
ATT_ID                          656
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: 9667.43 19637 1160092
customer order: creation 177.89 342 21347
customer order: refuse 15.38 313 1845
order to supplier: creation 47.28 1242 5674
order to supplier: removal 3.55 94442 426
invoice (draft): creation 47.57 3256 5708
invoice (draft): removal 55.86 708 6703
invoice accept: apply 42.58 16856 5109
invoice accept: cancel 50.31 749 6037
customer reserve: creation 3973.31 165 476797
customer reserve: removal 4947.78 53 593734
realization accept: apply 87.86 168 10543
realization accept: cancel 84.30 100 10116
payment from customer: creation 61.36 118 7363
payment from customer: removal 5.95 75 714
payment to supplier: creation 50.32 126 6038
payment to supplier: removal 3.67 50 440
service: total inventory turnovers 4.88 1065 585
service: total monetary turnovers 6.83 28 819
service: aggregate perf. data 0.75 25149 90
service: refresh index statistics 0.03 247731 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 19:39:31 to 20-02-2023 19:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3864
TEST_TIME 2 4033
TEST_TIME 3 7269
TEST_TIME 4 7140
TEST_TIME 5 6646
TEST_TIME 6 6059
TEST_TIME 7 5711
TEST_TIME 8 5420
TEST_TIME 9 5907
TEST_TIME 10 5957
TEST_TIME 11 6462
TEST_TIME 12 6531
TEST_TIME 13 6478
TEST_TIME 14 6383
TEST_TIME 15 6694
TEST_TIME 16 6975
TEST_TIME 17 7385
TEST_TIME 18 7285
TEST_TIME 19 7277
TEST_TIME 20 7552
TEST_TIME 21 7794
TEST_TIME 22 8055
TEST_TIME 23 8229
TEST_TIME 24 8367
TEST_TIME 25 8670
TEST_TIME 26 8644
TEST_TIME 27 8969
TEST_TIME 28 9575
TEST_TIME 29 9704
TEST_TIME 30 9605
TEST_TIME 31 9766
TEST_TIME 32 10360
TEST_TIME 33 10224
TEST_TIME 34 10166
TEST_TIME 35 10101
TEST_TIME 36 10102
TEST_TIME 37 10193
TEST_TIME 38 10131
TEST_TIME 39 10063
TEST_TIME 40 9944
TEST_TIME 41 10301
TEST_TIME 42 10311
TEST_TIME 43 10291
TEST_TIME 44 10317
TEST_TIME 45 10227
TEST_TIME 46 10029
TEST_TIME 47 10104
TEST_TIME 48 10042
TEST_TIME 49 10075
TEST_TIME 50 9951
TEST_TIME 51 9970
TEST_TIME 52 9883
TEST_TIME 53 9961
TEST_TIME 54 9969
TEST_TIME 55 9898
TEST_TIME 56 9873
TEST_TIME 57 9818
TEST_TIME 58 9712
TEST_TIME 59 9674
TEST_TIME 60 9638
TEST_TIME 61 9641
TEST_TIME 62 9566
TEST_TIME 63 9544
TEST_TIME 64 9618
TEST_TIME 65 9635
TEST_TIME 66 9726
TEST_TIME 67 9678
TEST_TIME 68 9747
TEST_TIME 69 9737
TEST_TIME 70 9782
TEST_TIME 71 9745
TEST_TIME 72 9749
TEST_TIME 73 9859
TEST_TIME 74 9893
TEST_TIME 75 9910
TEST_TIME 76 10010
TEST_TIME 77 9984
TEST_TIME 78 9889
TEST_TIME 79 9910
TEST_TIME 80 9832
TEST_TIME 81 9839
TEST_TIME 82 9808
TEST_TIME 83 9782
TEST_TIME 84 9743
TEST_TIME 85 9701
TEST_TIME 86 9633
TEST_TIME 87 9591
TEST_TIME 88 9600
TEST_TIME 89 9641
TEST_TIME 90 9586
TEST_TIME 91 9610
TEST_TIME 92 9638
TEST_TIME 93 9657
TEST_TIME 94 9743
TEST_TIME 95 9706
TEST_TIME 96 9699
TEST_TIME 97 9748
TEST_TIME 98 9746
TEST_TIME 99 9740
TEST_TIME 100 9771
TEST_TIME 101 9821
TEST_TIME 102 9838
TEST_TIME 103 9860
TEST_TIME 104 9825
TEST_TIME 105 9748
TEST_TIME 106 9699
TEST_TIME 107 9679
TEST_TIME 108 9673
TEST_TIME 109 9651
TEST_TIME 110 9655
TEST_TIME 111 9646
TEST_TIME 112 9596
TEST_TIME 113 9606
TEST_TIME 114 9648
TEST_TIME 115 9644
TEST_TIME 116 9674
TEST_TIME 117 9652
TEST_TIME 118 9664
TEST_TIME 119 9709
TEST_TIME 120 9669

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-02-2023 19:39:34 to 20-02-2023 19:39:38.

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 1601764 1601764 0 0.00 0 16515 5 0 0
doc_list_biud 1601764 1601764 0 0.00 0 82787 25 0 0
sp_add_invoice_to_stock 5223 5109 114 2.18 34 184872 16856 0 114
sp_cancel_adding_invoice 6037 6037 0 0.00 4 75674 749 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 8403 314 0 0
sp_cancel_customer_reserve 593734 593734 0 0.00 1 82788 54 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 5283 76 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 2545 50 0 0
sp_cancel_supplier_invoice 6710 6703 7 0.10 1 14953 708 0 7
sp_cancel_supplier_order 426 426 0 0.00 5 883289 94443 0 0
sp_cancel_write_off 10116 10116 0 0.00 1 15086 100 0 0
sp_client_order 21347 21347 0 0.00 3 25129 342 0 0
sp_customer_reserve 476797 476797 0 0.00 0 110346 165 0 0
sp_fill_shopping_cart 32708 32708 0 0.00 1 12144 34 0 0
sp_fill_shopping_cart_clo_res 476790 476790 0 0.00 0 19944 12 0 0
sp_get_clo_for_invoice 5102 5102 0 0.00 0 24343 262 0 0
sp_kill_cost_storno 17307 17307 0 0.00 0 9017 50 0 0
sp_kill_qstorno_ret_qs2qd 1204713 1204713 0 0.00 0 82783 29 0 0
sp_kill_qty_storno 634506 634506 0 0.00 0 82786 63 0 0
sp_lock_selected_doc 21549 21549 0 0.00 0 7928 6 0 0
sp_make_cost_storno 29046 29046 0 0.00 0 16510 51 0 0
sp_multiply_rows_for_pdistr 29046 29046 0 0.00 0 5750 26 0 0
sp_multiply_rows_for_qdistr 21347 21347 0 0.00 0 24812 145 0 0
sp_pay_from_customer 7363 7363 0 0.00 4 27505 118 0 0
sp_pay_to_supplier 6038 6038 0 0.00 4 15941 127 0 0
sp_payment_common 13401 13401 0 0.00 1 25075 33 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 8393 283 0 0
sp_qd_handle_on_invoice_upd_sts 11139 11139 0 0.00 0 21398 323 0 0
sp_qd_handle_on_reserve_upd_sts 10543 10543 0 0.00 0 17902 56 0 0
sp_reserve_write_off 10543 10543 0 0.00 6 17958 168 0 0
sp_supplier_invoice 5708 5708 0 0.00 6 103427 3256 0 0
sp_supplier_order 5674 5674 0 0.00 5 28348 1243 0 0
srv_aggregate_perf_data 90 90 0 0.00 3291 419486 25149 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1518 121463 44528 0 0
srv_find_qd_qs_mism 1110867 1110867 0 0.00 0 11737 4 0 0
srv_make_invnt_saldo 585 585 0 0.00 32 76764 1066 0 0
srv_make_money_saldo 819 819 0 0.00 0 2309 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 177081 317377 247731 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 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 5109 5109 0 0.00 0 15073 182 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 19288 219 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 4069 15 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 2330 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 2127 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 3200 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1094 12 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 23759 1576 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 1501 5 0 0
t$perf-norm:sp_client_order 21347 21347 0 0.00 0 17545 19 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 8766 17 0 0
t$perf-norm:sp_pay_from_customer 7363 7363 0 0.00 0 8558 10 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 9719 11 0 0
t$perf-norm:sp_reserve_write_off 10543 10543 0 0.00 0 11039 9 0 0
t$perf-norm:sp_supplier_invoice 5708 5708 0 0.00 0 3659 14 0 0
t$perf-norm:sp_supplier_order 5674 5674 0 0.00 0 7851 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 2620 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 154 3 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 7859 38 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 2721 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10430 10202 228 2.18 0 8353 20 0 228
v_all_customers 38706 38706 0 0.00 0 14 0 0 0
v_all_suppliers 31548 31548 0 0.00 0 22 1 0 0
v_all_wares 42694 42694 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2745 13 0 0
v_cancel_client_order 3690 3690 0 0.00 0 3638 6 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 791 3 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 5681 8 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 126 2 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 5575 20 0 14
v_cancel_supplier_order 852 852 0 0.00 0 116 3 0 0
v_cancel_write_off 2046 2046 0 0.00 0 1316 13 0 0
v_max_id_clo_ord 5674 5674 0 0.00 0 2804 2 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 3304 18 0 0
v_max_id_ord_sup 5687 5687 0 0.00 0 3865 3 0 0
v_max_non_paid_invoice 1625 1625 0 0.00 0 1933 3 0 0
v_max_non_paid_realizn 3962 3962 0 0.00 0 4437 5 0 0
v_min_id_clo_ord 5674 5674 0 0.00 0 10401 7 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 3764 8 0 0
v_min_id_ord_sup 5687 5687 0 0.00 0 5995 10 0 0
v_min_non_paid_invoice 6038 6038 0 0.00 0 8930 22 0 0
v_min_non_paid_realizn 7363 7363 0 0.00 0 16943 24 0 0
v_our_firm 10790 10790 0 0.00 0 19 1 0 0
v_reserve_write_off 21086 21086 0 0.00 0 7927 6 0 0
x_lock_depdocs_on_canc_invoice 12332 12332 0 0.00 0 11927 105 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 13116 386 0 0
x_make_qstorno_1000_1200 5674 5674 0 0.00 2 28334 1171 0 0
x_make_qstorno_1200_2000 5708 5708 0 0.00 3 103367 3131 0 0
x_make_qstorno_2100_3300 476797 476797 0 0.00 0 110344 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 19:39:39 to 20-02-2023 19:39:43.

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:44 4 8192000000 8804595312 8817639424 10309760 0.125 3 2 3 0 10452224 67504 74960
17:09:46 28641 8192000000 9913516160 10228842496 994494464 10.825 100 86 172 0 1023571936 6546688 22530784
17:11:20 22490 8192000000 9959458576 10284572672 1042881840 11.292 100 89 178 0 1074636640 8865616 22889184
17:12:46 13888 8192000000 9997778016 10332454912 1079738464 11.645 100 88 176 0 1111406592 9805136 21862992
17:14:05 9597 8192000000 10041171920 10390663168 1127861200 12.101 100 82 164 0 1159483680 11872304 19750176
17:15:32 6117 8192000000 10080607056 10441682944 1178078160 12.572 100 83 166 0 1209599184 12949856 18571168
17:16:44 3903 8192000000 10135367232 10508173312 1222184272 12.982 100 86 172 0 1254817312 13446592 19186448
17:17:50 2286 8192000000 10164330752 10542641152 1256825648 13.301 100 75 150 0 1285142400 11814112 16502640
17:18:54 1127 8192000000 10200538704 10584326144 1286802704 13.575 100 90 181 0 1319505984 13219568 19483712
17:19:56 31190 8192000000 10205440304 10599911424 1300102592 13.696 100 52 103 2 1319729760 7093344 12533824
17:21:28 5987 8192000000 10201346896 10609541120 1307336064 13.762 100 46 93 1 1327292800 9854640 10102096
17:22:36 6883 8192000000 10197320096 10619416576 1319992704 13.877 100 17 34 0 1329501872 5900320 3608848
17:23:45 14582 8192000000 10214943584 10634891264 1328201504 13.951 100 12 24 0 1336635520 5651168 2782848
17:25:04 34068 8192000000 10230245056 10644557824 1331563952 13.981 100 43 86 1 1347473680 6449808 9459920
17:26:41 6931 8192000000 10231695344 10649370624 1340911648 14.066 100 31 61 1 1354977264 6554352 7511264
17:27:52 4816 8192000000 10222551456 10634866688 1317076560 13.850 99 37 74 0 1332184240 6503680 8604000
17:29:00 30624 8192000000 10209823056 10616655872 1313241456 13.815 100 14 28 0 1321294128 4532736 3519936
17:30:32 3542 8192000000 10161657152 10561691648 1263841728 13.365 99 37 71 0 1279817744 7494592 8481424
17:31:36 6631 8192000000 10144570032 10540421120 1240815168 13.154 99 25 48 0 1251478608 5600992 5062448
17:32:45 5055 8192000000 10139095920 10536472576 1236979888 13.118 99 31 62 0 1250837840 6386928 7471024
17:33:50 31404 8192000000 10152503360 10561675264 1244462464 13.187 98 19 38 0 1254705040 5320272 4922304
17:35:27 9179 8192000000 10181169520 10593288192 1294370448 13.644 100 26 50 0 1306128992 5910256 5848288
17:36:36 7638 8192000000 10192991168 10604912640 1304811936 13.739 100 30 59 1 1317773200 5970128 6991136
17:37:44 10409 8192000000 10202565008 10619019264 1311898656 13.803 100 31 61 0 1325356064 5954288 7503120
17:38:56 50108 8192000000 10235416928 10651049984 1335088960 14.013 100 35 71 0 1349505312 6537024 7879328
17:40:55 6652 8192000000 10264317456 10676805632 1356307936 14.204 100 56 109 1 1377989632 9618432 12063264
17:42:06 23103 8192000000 10278107408 10694729728 1367525792 14.305 100 54 108 0 1390252320 10491808 12234720
17:43:34 14826 8192000000 10290033424 10714005504 1380487872 14.421 100 41 83 0 1401635712 10770128 10377712
17:44:59 28848 8192000000 10296673008 10726543360 1394602912 14.547 100 30 60 0 1414470640 12477120 7390608
17:46:32 25808 8192000000 10295773648 10724118528 1389045856 14.497 100 38 76 0 1413801776 15625904 9130016
17:48:10 10330 8192000000 10318406128 10748571648 1403930512 14.630 100 47 93 0 1429292560 14477936 10884112
17:49:33 11221 8192000000 10332036176 10763673600 1416521472 14.742 100 50 98 0 1454724384 26563728 11639184
17:50:48 10339 8192000000 10341302704 10766159872 1416771136 14.744 100 56 113 0 1451152768 20682400 13699232
17:52:02 4020 8192000000 10339243296 10759249920 1397992304 14.577 99 60 120 0 1440370416 26859856 15518256
17:53:12 4300 8192000000 10356853264 10774818816 1414054064 14.720 100 58 115 0 1461168336 32566144 14548128
17:54:25 2637 8192000000 10357193616 10777665536 1411370944 14.696 100 66 129 1 1461972720 34223392 16378384
17:55:32 5810 8192000000 10322724464 10737340416 1374644224 14.369 100 62 124 2 1424775488 35084304 15046960
17:56:42 5766 8192000000 10313370208 10726121472 1370442128 14.331 100 65 126 1 1421306688 35530288 15334272
17:58:03 1517 8192000000 10330075136 10734338048 1370613456 14.333 100 83 165 0 1429721376 38335376 20772544
17:59:11 7014 8192000000 10324978704 10753601536 1381032832 14.426 100 40 80 1 1434131328 42684160 10414336
18:00:24 11255 8192000000 10331621232 10750177280 1364903088 14.281 99 52 103 0 1431413664 52733232 13777344
18:01:38 10029 8192000000 10304703632 10732089344 1362321216 14.258 100 44 87 3 1417488080 43568848 11598016
18:02:51 13914 8192000000 10306010384 10734596096 1355133584 14.194 99 30 60 1 1404771472 41052528 8585360
18:04:13 3507 8192000000 10310627840 10731663360 1361690272 14.253 100 44 88 0 1415469984 41914352 11865360
18:05:24 9324 8192000000 10343145952 10770628608 1384246848 14.455 100 52 102 1 1451559360 53601536 13710976
18:06:36 12691 8192000000 10321792640 10761089024 1385343456 14.464 100 26 51 0 1426293312 33914560 7035296
18:08:15 36359 8192000000 10301202752 10736037888 1368744992 14.316 100 22 43 0 1407330592 33026640 5558960
18:09:54 44675 8192000000 10293800672 10736308224 1366486144 14.296 100 11 22 0 1395085872 25983296 2616432
18:12:05 31283 8192000000 10291759936 10732736512 1371750448 14.343 100 11 22 0 1390493520 16512400 2230672
18:13:47 97686 8192000000 10301819280 10742890496 1390186336 14.508 100 20 41 2 1408874512 14068400 4619776
18:16:27 80893 8192000000 10308068608 10753277952 1378749344 14.405 99 9 18 0 1388394640 7887408 1757888
18:18:56 82720 8192000000 10305780800 10772176896 1367315312 14.303 98 12 24 0 1393234480 23452992 2466176
18:21:34 89759 8192000000 10316860480 10768416768 1401308240 14.607 100 10 21 0 1419258528 15887744 2062544
18:24:16 104539 8192000000 10326092256 10788143104 1415860160 14.736 100 33 66 1 1435973552 12126096 7987296
18:27:04 121463 8192000000 10325256512 10774011904 1369502560 14.323 97 21 42 0 1386920512 12987392 4430560
18:30:15 55019 8192000000 10333307568 10780225536 1400223728 14.597 99 32 64 0 1419068752 10764608 8080416
18:32:13 36704 8192000000 10312921152 10781700096 1410851472 14.692 100 14 28 0 1424635920 10335952 3448496
18:33:51 42129 8192000000 10281856704 10748923904 1361634048 14.252 97 6 12 0 1372630080 9313392 1682640
18:35:33 49892 8192000000 10304232880 10772504576 1399539264 14.591 100 7 14 0 1413717808 12816560 1361984
18:37:28 51621 8192000000 10304568048 10770309120 1370702000 14.333 97 22 44 1 1390155520 15013120 4440400
18:40:24 44632 8192000000 10293507312 10760163328 1378091600 14.399 98 20 40 0 1393805264 11166944 4546720
18:42:14 66530 8192000000 10259517344 10716016640 1306812832 13.757 95 16 32 1 1322905936 12340064 3753040
18:44:21 34291 8192000000 10262328480 10715131904 1347339808 14.124 99 32 64 0 1366677344 11959072 7378464
18:45:59 63625 8192000000 10263191296 10708975616 1310455040 13.790 96 26 52 0 1325762240 9656208 5650992
18:48:02 43421 8192000000 10259178000 10704470016 1336576640 14.027 99 10 20 0 1352848960 13853888 2418432
18:49:49 39642 8192000000 10276745728 10723680256 1349091952 14.139 99 36 72 0 1374369184 17602304 7674928
18:51:29 118034 8192000000 10287973200 10738663424 1354942256 14.192 99 21 42 0 1374456656 14408080 5106320
18:55:03 77676 8192000000 10256527088 10711326720 1336901136 14.029 98 10 21 0 1352313520 13159984 2252400
18:57:25 84223 8192000000 10266216960 10725580800 1338652848 14.045 98 19 38 0 1354780560 12076960 4050752
18:59:51 39304 8192000000 10242843104 10696531968 1337921392 14.039 98 17 33 0 1349226672 7804752 3500528
19:01:32 71751 8192000000 10238106864 10688049152 1292884832 13.631 95 15 30 1 1302517792 6287104 3345856
19:04:30 73250 8192000000 10240579968 10689007616 1321640736 13.892 97 9 18 0 1331142112 7645552 1855824
19:06:44 49672 8192000000 10271708672 10729816064 1372141424 14.346 100 13 26 0 1386707584 11607216 2958944
19:08:35 57453 8192000000 10296695984 10750976000 1367913360 14.308 99 22 44 0 1386566800 13703664 4949776
19:10:32 71477 8192000000 10297771728 10759913472 1373975408 14.363 99 15 30 0 1392587520 15035456 3576656
19:12:49 31860 8192000000 10278120064 10731544576 1370183024 14.329 99 14 28 1 1385845856 12472048 3190784
19:14:22 19278 8192000000 10312459120 10759577600 1380856208 14.424 99 43 87 0 1399754128 9672896 9225024
19:15:42 70438 8192000000 10305664320 10769567744 1398956704 14.586 100 11 22 0 1413899488 12687024 2255760
19:17:52 44778 8192000000 10308573616 10760572928 1359148496 14.230 97 15 30 0 1377921456 15454960 3318000
19:19:56 75410 8192000000 10287275984 10741178368 1337199472 14.032 96 11 22 0 1351865552 12255344 2410736
19:22:20 76286 8192000000 10274802384 10738450432 1368044016 14.310 100 10 20 0 1385371824 14841792 2486016
19:24:38 68815 8192000000 10275637024 10735038464 1334678304 14.009 96 17 34 0 1352002992 13365232 3959456
19:26:50 56737 8192000000 10284379568 10748608512 1371491328 14.340 99 14 28 0 1391750400 16729792 3529280
19:29:31 61865 8192000000 10245000528 10698088448 1283909616 13.549 94 12 24 0 1299386672 12758480 2718576
19:31:41 44682 8192000000 10248969408 10720546816 1324079872 13.914 98 11 22 0 1346334304 19218672 3035760
19:33:29 19481 8192000000 10253103360 10706837504 1346011552 14.112 99 45 90 0 1369036864 12590272 10435040
19:34:58 85187 8192000000 10258235696 10714873856 1275511264 13.472 94 12 24 0 1292347216 14120192 2715760
19:37:23 62168 8192000000 10258156016 10696916992 1281543328 13.527 94 15 29 0 1295195312 9781632 3870352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2023 19:39:45 to 20-02-2023 19:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 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 20-02-2023 19:40:01 to 20-02-2023 19:40:03.

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          242199
MON$OLDEST_ACTIVE               244260
MON$OLDEST_SNAPSHOT             244260
MON$NEXT_TRANSACTION            244260
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                       2046112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:299: 
:300: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:41 2023
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
:303: 
:304: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:42 2023
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:306: 
:307: 
:308: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:42 2023
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
:311: 
:312: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:44 2023
:313:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:314: 
:315: 
:316: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:44 2023
:317:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:318: 
:319: 
:320: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:44 2023
:321:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:322: 
:323: 
:324: mscs-test-161.budzdorov.ru    Mon Feb 20 19:38:45 2023
:325:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:326: 
--- end of diff output ---

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

20.02.23 19:40:04. 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
2023-02-20 19:40:07 No severe PSQL-related problems occured
20.02.23 19:40:08. End of report.