Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2022 16:44
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 15597156 kB
MemAvailable 30634804 kB
Buffers 0 kB
Cached 16146164 kB
SwapCached 2028 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 2022-09-24 16:42:23
DTS_END                         2022-09-24 16:42:23.8510
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/43946
TRN_ID                          243300
ATT_ID                          724
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: 9367.14 20346 1124057
customer order: creation 177.59 357 21311
customer order: refuse 14.88 346 1785
order to supplier: creation 47.72 1232 5726
order to supplier: removal 3.61 88691 433
invoice (draft): creation 47.73 3060 5728
invoice (draft): removal 53.71 650 6445
invoice accept: apply 43.83 17406 5259
invoice accept: cancel 48.94 675 5873
customer reserve: creation 3927.48 177 471297
customer reserve: removal 4687.02 54 562442
realization accept: apply 87.11 165 10453
realization accept: cancel 92.37 100 11084
payment from customer: creation 61.43 95 7372
payment from customer: removal 6.28 77 754
payment to supplier: creation 50.82 120 6098
payment to supplier: removal 3.60 87 432
service: total inventory turnovers 5.18 1069 622
service: total monetary turnovers 6.91 23 829
service: aggregate perf. data 0.92 15472 110
service: refresh index statistics 0.03 277064 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-09-2022 16:44:27 to 24-09-2022 16:44:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4283
TEST_TIME 2 5079
TEST_TIME 3 5975
TEST_TIME 4 5381
TEST_TIME 5 5133
TEST_TIME 6 5234
TEST_TIME 7 4965
TEST_TIME 8 5049
TEST_TIME 9 5449
TEST_TIME 10 5493
TEST_TIME 11 5839
TEST_TIME 12 5970
TEST_TIME 13 6572
TEST_TIME 14 7098
TEST_TIME 15 7365
TEST_TIME 16 7348
TEST_TIME 17 7646
TEST_TIME 18 8385
TEST_TIME 19 8384
TEST_TIME 20 8555
TEST_TIME 21 8848
TEST_TIME 22 8881
TEST_TIME 23 9161
TEST_TIME 24 9696
TEST_TIME 25 9674
TEST_TIME 26 9836
TEST_TIME 27 10385
TEST_TIME 28 10699
TEST_TIME 29 10485
TEST_TIME 30 10594
TEST_TIME 31 10978
TEST_TIME 32 10816
TEST_TIME 33 10680
TEST_TIME 34 10454
TEST_TIME 35 10417
TEST_TIME 36 10378
TEST_TIME 37 10231
TEST_TIME 38 10225
TEST_TIME 39 10173
TEST_TIME 40 10116
TEST_TIME 41 10109
TEST_TIME 42 10157
TEST_TIME 43 10017
TEST_TIME 44 9975
TEST_TIME 45 9963
TEST_TIME 46 9893
TEST_TIME 47 9759
TEST_TIME 48 9762
TEST_TIME 49 9635
TEST_TIME 50 9577
TEST_TIME 51 9603
TEST_TIME 52 9496
TEST_TIME 53 9402
TEST_TIME 54 9662
TEST_TIME 55 9802
TEST_TIME 56 9814
TEST_TIME 57 9740
TEST_TIME 58 9807
TEST_TIME 59 9886
TEST_TIME 60 9872
TEST_TIME 61 9932
TEST_TIME 62 9912
TEST_TIME 63 9949
TEST_TIME 64 10002
TEST_TIME 65 10038
TEST_TIME 66 10033
TEST_TIME 67 9970
TEST_TIME 68 9850
TEST_TIME 69 9846
TEST_TIME 70 9818
TEST_TIME 71 9765
TEST_TIME 72 9765
TEST_TIME 73 9745
TEST_TIME 74 9684
TEST_TIME 75 9651
TEST_TIME 76 9693
TEST_TIME 77 9652
TEST_TIME 78 9665
TEST_TIME 79 9689
TEST_TIME 80 9698
TEST_TIME 81 9724
TEST_TIME 82 9689
TEST_TIME 83 9703
TEST_TIME 84 9727
TEST_TIME 85 9700
TEST_TIME 86 9765
TEST_TIME 87 9744
TEST_TIME 88 9706
TEST_TIME 89 9772
TEST_TIME 90 9798
TEST_TIME 91 9840
TEST_TIME 92 9781
TEST_TIME 93 9720
TEST_TIME 94 9729
TEST_TIME 95 9692
TEST_TIME 96 9651
TEST_TIME 97 9598
TEST_TIME 98 9569
TEST_TIME 99 9551
TEST_TIME 100 9519
TEST_TIME 101 9501
TEST_TIME 102 9494
TEST_TIME 103 9464
TEST_TIME 104 9482
TEST_TIME 105 9490
TEST_TIME 106 9532
TEST_TIME 107 9547
TEST_TIME 108 9534
TEST_TIME 109 9534
TEST_TIME 110 9594
TEST_TIME 111 9619
TEST_TIME 112 9603
TEST_TIME 113 9644
TEST_TIME 114 9619
TEST_TIME 115 9540
TEST_TIME 116 9492
TEST_TIME 117 9489
TEST_TIME 118 9441
TEST_TIME 119 9427
TEST_TIME 120 9372

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-09-2022 16:44:31 to 24-09-2022 16:44:35.

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 1561723 1561723 0 0.00 0 16083 6 0 0
doc_list_biud 1561723 1561723 0 0.00 0 57402 25 0 0
sp_add_invoice_to_stock 5418 5259 159 2.93 32 154380 17406 0 159
sp_cancel_adding_invoice 5873 5873 0 0.00 4 63088 675 0 0
sp_cancel_client_order 1785 1785 0 0.00 3 13183 347 0 0
sp_cancel_customer_reserve 562442 562442 0 0.00 0 57404 54 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 2 3311 77 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 5551 87 0 0
sp_cancel_supplier_invoice 6458 6445 13 0.20 2 15115 650 0 13
sp_cancel_supplier_order 433 433 0 0.00 5 919497 88692 0 0
sp_cancel_write_off 11084 11084 0 0.00 1 12664 100 0 0
sp_client_order 21311 21311 0 0.00 3 24275 357 0 0
sp_customer_reserve 471297 471297 0 0.00 0 51104 178 0 0
sp_fill_shopping_cart 32755 32755 0 0.00 1 21328 35 0 0
sp_fill_shopping_cart_clo_res 471288 471288 0 0.00 0 15597 13 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 26440 295 0 0
sp_kill_cost_storno 18143 18143 0 0.00 0 8520 46 0 0
sp_kill_qstorno_ret_qs2qd 1142846 1142846 0 0.00 0 57400 29 0 0
sp_kill_qty_storno 603764 603764 0 0.00 0 57402 63 0 0
sp_lock_selected_doc 21714 21714 0 0.00 0 6880 6 0 0
sp_make_cost_storno 29172 29172 0 0.00 0 11362 43 0 0
sp_multiply_rows_for_pdistr 29172 29172 0 0.00 0 16054 26 0 0
sp_multiply_rows_for_qdistr 21311 21311 0 0.00 0 12632 162 0 0
sp_pay_from_customer 7372 7372 0 0.00 4 12480 95 0 0
sp_pay_to_supplier 6098 6098 0 0.00 4 16089 121 0 0
sp_payment_common 13470 13470 0 0.00 2 12473 24 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 13174 314 0 0
sp_qd_handle_on_invoice_upd_sts 11122 11122 0 0.00 0 17920 280 0 0
sp_qd_handle_on_reserve_upd_sts 10453 10453 0 0.00 0 12353 68 0 0
sp_reserve_write_off 10453 10453 0 0.00 6 12450 166 0 0
sp_supplier_invoice 5728 5728 0 0.00 6 97413 3060 0 0
sp_supplier_order 5726 5726 0 0.00 5 21323 1232 0 0
srv_aggregate_perf_data 110 110 0 0.00 3176 86790 15472 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 896 114484 43834 0 0
srv_find_qd_qs_mism 1075421 1075421 0 0.00 0 13015 5 0 0
srv_make_invnt_saldo 622 622 0 0.00 25 46949 1070 0 0
srv_make_money_saldo 829 829 0 0.00 1 1362 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 153648 493031 277064 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5259 5259 0 0.00 0 15256 181 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 1656 65 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 5832 19 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 13883 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 4017 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 10225 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 847 11 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 34402 1432 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 10358 21 0 0
t$perf-norm:sp_client_order 21311 21311 0 0.00 0 13536 18 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 2795 10 0 0
t$perf-norm:sp_pay_from_customer 7372 7372 0 0.00 0 5680 10 0 0
t$perf-norm:sp_pay_to_supplier 6098 6098 0 0.00 0 4397 9 0 0
t$perf-norm:sp_reserve_write_off 10453 10453 0 0.00 0 9901 12 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 5111 19 0 0
t$perf-norm:sp_supplier_order 5726 5726 0 0.00 0 7464 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 851 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 285 5 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 575 15 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 6745 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10798 10480 318 2.94 0 6451 19 0 318
v_all_customers 38934 38934 0 0.00 0 20 0 0 0
v_all_suppliers 31308 31308 0 0.00 0 19 1 0 0
v_all_wares 42622 42622 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 207 5 0 0
v_cancel_client_order 3570 3570 0 0.00 0 2907 6 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 2526 9 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 2125 4 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 189 2 0 0
v_cancel_supplier_invoice 824 798 26 3.15 0 458 12 0 26
v_cancel_supplier_order 866 866 0 0.00 0 1111 6 0 0
v_cancel_write_off 2118 2118 0 0.00 0 3934 16 0 0
v_max_id_clo_ord 5721 5721 0 0.00 0 3662 3 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 3265 15 0 0
v_max_id_ord_sup 5723 5723 0 0.00 0 4904 5 0 0
v_max_non_paid_invoice 1888 1888 0 0.00 0 139 2 0 0
v_max_non_paid_realizn 3910 3910 0 0.00 0 3262 5 0 0
v_min_id_clo_ord 5721 5721 0 0.00 0 2283 4 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 2522 6 0 0
v_min_id_ord_sup 5723 5723 0 0.00 0 7384 13 0 0
v_min_non_paid_invoice 6098 6098 0 0.00 0 4916 17 0 0
v_min_non_paid_realizn 7372 7372 0 0.00 0 12468 16 0 0
v_our_firm 10612 10612 0 0.00 0 17 1 0 0
v_reserve_write_off 20906 20906 0 0.00 0 3649 5 0 0
x_lock_depdocs_on_canc_invoice 11919 11919 0 0.00 0 14459 114 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 20319 511 0 0
x_make_qstorno_1000_1200 5726 5726 0 0.00 2 21311 1161 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 2 86201 2934 0 0
x_make_qstorno_2100_3300 471297 471297 0 0.00 0 51103 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2022 16:44:36 to 24-09-2022 16:44:40.

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
14:12:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:28 28235 8192000000 9909793568 10221146112 991233680 10.793 100 88 176 0 1020988400 6311232 23443488
14:15:00 9666 8192000000 9952270128 10271408128 1036067872 11.227 100 96 193 0 1069514224 8543760 24902592
14:16:14 9807 8192000000 9978922336 10310348800 1064069200 11.495 100 90 180 0 1096202048 9165184 22967664
14:17:30 8588 8192000000 10028071600 10371801088 1114562016 11.976 100 83 165 0 1144083200 10107072 19414112
14:18:42 5336 8192000000 10071814160 10431868928 1161455264 12.417 100 75 150 1 1190864480 12410480 16998736
14:19:52 3432 8192000000 10135282768 10503438336 1217772848 12.941 100 90 176 0 1251538432 14142752 19622832
14:20:57 3277 8192000000 10157365440 10533314560 1247511152 13.215 100 81 163 1 1278373696 12798112 18064432
14:22:03 2016 8192000000 10187550464 10575761408 1277589232 13.491 100 79 159 1 1307673856 12456128 17628496
14:23:08 811 8192000000 10218901568 10604740608 1300552624 13.700 100 88 175 2 1333579488 13194320 19832544
14:24:09 1286 8192000000 10209232880 10609553408 1306335616 13.753 100 55 108 2 1327127040 8888432 11902992
14:25:12 7870 8192000000 10198582832 10611642368 1308346800 13.771 100 23 43 0 1323907504 10451280 5109424
14:26:22 6564 8192000000 10202755664 10620186624 1316352640 13.844 100 17 34 1 1326807952 6612672 3842640
14:27:33 13133 8192000000 10214398112 10633310208 1324132176 13.914 100 50 98 1 1341992080 6539392 11320512
14:28:48 4499 8192000000 10221879536 10642776064 1331135008 13.977 100 28 50 1 1343712208 6679392 5897808
14:29:55 7722 8192000000 10218297840 10646749184 1334337248 14.006 100 23 46 0 1345152400 5987712 4827440
14:31:03 54288 8192000000 10208648528 10623934464 1307229040 13.761 98 27 54 1 1318307488 4593008 6485440
14:32:58 3224 8192000000 10184306976 10589896704 1257754112 13.309 96 42 84 0 1274594784 8170880 8669792
14:34:02 7945 8192000000 10151184176 10559557632 1256447184 13.297 99 20 41 0 1267230512 6203536 4579792
14:35:12 8559 8192000000 10150777888 10551496704 1245895536 13.200 99 24 44 0 1261700208 9808720 5995952
14:36:21 41041 8192000000 10156723328 10545025024 1192678176 12.708 94 52 104 0 1211652608 6528272 12446160
14:38:02 5887 8192000000 10161238992 10568601600 1260745648 13.337 99 28 57 1 1273254224 6195104 6313472
14:39:10 11512 8192000000 10180114416 10586247168 1283166944 13.542 100 21 43 0 1293726560 5276080 5283536
14:40:24 63826 8192000000 10202396400 10615697408 1303760192 13.729 100 37 74 3 1322133568 9457248 8916128
14:42:30 8415 8192000000 10232686496 10643075072 1332209168 13.987 100 54 104 2 1349815312 6052800 11553344
14:43:45 6830 8192000000 10258231392 10671443968 1346322176 14.114 100 54 107 0 1370523200 12122928 12078096
14:44:57 11759 8192000000 10270046512 10689941504 1359523088 14.233 100 45 90 0 1383716112 13483600 10709424
14:46:11 11676 8192000000 10283826688 10707513344 1378335136 14.402 100 44 86 1 1401896880 13673168 9888576
14:47:29 16398 8192000000 10299839392 10727763968 1390784880 14.513 100 43 86 1 1418290816 17301584 10204352
14:48:58 33797 8192000000 10312295040 10744254464 1396225776 14.561 100 26 51 0 1420755856 18696192 5833888
14:50:36 26703 8192000000 10325486560 10757132288 1409060464 14.676 100 50 99 1 1444975984 22725056 13190464
14:52:09 2957 8192000000 10324224416 10748534784 1402458032 14.617 100 49 98 0 1437243696 23143088 11642576
14:53:15 4524 8192000000 10346601488 10770632704 1415136048 14.730 100 59 116 0 1459291792 29834944 14320800
14:54:22 2673 8192000000 10349150048 10763366400 1404385552 14.634 100 73 144 0 1455685680 34247344 17052784
14:55:33 895 8192000000 10350567328 10763317248 1403027632 14.622 99 78 155 1 1461160144 39250272 18882240
14:56:40 3486 8192000000 10343228816 10757091328 1393850928 14.540 100 79 155 0 1447749584 34244800 19653856
14:57:50 3631 8192000000 10335223856 10747465728 1376684800 14.387 99 68 135 0 1429190592 35691888 16813904
14:58:59 4912 8192000000 10342928016 10761121792 1392067616 14.524 100 72 145 1 1451070592 40196528 18806448
15:00:11 10205 8192000000 10324006640 10751934464 1377622176 14.395 99 44 88 1 1428085184 38885408 11577600
15:01:28 7861 8192000000 10351918240 10778738688 1404529520 14.635 100 58 117 0 1457788288 38799840 14458928
15:02:49 10076 8192000000 10305250528 10740441088 1359655488 14.234 99 30 58 0 1407882560 40174592 8052480
15:04:00 28813 8192000000 10296129296 10724409344 1340319648 14.060 97 26 50 0 1384570960 37520016 6731296
15:05:32 6703 8192000000 10317257328 10738331648 1367955616 14.309 100 47 93 0 1418475520 38723856 11796048
15:06:42 12609 8192000000 10285236016 10709475328 1346128080 14.113 99 32 64 1 1388295136 33725728 8441328
15:08:17 26384 8192000000 10269262496 10711457792 1330566112 13.972 99 19 39 0 1367414464 31457552 5390800
15:09:52 42659 8192000000 10266076880 10712076288 1332579808 13.990 99 15 30 0 1362602960 26421216 3601936
15:11:36 30206 8192000000 10275468336 10704633856 1346776080 14.118 99 38 76 0 1367004912 11913088 8315744
15:13:13 105050 8192000000 10289974320 10719891456 1350472624 14.152 99 9 18 0 1371328112 19175648 1679840
15:16:06 86689 8192000000 10279798560 10725216256 1364665520 14.279 99 32 64 0 1379412432 7489184 7257728
15:18:45 15944 8192000000 10281489776 10723053568 1369420128 14.322 100 33 67 1 1384250560 7574032 7256400
15:20:04 49843 8192000000 10284506560 10724081664 1373720304 14.360 100 43 86 1 1391439520 8176704 9542512
15:21:58 26099 8192000000 10302428512 10746368000 1388970528 14.497 100 40 79 0 1406908272 8887872 9049872
15:23:28 53247 8192000000 10299056240 10757361664 1397373696 14.572 100 35 70 0 1415496016 10345408 7776912
15:25:22 52097 8192000000 10298757808 10755207168 1385722768 14.468 99 13 26 0 1396237632 7423728 3091136
15:27:16 37263 8192000000 10303660272 10762899456 1389473472 14.501 99 14 26 0 1401305344 8775168 3056704
15:28:54 59099 8192000000 10287011408 10741706752 1365833904 14.290 98 22 44 0 1377579392 7212240 4533248
15:31:03 29578 8192000000 10290913152 10747531264 1378692208 14.405 99 25 50 1 1393819648 8984800 6142640
15:32:36 47990 8192000000 10311328576 10764840960 1366304864 14.294 97 22 42 1 1390971504 19398128 5268512
15:34:25 109319 8192000000 10303789584 10762887168 1357905136 14.219 97 21 42 0 1396711840 33978752 4827952
15:37:14 95115 8192000000 10306290208 10764345344 1385521360 14.466 99 16 32 0 1403324640 14008688 3794592
15:39:50 50163 8192000000 10307611168 10762260480 1352477776 14.170 96 30 60 0 1373280480 13995232 6807472
15:41:47 56989 8192000000 10290744000 10736349184 1357896528 14.218 98 17 34 0 1374273648 12796928 3580192
15:43:45 51411 8192000000 10238637296 10688561152 1259179904 13.322 92 12 24 0 1270102656 8739792 2182960
15:45:37 36605 8192000000 10270476512 10709286912 1305639584 13.746 97 36 72 0 1329060176 15024096 8396496
15:47:13 58949 8192000000 10241861920 10685878272 1266710288 13.391 94 18 36 0 1281357440 10410016 4237136
15:49:14 114484 8192000000 10253578144 10695409664 1319618864 13.873 97 33 66 0 1340850192 13944656 7286672
15:52:09 48709 8192000000 10256639696 10692100096 1328739312 13.956 98 37 74 0 1345899680 8859696 8300672
15:54:11 44994 8192000000 10250486176 10697875456 1345714560 14.109 99 19 38 0 1357800816 7703376 4382880
15:55:57 76862 8192000000 10257983136 10718277632 1320993648 13.886 96 13 26 0 1338613888 14336688 3283552
15:58:14 44179 8192000000 10247128912 10710192128 1336546992 14.026 99 12 24 0 1355121776 15656464 2918320
16:00:35 57613 8192000000 10254342768 10710253568 1332125824 13.986 98 19 36 0 1347967552 11984432 3857296
16:02:33 52150 8192000000 10257767904 10706354176 1333953296 14.003 98 23 46 0 1348320560 9206016 5161248
16:04:26 57241 8192000000 10267613456 10729332736 1367295072 14.303 100 21 41 1 1381163024 9543360 4324592
16:06:33 48319 8192000000 10286200192 10738335744 1348390832 14.133 98 25 50 0 1368956848 15175984 5390032
16:08:30 55932 8192000000 10282942752 10742112256 1378991920 14.408 100 28 54 0 1396689616 11860768 5836928
16:10:26 60800 8192000000 10298696288 10759299072 1386886512 14.478 100 20 41 0 1405898640 14494976 4517152
16:12:27 94197 8192000000 10305340432 10772168704 1368365248 14.312 98 16 32 0 1393710640 22146944 3198448
16:15:04 82399 8192000000 10304536608 10761404416 1368403440 14.313 98 26 52 0 1381378560 7318304 5656816
16:17:34 64156 8192000000 10294463520 10755579904 1394794496 14.549 100 17 35 0 1405780080 6789520 4196064
16:19:42 57269 8192000000 10284037312 10749952000 1370449680 14.331 98 13 26 0 1381880400 8400672 3030048
16:22:04 76978 8192000000 10278919024 10746396672 1369655136 14.324 98 11 22 0 1379557280 7490976 2411168
16:24:22 35967 8192000000 10281370240 10742157312 1360738816 14.244 98 25 50 0 1374373776 8154016 5480944
16:25:59 46291 8192000000 10290351776 10750672896 1359562656 14.233 97 20 40 0 1378768832 14574416 4631760
16:28:11 66766 8192000000 10287563424 10751832064 1364854048 14.281 98 10 20 0 1380228432 13001872 2372512
16:30:28 40048 8192000000 10266860688 10701484032 1311454288 13.799 96 33 64 1 1329792144 10649632 7688224
16:32:11 69945 8192000000 10251484144 10715504640 1268713808 13.410 94 23 46 0 1292914032 19268928 4931296
16:34:26 78046 8192000000 10254601696 10714136576 1327728496 13.947 99 32 63 0 1354016640 19845568 6442576
16:36:50 83288 8192000000 10259983632 10713731072 1359921440 14.237 100 18 36 0 1374935936 10815488 4199008
16:39:14 62784 8192000000 10258546080 10722611200 1352620992 14.171 99 11 22 0 1366999136 11948176 2429968
16:41:20 37837 8192000000 10252502688 10714390528 1347433616 14.124 99 7 14 0 1360076432 10882256 1760560

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-09-2022 16:44:42 to 24-09-2022 16:44:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2022 16:44:58 to 24-09-2022 16:45:00.

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          243299
MON$OLDEST_ACTIVE               246233
MON$OLDEST_SNAPSHOT             246233
MON$NEXT_TRANSACTION            246233
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                       2029664
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.33629 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33629 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33629 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33629 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Sep 24 16:42:29 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Sep 24 16:42:32 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Sep 24 16:42:34 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

24.09.22 16:45:01. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-09-24 16:45:04 No severe PSQL-related problems occured
24.09.22 16:45:05. End of report.