Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15983172 kB
MemAvailable 30647992 kB
Buffers 0 kB
Cached 15736372 kB
SwapCached 3704 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.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-22 01:37:55
DTS_END                         2021-10-22 01:37:55.2470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42316
TRN_ID                          232252
ATT_ID                          647
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: 8885.78 21501 1066293
customer order: creation 164.83 390 19780
customer order: refuse 14.39 359 1727
order to supplier: creation 43.27 1420 5192
order to supplier: removal 3.41 103610 409
invoice (draft): creation 44.13 3742 5295
invoice (draft): removal 51.89 791 6227
invoice accept: apply 39.66 19020 4759
invoice accept: cancel 46.68 810 5601
customer reserve: creation 3639.72 188 436766
customer reserve: removal 4545.88 60 545505
realization accept: apply 80.07 212 9608
realization accept: cancel 87.75 112 10530
payment from customer: creation 56.57 136 6788
payment from customer: removal 5.89 80 707
payment to supplier: creation 46.92 167 5630
payment to supplier: removal 3.37 118 404
service: total inventory turnovers 4.36 1110 523
service: total monetary turnovers 6.33 28 759
service: aggregate perf. data 0.66 29866 79
service: refresh index statistics 0.03 267806 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-10-2021 01:39:47 to 22-10-2021 01:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2174
TEST_TIME 2 3235
TEST_TIME 3 2820
TEST_TIME 4 3230
TEST_TIME 5 3928
TEST_TIME 6 4402
TEST_TIME 7 4614
TEST_TIME 8 4819
TEST_TIME 9 4924
TEST_TIME 10 5128
TEST_TIME 11 5085
TEST_TIME 12 5211
TEST_TIME 13 5603
TEST_TIME 14 5953
TEST_TIME 15 6052
TEST_TIME 16 6352
TEST_TIME 17 6880
TEST_TIME 18 7191
TEST_TIME 19 7176
TEST_TIME 20 7182
TEST_TIME 21 7432
TEST_TIME 22 7736
TEST_TIME 23 7709
TEST_TIME 24 7846
TEST_TIME 25 8495
TEST_TIME 26 8658
TEST_TIME 27 9107
TEST_TIME 28 9410
TEST_TIME 29 9861
TEST_TIME 30 10194
TEST_TIME 31 10003
TEST_TIME 32 9988
TEST_TIME 33 9761
TEST_TIME 34 9682
TEST_TIME 35 9643
TEST_TIME 36 9531
TEST_TIME 37 9492
TEST_TIME 38 9332
TEST_TIME 39 9305
TEST_TIME 40 9222
TEST_TIME 41 9109
TEST_TIME 42 8965
TEST_TIME 43 8996
TEST_TIME 44 8875
TEST_TIME 45 8865
TEST_TIME 46 8931
TEST_TIME 47 8770
TEST_TIME 48 8780
TEST_TIME 49 8853
TEST_TIME 50 8844
TEST_TIME 51 8769
TEST_TIME 52 8767
TEST_TIME 53 8692
TEST_TIME 54 8650
TEST_TIME 55 8634
TEST_TIME 56 8785
TEST_TIME 57 8820
TEST_TIME 58 8750
TEST_TIME 59 8736
TEST_TIME 60 8783
TEST_TIME 61 8784
TEST_TIME 62 8793
TEST_TIME 63 8873
TEST_TIME 64 8939
TEST_TIME 65 8897
TEST_TIME 66 8990
TEST_TIME 67 9060
TEST_TIME 68 9076
TEST_TIME 69 9151
TEST_TIME 70 9130
TEST_TIME 71 9023
TEST_TIME 72 9062
TEST_TIME 73 9008
TEST_TIME 74 8964
TEST_TIME 75 8967
TEST_TIME 76 8983
TEST_TIME 77 8919
TEST_TIME 78 8881
TEST_TIME 79 8875
TEST_TIME 80 8821
TEST_TIME 81 8859
TEST_TIME 82 8932
TEST_TIME 83 8922
TEST_TIME 84 8888
TEST_TIME 85 8927
TEST_TIME 86 8959
TEST_TIME 87 8940
TEST_TIME 88 8919
TEST_TIME 89 8927
TEST_TIME 90 9015
TEST_TIME 91 9039
TEST_TIME 92 9113
TEST_TIME 93 9170
TEST_TIME 94 9096
TEST_TIME 95 9041
TEST_TIME 96 9009
TEST_TIME 97 8987
TEST_TIME 98 8984
TEST_TIME 99 8973
TEST_TIME 100 8913
TEST_TIME 101 8894
TEST_TIME 102 8986
TEST_TIME 103 8968
TEST_TIME 104 8921
TEST_TIME 105 8921
TEST_TIME 106 9008
TEST_TIME 107 8998
TEST_TIME 108 9005
TEST_TIME 109 9039
TEST_TIME 110 9030
TEST_TIME 111 9049
TEST_TIME 112 8989
TEST_TIME 113 8967
TEST_TIME 114 8926
TEST_TIME 115 8940
TEST_TIME 116 8938
TEST_TIME 117 8912
TEST_TIME 118 8925
TEST_TIME 119 8895
TEST_TIME 120 8887

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2021 01:39:51 to 22-10-2021 01:39:54.

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 1470896 1470896 0 0.00 0 27384 7 0 0
doc_list_biud 1470896 1470896 0 0.00 0 56008 28 0 0
sp_add_invoice_to_stock 4852 4759 93 1.91 36 188949 19021 0 93
sp_cancel_adding_invoice 5601 5601 0 0.00 4 55328 811 0 0
sp_cancel_client_order 1727 1727 0 0.00 4 27754 360 0 0
sp_cancel_customer_reserve 545505 545505 0 0.00 1 56009 61 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 6220 80 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 7022 119 0 0
sp_cancel_supplier_invoice 6239 6227 12 0.19 2 20947 791 0 12
sp_cancel_supplier_order 409 409 0 0.00 14 999260 103610 0 0
sp_cancel_write_off 10530 10530 0 0.00 1 15051 112 0 0
sp_client_order 19780 19780 0 0.00 3 27788 390 0 0
sp_customer_reserve 436766 436766 0 0.00 0 61143 189 0 0
sp_fill_shopping_cart 30253 30253 0 0.00 1 10405 43 0 0
sp_fill_shopping_cart_clo_res 436764 436764 0 0.00 0 11668 15 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 30425 342 0 0
sp_kill_cost_storno 17242 17242 0 0.00 0 10004 56 0 0
sp_kill_qstorno_ret_qs2qd 1108176 1108176 0 0.00 0 56005 33 0 0
sp_kill_qty_storno 584364 584364 0 0.00 0 56008 71 0 0
sp_lock_selected_doc 19995 19995 0 0.00 0 7384 6 0 0
sp_make_cost_storno 26783 26783 0 0.00 0 19532 61 0 0
sp_multiply_rows_for_pdistr 26783 26783 0 0.00 0 27357 33 0 0
sp_multiply_rows_for_qdistr 19780 19780 0 0.00 0 20977 153 0 0
sp_pay_from_customer 6788 6788 0 0.00 4 27767 136 0 0
sp_pay_to_supplier 5630 5630 0 0.00 5 27390 168 0 0
sp_payment_common 12418 12418 0 0.00 1 15035 36 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 9226 314 0 0
sp_qd_handle_on_invoice_upd_sts 10358 10358 0 0.00 0 24541 352 0 0
sp_qd_handle_on_reserve_upd_sts 9608 9608 0 0.00 0 26255 72 0 0
sp_reserve_write_off 9608 9608 0 0.00 6 26485 213 0 0
sp_supplier_invoice 5295 5295 0 0.00 6 75176 3743 0 0
sp_supplier_order 5192 5192 0 0.00 5 31200 1420 0 0
srv_aggregate_perf_data 79 79 0 0.00 3193 309105 29867 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2494 194095 44578 0 0
srv_find_qd_qs_mism 1020873 1020873 0 0.00 0 14930 5 0 0
srv_make_invnt_saldo 523 523 0 0.00 33 33277 1110 0 0
srv_make_money_saldo 759 759 0 0.00 1 1667 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 62963 383559 267807 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4759 4759 0 0.00 0 22223 212 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 6900 145 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 3660 17 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 4903 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 7175 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 4933 40 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 7943 51 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 35426 1925 0 0
t$perf-norm:sp_cancel_write_off 932 932 0 0.00 0 3409 10 0 0
t$perf-norm:sp_client_order 19780 19780 0 0.00 0 12289 19 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2023 8 0 0
t$perf-norm:sp_pay_from_customer 6788 6788 0 0.00 0 9218 14 0 0
t$perf-norm:sp_pay_to_supplier 5630 5630 0 0.00 0 12073 24 0 0
t$perf-norm:sp_reserve_write_off 9608 9608 0 0.00 0 6556 13 0 0
t$perf-norm:sp_supplier_invoice 5295 5295 0 0.00 0 10310 24 0 0
t$perf-norm:sp_supplier_order 5192 5192 0 0.00 0 10742 19 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 2060 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 883 24 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 719 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9700 9514 186 1.91 0 26869 26 0 186
v_all_customers 36174 36174 0 0.00 0 32 1 0 0
v_all_suppliers 28976 28976 0 0.00 0 28 1 0 0
v_all_wares 39560 39560 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 22244 35 0 0
v_cancel_client_order 3454 3454 0 0.00 0 27633 13 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 2149 10 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 27677 33 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1132 6 0 0
v_cancel_supplier_invoice 826 802 24 2.90 0 4039 19 0 24
v_cancel_supplier_order 818 818 0 0.00 0 2443 12 0 0
v_cancel_write_off 1864 1864 0 0.00 0 4208 17 0 0
v_max_id_clo_ord 5192 5192 0 0.00 0 1734 2 0 0
v_max_id_clo_res 985 985 0 0.00 0 1647 9 0 0
v_max_id_ord_sup 5280 5280 0 0.00 0 5370 6 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 4667 10 0 0
v_max_non_paid_realizn 3524 3524 0 0.00 0 6753 6 0 0
v_min_id_clo_ord 5192 5192 0 0.00 0 8560 13 0 0
v_min_id_clo_res 985 985 0 0.00 0 1168 3 0 0
v_min_id_ord_sup 5280 5280 0 0.00 0 7624 8 0 0
v_min_non_paid_invoice 5630 5630 0 0.00 0 8229 27 0 0
v_min_non_paid_realizn 6788 6788 0 0.00 0 15028 26 0 0
v_our_firm 9914 9914 0 0.00 0 23 1 0 0
v_reserve_write_off 19216 19216 0 0.00 0 26327 7 0 0
x_lock_depdocs_on_canc_invoice 11427 11427 0 0.00 0 15573 128 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 5645 343 0 0
x_make_qstorno_1000_1200 5192 5192 0 0.00 2 31189 1321 0 0
x_make_qstorno_1200_2000 5295 5295 0 0.00 3 75167 3595 0 0
x_make_qstorno_2100_3300 436766 436766 0 0.00 0 61142 171 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-10-2021 01:39:55 to 22-10-2021 01:40:00.

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:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:59 27684 8192000000 9906201664 10219708416 988897360 10.771 100 90 180 1 1018052384 5861056 23293968
23:10:35 19422 8192000000 9952108352 10275893248 1033384080 11.201 100 93 186 0 1065862512 8874672 23603760
23:12:03 18767 8192000000 9994281248 10335653888 1085540240 11.700 100 79 158 0 1114745712 10267856 18937616
23:13:26 10413 8192000000 10054844496 10406846464 1137391632 12.191 100 89 178 0 1171171136 13033920 20745584
23:14:39 8994 8192000000 10087386336 10451542016 1175400176 12.547 100 85 170 3 1208106656 13544416 19162064
23:15:51 6847 8192000000 10127042528 10495893504 1210076528 12.870 100 78 156 0 1240736512 12988288 17671696
23:17:00 3175 8192000000 10152816000 10530529280 1240662176 13.152 100 84 168 1 1272725312 13417120 18646016
23:18:06 1605 8192000000 10197370352 10577334272 1273847200 13.457 100 88 176 0 1308316160 14574928 19894032
23:19:09 1778 8192000000 10215607216 10603917312 1298626432 13.683 100 90 183 1 1332093328 13140448 20326448
23:20:11 21684 8192000000 10213427920 10610421760 1306375712 13.753 100 48 97 1 1322727824 4683216 11668896
23:21:34 3510 8192000000 10202451424 10610425856 1308128832 13.769 100 47 97 3 1324774272 5192432 11453008
23:22:38 7711 8192000000 10196970944 10616287232 1313830144 13.821 100 18 36 0 1324016272 6132704 4053424
23:23:52 6479 8192000000 10208660640 10626981888 1321860208 13.894 100 20 41 0 1332523888 6244128 4419552
23:25:05 64645 8192000000 10224504992 10641784832 1327964976 13.949 100 36 72 0 1342046576 6084416 7997184
23:27:11 4411 8192000000 10201376064 10621018112 1308518528 13.773 99 28 57 2 1320460144 5452368 6489248
23:28:17 11800 8192000000 10196836160 10615828480 1274564352 13.463 97 17 35 0 1283246928 4990976 3691600
23:29:29 60739 8192000000 10121155344 10524966912 1176258416 12.555 92 22 44 1 1187213024 5603856 5350752
23:31:30 6393 8192000000 10140496112 10542739456 1239275696 13.140 99 32 65 1 1253613824 6716208 7621920
23:32:36 7714 8192000000 10152750272 10551922688 1246219424 13.203 99 26 52 1 1257635920 5092080 6324416
23:33:44 11661 8192000000 10162488720 10573553664 1266989056 13.394 99 13 26 0 1275747728 5567216 3191456
23:34:56 34239 8192000000 10181785888 10597150720 1294374688 13.644 100 30 60 2 1307394368 6199632 6820048
23:36:34 8126 8192000000 10190374048 10609201152 1304621920 13.737 100 24 48 1 1315616992 5059120 5935952
23:37:45 7680 8192000000 10227466960 10641866752 1331572672 13.981 100 31 62 0 1344150736 5089552 7488512
23:38:59 8487 8192000000 10258628240 10666811392 1347816656 14.128 100 58 112 1 1370802176 9458368 13527152
23:40:16 53256 8192000000 10262900992 10688733184 1362039520 14.256 100 33 66 1 1381212432 11565552 7607360
23:42:17 14470 8192000000 10289786304 10709155840 1363958528 14.273 99 41 81 0 1387737120 13740032 10038560
23:43:36 18366 8192000000 10302067888 10723827712 1384534992 14.457 100 49 99 2 1410634368 15054624 11044752
23:45:02 15516 8192000000 10313314784 10737217536 1397442976 14.572 100 48 96 1 1423888928 15820656 10625296
23:46:26 12485 8192000000 10335880128 10752524288 1409218288 14.677 100 69 138 2 1446944608 22151760 15574560
23:47:54 6846 8192000000 10345848768 10774462464 1423326000 14.802 100 38 76 0 1456367376 23746608 9294768
23:49:09 7715 8192000000 10351793568 10774712320 1416507424 14.742 100 64 129 1 1459620800 26550656 16562720
23:50:25 6038 8192000000 10360862400 10781786112 1420016304 14.773 100 65 125 2 1467871040 32945680 14909056
23:51:39 3264 8192000000 10363106640 10781671424 1415035872 14.729 100 77 151 2 1464837296 30467920 19333504
23:52:49 5478 8192000000 10365821744 10788515840 1415392064 14.732 99 71 141 2 1468680352 35232736 18055552
23:53:59 6628 8192000000 10364383312 10776326144 1408951888 14.675 100 72 144 2 1465947760 39060912 17934960
23:55:13 5207 8192000000 10358013072 10767122432 1404482656 14.635 100 77 152 4 1458700944 35015792 19202496
23:56:25 4160 8192000000 10356467536 10772561920 1407641168 14.663 100 72 141 0 1463228448 37855440 17731840
23:57:33 2492 8192000000 10360721632 10766536704 1386564400 14.475 99 78 156 0 1451052976 45323936 19164640
23:58:43 2970 8192000000 10348996128 10764447744 1377018784 14.390 98 63 127 1 1438772512 44782720 16971008
23:59:50 11485 8192000000 10323980352 10739347456 1349825536 14.146 97 41 80 2 1405668496 44850896 10992064
00:01:08 6199 8192000000 10344322560 10760105984 1368775728 14.316 99 49 98 2 1439768608 57014848 13978032
00:02:25 7810 8192000000 10337958672 10764304384 1383375264 14.447 100 36 72 2 1447207408 53305664 10526480
00:03:42 21279 8192000000 10306990320 10738171904 1346883888 14.119 99 29 58 2 1404053760 48872992 8296880
00:05:07 27527 8192000000 10293620496 10731618304 1347427904 14.124 100 21 42 1 1405721072 52020336 6272832
00:06:48 14409 8192000000 10273434464 10709848064 1351986784 14.165 100 29 55 1 1383940832 25601728 6352320
00:08:07 44759 8192000000 10255709968 10685714432 1347857264 14.128 100 33 63 1 1363560224 8122816 7580144
00:09:57 98987 8192000000 10259644272 10708480000 1355210672 14.194 100 14 28 0 1372821936 14304752 3306512
00:12:36 52678 8192000000 10270325984 10715512832 1349158576 14.140 99 14 29 0 1362968832 10483728 3326528
00:14:34 37104 8192000000 10270231200 10723123200 1368457120 14.313 100 13 24 1 1383121632 12313920 2350592
00:16:11 44609 8192000000 10262818912 10705141760 1349916528 14.147 99 12 24 0 1364176928 11366480 2893920
00:17:59 56776 8192000000 10280729616 10723790848 1360211424 14.239 99 19 39 1 1376710720 12204592 4294704
00:20:07 45042 8192000000 10272676368 10725715968 1369178240 14.320 100 19 39 0 1388784032 14650528 4955264
00:21:55 97429 8192000000 10277190976 10737917952 1380027536 14.417 100 32 64 0 1408643984 20307136 8309312
00:24:34 32340 8192000000 10309021248 10744500224 1389280880 14.499 100 44 86 1 1407389040 7246016 10862144
00:26:07 65409 8192000000 10291688528 10750578688 1397496128 14.573 100 21 42 0 1414222080 11564144 5161808
00:28:22 61576 8192000000 10292291248 10759327744 1398607056 14.583 100 11 22 0 1412214864 10518304 3089504
00:31:42 42570 8192000000 10303403520 10764279808 1398405744 14.581 100 10 20 0 1413908944 13222000 2281200
00:33:35 46232 8192000000 10304908448 10781528064 1402942544 14.621 100 6 12 0 1421777376 17447792 1387040
00:35:22 59507 8192000000 10314767184 10764615680 1399072464 14.587 100 31 62 0 1417679904 11540336 7067104
00:37:26 73366 8192000000 10294950176 10751197184 1332490368 13.990 95 16 31 1 1347242320 11451952 3300000
00:39:41 18757 8192000000 10304214160 10742534144 1341436496 14.070 96 44 88 0 1364822000 13775728 9609776
00:41:00 56798 8192000000 10295855504 10754711552 1358628224 14.225 99 16 30 1 1381576480 19211056 3737200
00:43:00 59109 8192000000 10276892992 10735792128 1341564144 14.072 98 27 54 1 1366419296 18747280 6107872
00:45:00 88256 8192000000 10259965408 10715484160 1295858160 13.658 95 18 36 1 1323450400 23342992 4249248
00:47:28 103918 8192000000 10273260432 10712596480 1353039344 14.175 100 27 54 1 1372842224 14015536 5787344
00:50:25 30330 8192000000 10245441136 10701324288 1339232688 14.050 99 15 30 0 1357218208 14695776 3289744
00:51:57 40930 8192000000 10239040912 10686042112 1329788752 13.965 98 14 28 0 1342865728 9961760 3115216
00:53:55 31661 8192000000 10233787056 10688319488 1322347504 13.898 99 3 6 0 1334380688 11647600 385584
00:55:27 58203 8192000000 10235500672 10689110016 1310897648 13.794 97 6 12 0 1322280832 10049984 1333200
00:57:25 54911 8192000000 10254960576 10712190976 1350866304 14.155 100 14 28 2 1371149008 16421696 3861008
00:59:20 51005 8192000000 10266876784 10715021312 1353980432 14.183 100 15 30 0 1367718384 10231200 3506752
01:01:19 25725 8192000000 10265622784 10705399808 1355937088 14.201 100 30 60 0 1372067248 9226304 6903856
01:02:48 107004 8192000000 10257244144 10690965504 1270566288 13.427 93 14 28 1 1281858480 8767216 2524976
01:05:37 73547 8192000000 10289813040 10738343936 1375757168 14.379 100 35 68 2 1400032208 16497616 7777424
01:08:01 194095 8192000000 10264410736 10717274112 1276746800 13.483 92 5 10 0 1298369024 20735024 887200
01:12:18 57405 8192000000 10275153392 10721263616 1358964416 14.228 99 15 30 0 1369783808 7282896 3536496
01:14:16 58898 8192000000 10276211904 10729287680 1363380672 14.268 99 6 12 0 1373317648 8343520 1593456
01:16:19 66828 8192000000 10308042736 10752430080 1384463824 14.456 100 24 47 0 1399384160 10158640 4761696
01:18:26 105561 8192000000 10298859728 10761412608 1384818608 14.460 99 28 56 0 1404904048 13442608 6642832
01:21:25 22739 8192000000 10315056576 10758270976 1387805552 14.486 99 36 72 1 1408645072 12643056 8196464
01:22:56 94204 8192000000 10300216752 10755911680 1366997680 14.300 98 8 16 0 1384724544 16194288 1532576
01:25:37 39102 8192000000 10311783776 10751422464 1379269952 14.410 99 24 49 0 1393864320 9236656 5357712
01:27:18 62393 8192000000 10290595264 10748276736 1349638768 14.144 97 20 40 0 1364943360 10698128 4606464
01:29:21 87676 8192000000 10253362592 10694381568 1301787200 13.711 95 24 47 1 1315076992 7800048 5489744
01:32:13 34786 8192000000 10257151200 10699984896 1322510432 13.899 96 21 40 0 1334888112 7766288 4611392
01:34:15 51144 8192000000 10257226112 10716794880 1344212384 14.095 99 9 18 0 1359932352 13753312 1966656
01:36:21 57408 8192000000 10271782864 10728378368 1375907024 14.380 100 17 34 1 1389458656 9635840 3915792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2021 01:40:01 to 22-10-2021 01:40:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2021 01:40:17 to 22-10-2021 01:40:19.

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          232251
MON$OLDEST_ACTIVE               233872
MON$OLDEST_SNAPSHOT             233872
MON$NEXT_TRANSACTION            233872
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                       2018176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33522 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33522 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33522 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33522 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Oct 22 01:38:11 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Oct 22 01:38:11 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Oct 22 01:38:11 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

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