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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16281928 kB
MemAvailable 31243960 kB
Buffers 0 kB
Cached 15343324 kB
SwapCached 1204 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 2022-01-10 01:38:01
DTS_END                         2022-01-10 01:38:01.8080
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/35784
TRN_ID                          239757
ATT_ID                          689
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: 9773.66 19582 1172839
customer order: creation 176.81 338 21217
customer order: refuse 15.23 350 1828
order to supplier: creation 47.87 1331 5744
order to supplier: removal 3.58 94224 429
invoice (draft): creation 46.62 3632 5594
invoice (draft): removal 55.16 768 6619
invoice accept: apply 43.03 18903 5164
invoice accept: cancel 50.33 748 6039
customer reserve: creation 4012.92 186 481550
customer reserve: removal 5011.91 52 601429
realization accept: apply 85.83 173 10300
realization accept: cancel 90.98 102 10917
payment from customer: creation 60.57 88 7268
payment from customer: removal 5.86 77 703
payment to supplier: creation 51.13 106 6136
payment to supplier: removal 3.23 50 387
service: total inventory turnovers 4.72 1048 566
service: total monetary turnovers 6.94 25 833
service: aggregate perf. data 0.93 19713 112
service: refresh index statistics 0.03 249732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2022 01:38:40 to 10-01-2022 01:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7249
TEST_TIME 2 6782
TEST_TIME 3 7392
TEST_TIME 4 7044
TEST_TIME 5 6737
TEST_TIME 6 6520
TEST_TIME 7 6410
TEST_TIME 8 7260
TEST_TIME 9 7489
TEST_TIME 10 7582
TEST_TIME 11 7608
TEST_TIME 12 7693
TEST_TIME 13 7796
TEST_TIME 14 7890
TEST_TIME 15 8342
TEST_TIME 16 8695
TEST_TIME 17 8790
TEST_TIME 18 9034
TEST_TIME 19 9229
TEST_TIME 20 8991
TEST_TIME 21 9106
TEST_TIME 22 9221
TEST_TIME 23 9628
TEST_TIME 24 10010
TEST_TIME 25 10297
TEST_TIME 26 10235
TEST_TIME 27 10030
TEST_TIME 28 9771
TEST_TIME 29 10429
TEST_TIME 30 11357
TEST_TIME 31 11240
TEST_TIME 32 11083
TEST_TIME 33 11106
TEST_TIME 34 11218
TEST_TIME 35 11049
TEST_TIME 36 10855
TEST_TIME 37 11017
TEST_TIME 38 10938
TEST_TIME 39 10871
TEST_TIME 40 10906
TEST_TIME 41 10723
TEST_TIME 42 10536
TEST_TIME 43 10480
TEST_TIME 44 10487
TEST_TIME 45 10403
TEST_TIME 46 10392
TEST_TIME 47 10319
TEST_TIME 48 10276
TEST_TIME 49 10350
TEST_TIME 50 10210
TEST_TIME 51 10170
TEST_TIME 52 10090
TEST_TIME 53 10121
TEST_TIME 54 10023
TEST_TIME 55 9924
TEST_TIME 56 9953
TEST_TIME 57 9809
TEST_TIME 58 10095
TEST_TIME 59 10027
TEST_TIME 60 10072
TEST_TIME 61 10141
TEST_TIME 62 10205
TEST_TIME 63 10360
TEST_TIME 64 10395
TEST_TIME 65 10322
TEST_TIME 66 10421
TEST_TIME 67 10352
TEST_TIME 68 10420
TEST_TIME 69 10450
TEST_TIME 70 10365
TEST_TIME 71 10261
TEST_TIME 72 10251
TEST_TIME 73 10255
TEST_TIME 74 10215
TEST_TIME 75 10185
TEST_TIME 76 10139
TEST_TIME 77 10127
TEST_TIME 78 10089
TEST_TIME 79 10044
TEST_TIME 80 10007
TEST_TIME 81 9915
TEST_TIME 82 9853
TEST_TIME 83 9884
TEST_TIME 84 9869
TEST_TIME 85 9843
TEST_TIME 86 9917
TEST_TIME 87 9943
TEST_TIME 88 9947
TEST_TIME 89 9948
TEST_TIME 90 9911
TEST_TIME 91 10019
TEST_TIME 92 10033
TEST_TIME 93 10065
TEST_TIME 94 10101
TEST_TIME 95 10065
TEST_TIME 96 10119
TEST_TIME 97 10161
TEST_TIME 98 10085
TEST_TIME 99 10011
TEST_TIME 100 9969
TEST_TIME 101 9949
TEST_TIME 102 9878
TEST_TIME 103 9863
TEST_TIME 104 9855
TEST_TIME 105 9807
TEST_TIME 106 9837
TEST_TIME 107 9804
TEST_TIME 108 9788
TEST_TIME 109 9766
TEST_TIME 110 9749
TEST_TIME 111 9761
TEST_TIME 112 9790
TEST_TIME 113 9758
TEST_TIME 114 9770
TEST_TIME 115 9767
TEST_TIME 116 9743
TEST_TIME 117 9764
TEST_TIME 118 9870
TEST_TIME 119 9856
TEST_TIME 120 9775

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-01-2022 01:38:43 to 10-01-2022 01:38:47.

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 1617238 1617238 0 0.00 0 17408 6 0 0
doc_list_biud 1617238 1617238 0 0.00 0 41455 25 0 0
sp_add_invoice_to_stock 5286 5164 122 2.30 34 180524 18903 0 122
sp_cancel_adding_invoice 6039 6039 0 0.00 5 80632 748 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 8634 351 0 0
sp_cancel_customer_reserve 601429 601429 0 0.00 1 41458 52 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 2 4753 77 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 2843 51 0 0
sp_cancel_supplier_invoice 6633 6619 14 0.21 2 12999 768 0 14
sp_cancel_supplier_order 429 429 0 0.00 6 880173 94225 0 0
sp_cancel_write_off 10917 10917 0 0.00 1 5508 103 0 0
sp_client_order 21217 21217 0 0.00 3 16537 338 0 0
sp_customer_reserve 481550 481550 0 0.00 0 99553 186 0 0
sp_fill_shopping_cart 32529 32529 0 0.00 1 15869 38 0 0
sp_fill_shopping_cart_clo_res 481544 481544 0 0.00 0 16693 12 0 0
sp_get_clo_for_invoice 5158 5158 0 0.00 0 18302 258 0 0
sp_kill_cost_storno 18046 18046 0 0.00 0 7957 52 0 0
sp_kill_qstorno_ret_qs2qd 1220823 1220823 0 0.00 0 41443 28 0 0
sp_kill_qty_storno 642719 642719 0 0.00 0 41455 62 0 0
sp_lock_selected_doc 21245 21245 0 0.00 0 9626 6 0 0
sp_make_cost_storno 28862 28862 0 0.00 0 17397 49 0 0
sp_multiply_rows_for_pdistr 28862 28862 0 0.00 0 7830 26 0 0
sp_multiply_rows_for_qdistr 21217 21217 0 0.00 0 9978 151 0 0
sp_pay_from_customer 7268 7268 0 0.00 4 16027 89 0 0
sp_pay_to_supplier 6136 6136 0 0.00 5 9579 107 0 0
sp_payment_common 13404 13404 0 0.00 1 16012 21 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 7304 323 0 0
sp_qd_handle_on_invoice_upd_sts 11197 11197 0 0.00 0 16699 337 0 0
sp_qd_handle_on_reserve_upd_sts 10300 10300 0 0.00 0 8992 56 0 0
sp_reserve_write_off 10300 10300 0 0.00 6 22227 173 0 0
sp_supplier_invoice 5594 5594 0 0.00 6 85366 3633 0 0
sp_supplier_order 5744 5744 0 0.00 5 25630 1331 0 0
srv_aggregate_perf_data 112 112 0 0.00 2299 258645 19713 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1499 121792 43378 0 0
srv_find_qd_qs_mism 1122747 1122747 0 0.00 0 12459 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 44 35571 1049 0 0
srv_make_money_saldo 833 833 0 0.00 0 423 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 175934 301575 249732 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5164 5164 0 0.00 0 10253 180 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 5007 111 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 4335 16 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 6423 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1746 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 630 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 1071 14 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 24082 1460 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2889 11 0 0
t$perf-norm:sp_client_order 21218 21218 0 0.00 0 11377 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 4674 19 0 0
t$perf-norm:sp_pay_from_customer 7268 7268 0 0.00 0 6012 11 0 0
t$perf-norm:sp_pay_to_supplier 6136 6136 0 0.00 0 5668 13 0 0
t$perf-norm:sp_reserve_write_off 10300 10300 0 0.00 0 5520 10 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 8507 26 0 0
t$perf-norm:sp_supplier_order 5744 5744 0 0.00 0 4357 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 320 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 5006 27 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1398 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10560 10316 244 2.31 0 8246 19 0 244
v_all_customers 38900 38900 0 0.00 0 16 1 0 0
v_all_suppliers 31432 31432 0 0.00 0 17 1 0 0
v_all_wares 42434 42434 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2536 9 0 0
v_cancel_client_order 3656 3656 0 0.00 0 2681 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 4725 6 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 4065 8 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 92 2 0 0
v_cancel_supplier_invoice 852 824 28 3.28 0 2622 23 0 28
v_cancel_supplier_order 858 858 0 0.00 0 698 4 0 0
v_cancel_write_off 1972 1972 0 0.00 0 4111 16 0 0
v_max_id_clo_ord 5744 5744 0 0.00 0 15745 4 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2926 13 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 5433 6 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 2046 5 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 5164 5 0 0
v_min_id_clo_ord 5744 5744 0 0.00 0 920 5 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 1854 7 0 0
v_min_id_ord_sup 5568 5568 0 0.00 0 4640 5 0 0
v_min_non_paid_invoice 6136 6136 0 0.00 0 4814 10 0 0
v_min_non_paid_realizn 7268 7268 0 0.00 0 7799 15 0 0
v_our_firm 10494 10494 0 0.00 0 14 1 0 0
v_reserve_write_off 20600 20600 0 0.00 0 10209 7 0 0
x_lock_depdocs_on_canc_invoice 12246 12246 0 0.00 0 12682 103 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 8938 346 0 0
x_make_qstorno_1000_1200 5744 5744 0 0.00 1 25620 1247 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 3 85301 3487 0 0
x_make_qstorno_2100_3300 481550 481550 0 0.00 0 99551 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2022 01:38:48 to 10-01-2022 01:38:52.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:05 12 8192000000 8806953584 8821379072 10699040 0.130 6 1 2 0 10803952 62128 42784
23:09:07 24046 8192000000 9909528928 10224041984 991240256 10.794 100 90 180 1 1021182400 6261824 23680320
23:10:36 17689 8192000000 9957590240 10281385984 1040553872 11.270 100 89 178 0 1072104016 8903680 22646464
23:11:58 23718 8192000000 9997819104 10334273536 1077621696 11.625 100 84 168 2 1108377744 10024288 20731760
23:13:32 11060 8192000000 10032622320 10382020608 1123624784 12.061 100 73 146 1 1151415488 10440304 17350400
23:14:45 11888 8192000000 10088334464 10450878464 1176014416 12.553 100 81 163 1 1205719936 11098864 18606656
23:16:00 5268 8192000000 10128380208 10500943872 1221328256 12.974 100 76 152 0 1249968256 12268864 16371136
23:17:09 10379 8192000000 10166544032 10544025600 1250965088 13.247 100 80 160 1 1281653744 13585616 17103040
23:18:21 2670 8192000000 10194351296 10579210240 1279090528 13.505 100 84 167 0 1311378496 14084800 18203168
23:19:25 10355 8192000000 10216925488 10604699648 1301258768 13.707 100 81 161 1 1333115280 13496976 18359536
23:20:36 7067 8192000000 10208063104 10610520064 1311314576 13.798 100 45 91 2 1330381376 9569168 9497632
23:21:45 11423 8192000000 10200756608 10621841408 1320796768 13.884 100 22 44 0 1332548848 6703024 5049056
23:23:01 54491 8192000000 10213319728 10634309632 1326189200 13.933 100 32 64 1 1339235136 5287680 7758256
23:24:57 7390 8192000000 10226653360 10648293376 1336524592 14.026 100 20 41 0 1347616912 6787280 4305040
23:26:05 8570 8192000000 10232523856 10656235520 1343148736 14.086 100 13 25 1 1351507776 5673936 2685104
23:27:15 64132 8192000000 10214896032 10626723840 1273388464 13.453 95 31 62 1 1290284368 9567984 7327920
23:29:19 8606 8192000000 10190764160 10594361344 1275710944 13.474 98 35 68 2 1289320912 6162320 7447648
23:30:29 7732 8192000000 10138412992 10542927872 1190933200 12.692 93 16 32 0 1199817840 4914544 3970096
23:31:38 17295 8192000000 10133704032 10535960576 1172495472 12.520 93 17 34 1 1181648368 5221232 3931664
23:32:56 26509 8192000000 10139573856 10549522432 1216056960 12.925 96 33 66 0 1230381408 6624768 7699680
23:34:26 9126 8192000000 10180357696 10587447296 1283831472 13.548 100 36 71 2 1298382400 5824304 8726624
23:35:36 11592 8192000000 10178526208 10590978048 1290357584 13.607 100 31 62 0 1303440800 5756896 7326320
23:36:48 11671 8192000000 10199759264 10611204096 1302080096 13.714 100 20 40 1 1312193072 5597680 4515296
23:38:03 37485 8192000000 10220596176 10633129984 1315045552 13.832 100 47 90 1 1331771136 5034752 11690832
23:39:50 44734 8192000000 10256405744 10673111040 1348795424 14.137 100 45 90 0 1369799728 11448176 9556128
23:41:38 17484 8192000000 10282875968 10706911232 1372135856 14.346 100 43 86 1 1395384176 13288656 9959664
23:43:01 9543 8192000000 10303341760 10723794944 1388450464 14.492 100 57 113 1 1418932976 17240960 13241552
23:44:19 8891 8192000000 10318104304 10738450432 1394517568 14.546 100 54 107 2 1423083456 16108656 12457232
23:45:37 11996 8192000000 10324924752 10750861312 1404050256 14.631 100 53 105 1 1438048400 21399632 12598512
23:47:01 1498 8192000000 10349062272 10761773056 1410810368 14.691 100 85 169 0 1451028912 20850896 19367648
23:48:06 4703 8192000000 10361156864 10780807168 1417904880 14.754 100 81 160 0 1468162272 31147712 19109680
23:49:15 3197 8192000000 10369702560 10785050624 1415765120 14.735 99 76 152 0 1464699520 30686944 18247456
23:50:24 4752 8192000000 10354504672 10772365312 1407967200 14.666 100 67 134 4 1464457216 40099968 16390048
23:51:32 3964 8192000000 10358582016 10776678400 1407267040 14.660 100 67 133 0 1466864016 42859680 16737296
23:52:40 4308 8192000000 10364430160 10775007232 1401134352 14.605 99 69 138 2 1465073840 47378208 16561280
23:53:48 2139 8192000000 10365728736 10776211456 1398879888 14.585 99 84 169 2 1465615232 46240720 20494624
23:54:53 7539 8192000000 10354086848 10778230784 1390909136 14.514 99 62 124 2 1452095696 45239312 15947248
23:56:05 4296 8192000000 10318734368 10725593088 1351657136 14.162 99 60 118 1 1411552384 44422192 15473056
23:57:14 4626 8192000000 10319531152 10738425856 1370506704 14.332 100 54 108 0 1428787776 44133840 14147232
23:58:28 2865 8192000000 10318438304 10737213440 1362974432 14.264 100 59 116 6 1421551056 43588528 14988096
23:59:42 11426 8192000000 10326203184 10751324160 1362493664 14.260 100 38 76 0 1435783360 62629568 10660128
00:00:56 12768 8192000000 10328504272 10752663552 1365376976 14.286 100 38 76 0 1436795376 60430544 10987856
00:02:11 28687 8192000000 10305346096 10737721344 1358011872 14.220 99 25 50 1 1416339776 51194736 7133168
00:03:42 13109 8192000000 10328266272 10769006592 1387459984 14.483 100 25 46 1 1430639056 36775552 6403520
00:05:09 17942 8192000000 10331798720 10768068608 1388638640 14.494 100 37 74 1 1436210592 37889952 9682000
00:06:34 24340 8192000000 10326514960 10765721600 1366775488 14.298 100 23 46 0 1428750176 55078160 6896528
00:08:01 30978 8192000000 10303225232 10747293696 1355057312 14.193 99 18 35 2 1402715824 42501984 5156528
00:09:32 69634 8192000000 10293991200 10738802688 1370586288 14.332 99 17 32 0 1387293376 12743040 3964048
00:11:48 78637 8192000000 10292902032 10747224064 1372531616 14.350 99 5 10 0 1390119216 16372400 1215200
00:14:18 28059 8192000000 10325687648 10760482816 1391755088 14.522 100 50 100 2 1415621808 11305296 12561424
00:15:53 114693 8192000000 10317408976 10773536768 1369180768 14.320 98 15 30 1 1384158064 11969776 3007520
00:18:49 81189 8192000000 10287689296 10736574464 1352362448 14.169 97 20 40 0 1366108128 9666912 4078768
00:21:26 37776 8192000000 10310916336 10752147456 1378787040 14.406 99 59 116 0 1407095760 15577936 12730784
00:23:05 45088 8192000000 10302762192 10754674688 1378807552 14.406 99 25 50 0 1396400352 12445712 5147088
00:24:53 34471 8192000000 10311467008 10754752512 1375652960 14.378 98 26 52 1 1389651504 7961712 6036832
00:26:31 38184 8192000000 10296380336 10757664768 1369008064 14.318 98 18 37 1 1383111264 9459984 4643216
00:28:19 29329 8192000000 10302895024 10761281536 1395161648 14.552 100 22 44 1 1412354352 11995488 5197216
00:29:58 31034 8192000000 10305239472 10767224832 1398133344 14.578 100 10 18 0 1412099552 11699360 2266848
00:31:37 64710 8192000000 10304819200 10764427264 1387784928 14.486 99 16 32 0 1400711200 9165216 3761056
00:33:45 94124 8192000000 10317448592 10795618304 1379455296 14.412 98 10 20 0 1406611568 24710064 2446208
00:36:22 23797 8192000000 10315085600 10785308672 1394758928 14.548 100 15 30 0 1420471040 21758560 3953552
00:37:52 36728 8192000000 10296030720 10753818624 1360034016 14.238 98 12 24 1 1378176816 15412128 2730672
00:39:34 68004 8192000000 10301265904 10753499136 1353951216 14.183 97 17 34 1 1374583552 16854096 3778240
00:41:44 63593 8192000000 10271901184 10728460288 1255646096 13.290 91 11 22 1 1275444944 17270592 2528256
00:43:47 83464 8192000000 10294249152 10747813888 1328065456 13.950 96 16 32 0 1345851344 14496688 3289200
00:46:16 90648 8192000000 10259527168 10711076864 1266130800 13.386 92 20 40 0 1280513296 10389648 3992848
00:48:49 83231 8192000000 10259245408 10694959104 1321380208 13.889 97 50 100 1 1347550688 15441152 10729328
00:51:27 81173 8192000000 10258138048 10708647936 1317679568 13.856 97 23 46 0 1332255904 9219552 5356784
00:53:51 75294 8192000000 10246068576 10702860288 1308628096 13.774 96 10 20 0 1320049424 9264576 2156752
00:56:06 20237 8192000000 10262629488 10701582336 1347493616 14.125 100 34 70 2 1363349664 8102880 7753168
00:57:28 35217 8192000000 10251839856 10712928256 1348800080 14.137 99 8 16 1 1356477568 5970512 1706976
00:59:04 92305 8192000000 10270572288 10723758080 1356958976 14.210 99 25 50 1 1373475760 10675264 5841520
01:01:54 63870 8192000000 10282347520 10742669312 1373059392 14.354 100 14 28 0 1391658064 15324752 3273920
01:03:58 63013 8192000000 10290920592 10763747328 1372188720 14.347 99 13 26 0 1392947184 17688176 3070288
01:06:01 41521 8192000000 10295053200 10736189440 1378817168 14.406 100 37 74 1 1399022480 11172528 9032784
01:07:44 41583 8192000000 10305054768 10758909952 1374313504 14.366 99 24 48 1 1392719952 13160016 5246432
01:09:32 79052 8192000000 10298821856 10759446528 1358023344 14.220 97 7 14 0 1373509824 13887632 1598848
01:11:55 61002 8192000000 10300559456 10756304896 1382161840 14.436 99 7 14 0 1396193744 11939616 2092288
01:13:56 81569 8192000000 10316109280 10784227328 1391697456 14.521 99 17 34 1 1409613248 14107952 3807840
01:16:19 121791 8192000000 10290021296 10750492672 1379705120 14.414 99 19 37 1 1395358160 11606304 4046736
01:19:58 75901 8192000000 10305588880 10773774336 1391609152 14.520 99 8 16 0 1406968784 13339904 2019728
01:22:16 49758 8192000000 10316034304 10784223232 1408113152 14.667 100 16 32 1 1426703712 15188960 3401600
01:24:25 40992 8192000000 10292877344 10755481600 1371991872 14.345 98 19 38 0 1393198912 16173760 5033280
01:26:06 73096 8192000000 10267081392 10734555136 1293749616 13.638 93 8 16 0 1303315120 8090048 1475456
01:28:32 48211 8192000000 10294986512 10756345856 1371404832 14.340 99 20 40 1 1394328048 17695072 5228144
01:30:25 45152 8192000000 10279521008 10737041408 1342608400 14.081 97 8 16 1 1354316752 9776496 1931856
01:32:36 42582 8192000000 10283428432 10737315840 1368343824 14.312 99 33 66 0 1389515856 13328512 7843520
01:34:25 72214 8192000000 10261785440 10717814784 1292366480 13.626 95 20 40 0 1309653456 12854960 4432016
01:36:40 47668 8192000000 10260775984 10713714688 1318417280 13.862 97 13 24 1 1334260176 13245744 2597152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2022 01:38:54 to 10-01-2022 01:39:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2022 01:39:09 to 10-01-2022 01:39:11.

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          238635
MON$OLDEST_ACTIVE               240014
MON$OLDEST_SNAPSHOT             240014
MON$NEXT_TRANSACTION            240014
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                       2044320
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:725: 
:726: mscs-test-161.budzdorov.ru    Mon Jan 10 01:38:07 2022
:727:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:728: 
:729: 
:730: mscs-test-161.budzdorov.ru    Mon Jan 10 01:38:07 2022
:731:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:732: 
:733: 
:734: mscs-test-161.budzdorov.ru    Mon Jan 10 01:38:07 2022
:735:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:736: 
:737: 
:738: mscs-test-161.budzdorov.ru    Mon Jan 10 01:38:13 2022
:739:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:740: 
--- end of diff output ---

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

10.01.22 01:39:12. 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-01-10 01:39:15 No severe PSQL-related problems occured
10.01.22 01:39:16. End of report.