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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16458840 kB
MemAvailable 30957248 kB
Buffers 0 kB
Cached 15201300 kB
SwapCached 2600 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-08-18 22:37:51
DTS_END                         2021-08-18 22:37:51.1250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54058
TRN_ID                          246848
ATT_ID                          651
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: 8981.12 20108 1077734
customer order: creation 179.98 344 21598
customer order: refuse 14.79 359 1775
order to supplier: creation 47.41 1252 5689
order to supplier: removal 3.09 119445 371
invoice (draft): creation 46.29 3155 5555
invoice (draft): removal 48.85 841 5862
invoice accept: apply 42.64 19018 5117
invoice accept: cancel 44.42 942 5330
customer reserve: creation 3909.90 191 469188
customer reserve: removal 4340.63 69 520875
realization accept: apply 86.68 180 10402
realization accept: cancel 82.48 116 9898
payment from customer: creation 60.46 114 7255
payment from customer: removal 6.19 71 743
payment to supplier: creation 50.57 117 6068
payment to supplier: removal 3.68 88 441
service: total inventory turnovers 5.28 950 633
service: total monetary turnovers 6.80 25 816
service: aggregate perf. data 0.95 15285 114
service: refresh index statistics 0.03 239596 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-08-2021 22:39:07 to 18-08-2021 22:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2645
TEST_TIME 2 3952
TEST_TIME 3 4601
TEST_TIME 4 4827
TEST_TIME 5 5339
TEST_TIME 6 5707
TEST_TIME 7 5834
TEST_TIME 8 6204
TEST_TIME 9 5798
TEST_TIME 10 5741
TEST_TIME 11 5682
TEST_TIME 12 5717
TEST_TIME 13 5734
TEST_TIME 14 5955
TEST_TIME 15 6080
TEST_TIME 16 6564
TEST_TIME 17 6763
TEST_TIME 18 7069
TEST_TIME 19 7329
TEST_TIME 20 7437
TEST_TIME 21 8168
TEST_TIME 22 8004
TEST_TIME 23 8097
TEST_TIME 24 8014
TEST_TIME 25 8350
TEST_TIME 26 8595
TEST_TIME 27 8657
TEST_TIME 28 9529
TEST_TIME 29 10437
TEST_TIME 30 10236
TEST_TIME 31 10234
TEST_TIME 32 10092
TEST_TIME 33 9979
TEST_TIME 34 9915
TEST_TIME 35 9753
TEST_TIME 36 9614
TEST_TIME 37 9476
TEST_TIME 38 9349
TEST_TIME 39 9252
TEST_TIME 40 9197
TEST_TIME 41 9216
TEST_TIME 42 9065
TEST_TIME 43 9014
TEST_TIME 44 8919
TEST_TIME 45 8937
TEST_TIME 46 8898
TEST_TIME 47 8733
TEST_TIME 48 8863
TEST_TIME 49 9127
TEST_TIME 50 9030
TEST_TIME 51 9077
TEST_TIME 52 9187
TEST_TIME 53 9104
TEST_TIME 54 9164
TEST_TIME 55 9124
TEST_TIME 56 9125
TEST_TIME 57 9136
TEST_TIME 58 9219
TEST_TIME 59 9201
TEST_TIME 60 9184
TEST_TIME 61 9332
TEST_TIME 62 9226
TEST_TIME 63 9099
TEST_TIME 64 9038
TEST_TIME 65 9021
TEST_TIME 66 8984
TEST_TIME 67 9018
TEST_TIME 68 8994
TEST_TIME 69 8920
TEST_TIME 70 8886
TEST_TIME 71 8855
TEST_TIME 72 8822
TEST_TIME 73 8890
TEST_TIME 74 8939
TEST_TIME 75 8929
TEST_TIME 76 8958
TEST_TIME 77 9006
TEST_TIME 78 9013
TEST_TIME 79 8994
TEST_TIME 80 9002
TEST_TIME 81 9085
TEST_TIME 82 9074
TEST_TIME 83 9080
TEST_TIME 84 8998
TEST_TIME 85 8965
TEST_TIME 86 8975
TEST_TIME 87 8921
TEST_TIME 88 8897
TEST_TIME 89 8909
TEST_TIME 90 8872
TEST_TIME 91 8798
TEST_TIME 92 8896
TEST_TIME 93 8922
TEST_TIME 94 8919
TEST_TIME 95 8921
TEST_TIME 96 8975
TEST_TIME 97 8958
TEST_TIME 98 9022
TEST_TIME 99 8984
TEST_TIME 100 9016
TEST_TIME 101 9053
TEST_TIME 102 9116
TEST_TIME 103 9115
TEST_TIME 104 9112
TEST_TIME 105 9117
TEST_TIME 106 9205
TEST_TIME 107 9148
TEST_TIME 108 9083
TEST_TIME 109 9063
TEST_TIME 110 9021
TEST_TIME 111 9021
TEST_TIME 112 8998
TEST_TIME 113 8967
TEST_TIME 114 8940
TEST_TIME 115 8953
TEST_TIME 116 8921
TEST_TIME 117 8922
TEST_TIME 118 8963
TEST_TIME 119 8967
TEST_TIME 120 8985

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-08-2021 22:39:10 to 18-08-2021 22:39:14.

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 1511904 1511904 0 0.00 0 17076 7 0 0
doc_list_biud 1511904 1511904 0 0.00 0 44852 30 0 0
sp_add_invoice_to_stock 5276 5117 159 3.01 43 217855 19018 0 159
sp_cancel_adding_invoice 5330 5330 0 0.00 6 80961 942 0 0
sp_cancel_client_order 1775 1775 0 0.00 4 16230 359 0 0
sp_cancel_customer_reserve 520875 520875 0 0.00 1 44853 69 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 5878 72 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 5260 88 0 0
sp_cancel_supplier_invoice 5876 5862 14 0.23 2 19268 841 0 14
sp_cancel_supplier_order 371 371 0 0.00 5 1278079 119445 0 0
sp_cancel_write_off 9898 9898 0 0.00 1 9413 117 0 0
sp_client_order 21598 21598 0 0.00 4 23506 344 0 0
sp_customer_reserve 469188 469188 0 0.00 0 85969 191 0 0
sp_fill_shopping_cart 32828 32828 0 0.00 1 15395 43 0 0
sp_fill_shopping_cart_clo_res 469182 469182 0 0.00 0 13413 14 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 28818 304 0 0
sp_kill_cost_storno 16412 16412 0 0.00 0 6377 55 0 0
sp_kill_qstorno_ret_qs2qd 1057881 1057881 0 0.00 0 44850 37 0 0
sp_kill_qty_storno 559621 559621 0 0.00 0 44852 79 0 0
sp_lock_selected_doc 21470 21470 0 0.00 0 7283 6 0 0
sp_make_cost_storno 28833 28833 0 0.00 0 15361 48 0 0
sp_multiply_rows_for_pdistr 28833 28833 0 0.00 0 17003 30 0 0
sp_multiply_rows_for_qdistr 21598 21598 0 0.00 0 22363 151 0 0
sp_pay_from_customer 7255 7255 0 0.00 4 17083 114 0 0
sp_pay_to_supplier 6068 6068 0 0.00 5 13409 118 0 0
sp_payment_common 13323 13323 0 0.00 1 11317 28 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 16221 333 0 0
sp_qd_handle_on_invoice_upd_sts 10438 10438 0 0.00 0 13555 323 0 0
sp_qd_handle_on_reserve_upd_sts 10402 10402 0 0.00 0 14946 77 0 0
sp_reserve_write_off 10402 10402 0 0.00 6 14958 181 0 0
sp_supplier_invoice 5555 5555 0 0.00 6 72483 3155 0 0
sp_supplier_order 5689 5689 0 0.00 5 37278 1253 0 0
srv_aggregate_perf_data 114 114 0 0.00 2811 109883 15286 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1483 124666 40380 0 0
srv_find_qd_qs_mism 1028518 1028518 0 0.00 0 16418 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 35 50076 950 0 0
srv_make_money_saldo 816 816 0 0.00 1 4150 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 97774 343693 239597 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 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 0 9060 167 0 0
t$perf-norm:sp_cancel_adding_invoice 469 469 0 0.00 0 12114 138 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 3752 13 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 5876 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 1291 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 3038 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2174 17 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 21510 1594 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 4157 13 0 0
t$perf-norm:sp_client_order 21598 21598 0 0.00 0 8994 17 0 0
t$perf-norm:sp_customer_reserve 1102 1102 0 0.00 0 3778 8 0 0
t$perf-norm:sp_pay_from_customer 7255 7255 0 0.00 0 8584 14 0 0
t$perf-norm:sp_pay_to_supplier 6068 6068 0 0.00 0 9104 8 0 0
t$perf-norm:sp_reserve_write_off 10402 10402 0 0.00 0 9122 13 0 0
t$perf-norm:sp_supplier_invoice 5555 5555 0 0.00 0 7686 18 0 0
t$perf-norm:sp_supplier_order 5689 5689 0 0.00 0 8138 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 244 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 1952 25 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 4236 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 10534 10216 318 3.01 0 3865 16 0 318
v_all_customers 39164 39164 0 0.00 0 18 1 0 0
v_all_suppliers 30906 30906 0 0.00 0 115 1 0 0
v_all_wares 43196 43196 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 938 938 0 0.00 0 1015 8 0 0
v_cancel_client_order 3550 3550 0 0.00 0 1687 3 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 945 5 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 168 3 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3041 11 0 0
v_cancel_supplier_invoice 836 808 28 3.34 0 838 14 0 28
v_cancel_supplier_order 742 742 0 0.00 0 2906 9 0 0
v_cancel_write_off 2092 2092 0 0.00 0 5009 17 0 0
v_max_id_clo_ord 5686 5686 0 0.00 0 5917 3 0 0
v_max_id_clo_res 1102 1102 0 0.00 0 2682 13 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 4338 3 0 0
v_max_non_paid_invoice 1845 1845 0 0.00 0 4650 6 0 0
v_max_non_paid_realizn 3869 3869 0 0.00 0 3277 5 0 0
v_min_id_clo_ord 5686 5686 0 0.00 0 8977 7 0 0
v_min_id_clo_res 1102 1102 0 0.00 0 3209 13 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 6243 10 0 0
v_min_non_paid_invoice 6068 6068 0 0.00 0 8604 16 0 0
v_min_non_paid_realizn 7255 7255 0 0.00 0 3597 16 0 0
v_our_firm 10804 10804 0 0.00 0 13 1 0 0
v_reserve_write_off 20804 20804 0 0.00 0 3321 4 0 0
x_lock_depdocs_on_canc_invoice 10788 10788 0 0.00 0 18811 140 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 4377 361 0 0
x_make_qstorno_1000_1200 5689 5689 0 0.00 2 37272 1169 0 0
x_make_qstorno_1200_2000 5555 5555 0 0.00 2 72474 2994 0 0
x_make_qstorno_2100_3300 469188 469188 0 0.00 0 85968 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-08-2021 22:39:15 to 18-08-2021 22:39:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:54 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:56 23533 8192000000 10345859632 10659917824 1393385152 14.536 100 84 168 0 1427165536 5407968 28372416
20:10:28 27583 8192000000 10442831936 10770968576 1492156432 15.408 100 89 178 0 1528067536 7964160 27946944
20:12:02 14342 8192000000 10498229296 10842525696 1552333392 15.930 100 82 164 0 1586859344 9005216 25520736
20:13:21 13459 8192000000 10569119504 10930450432 1633588080 16.625 100 75 150 0 1665732480 9929680 22214720
20:14:41 8347 8192000000 10652756576 11019051008 1707879232 17.251 100 79 156 2 1741887536 11526928 22481376
20:15:52 4758 8192000000 10695565280 11074678784 1762946688 17.709 100 81 157 2 1798678800 12693360 23038752
20:16:58 3181 8192000000 10759375904 11142451200 1817041584 18.154 100 88 176 1 1856178752 15208832 23928336
20:18:02 1677 8192000000 10796586448 11185364992 1855497888 18.467 100 89 177 1 1895284608 14267952 25518768
20:19:06 1706 8192000000 10825487056 11218452480 1887929936 18.729 100 91 180 2 1925228896 12493952 24805008
20:20:07 1745 8192000000 10842112336 11239911424 1899186304 18.820 100 70 142 0 1926704880 5415264 22103312
20:21:09 6077 8192000000 10812212576 11239276544 1896276864 18.796 100 32 64 1 1912076400 7589168 8210368
20:22:16 7770 8192000000 10815772416 11253010432 1910074384 18.907 100 33 66 0 1926608128 7416576 9117168
20:23:25 11154 8192000000 10822804400 11256393728 1907893744 18.890 100 15 28 2 1917352000 5614336 3843920
20:24:37 52997 8192000000 10825623424 11268599808 1919979952 18.987 100 21 42 1 1931274720 4799120 6495648
20:26:30 6985 8192000000 10850716320 11277467648 1925090432 19.028 100 19 38 1 1936410640 4905456 6414752
20:27:38 7677 8192000000 10803875728 11236384768 1847782928 18.404 97 18 36 3 1857925312 5041968 5100416
20:28:46 66534 8192000000 10771055376 11182374912 1795876576 17.980 96 33 65 3 1810456080 4424672 10154832
20:30:53 10797 8192000000 10694848336 11110580224 1653647376 16.795 91 16 32 2 1663531040 5074144 4809520
20:32:05 5764 8192000000 10707176016 11130650624 1791204976 17.942 99 24 49 0 1803087984 5422144 6460864
20:33:12 13960 8192000000 10750753008 11160190976 1830594368 18.264 100 27 53 2 1842871632 4717216 7560048
20:34:27 38829 8192000000 10759408416 11184357376 1829653664 18.257 99 29 58 0 1844417120 6579200 8184256
20:36:08 5501 8192000000 10780470432 11211087872 1867762736 18.566 100 25 50 2 1880708048 5533984 7411328
20:37:15 10266 8192000000 10817831728 11239612416 1896906096 18.801 100 34 68 1 1912575984 5438336 10231552
20:38:30 6774 8192000000 10853781808 11273670656 1922706544 19.009 100 53 105 5 1945634816 7519168 15409104
20:39:40 20794 8192000000 10855552720 11284918272 1932563072 19.087 100 45 90 2 1953572848 8099424 12910352
20:41:05 2846 8192000000 10885748224 11311386624 1954352288 19.261 100 54 108 1 1981198688 11509504 15336896
20:42:13 11630 8192000000 10900243296 11331604480 1967866384 19.369 100 53 107 3 1995598112 12391248 15340480
20:43:31 12591 8192000000 10906135232 11340304384 1953933616 19.258 99 47 94 1 1983343424 15221744 14188064
20:44:50 3596 8192000000 10917973440 11346235392 1972938944 19.409 100 59 113 2 2008130352 18260080 16931328
20:45:57 12966 8192000000 10923415488 11369373696 1986245392 19.514 100 46 92 0 2021501152 21011424 14244336
20:47:12 9195 8192000000 10938476144 11373895680 1982497248 19.484 99 55 110 1 2018856736 19786912 16572576
20:48:26 20700 8192000000 10954535968 11387670528 2006349056 19.673 100 53 106 1 2045709056 23178240 16181760
20:49:51 3933 8192000000 10946669216 11372130304 1985455968 19.508 100 56 111 0 2034811568 32629584 16726016
20:51:00 4549 8192000000 10954838112 11377045504 1990219680 19.546 100 67 134 0 2039175840 28148784 20807376
20:52:09 12372 8192000000 10974386896 11408343040 2013323040 19.728 100 65 130 0 2065914128 32143936 20447152
20:53:27 8316 8192000000 10978976656 11410116608 2018128736 19.765 100 62 123 0 2068231424 30027472 20075216
20:54:39 7158 8192000000 10959393600 11380879360 1986834320 19.519 100 74 148 0 2044910848 34857472 23219056
20:55:51 9373 8192000000 10935105984 11352371200 1946714032 19.200 99 74 148 1 2012364064 42088272 23561760
20:57:06 2452 8192000000 10971006560 11384868864 1986228544 19.514 100 89 175 0 2054176096 40426240 27521312
20:58:14 1482 8192000000 11001875712 11411456000 2000953408 19.630 100 86 172 1 2079335488 50958400 27423680
20:59:19 10523 8192000000 10991575360 11422547968 2012472704 19.721 100 63 126 1 2073937856 41239424 20225728
21:00:37 16085 8192000000 10945949616 11384791040 1964096928 19.339 99 32 64 3 2018208016 43035120 11075968
21:02:09 15285 8192000000 10939031648 11391172608 1963838800 19.337 100 29 58 0 2026105584 51658208 10608576
21:03:27 12067 8192000000 10922812224 11373326336 1935926880 19.114 99 26 52 1 1999097920 53264624 9906416
21:04:52 19930 8192000000 10920736352 11377250304 1921319424 18.997 99 21 42 1 1990446112 60562064 8564624
21:06:30 34492 8192000000 10841820768 11295076352 1864898608 18.543 97 33 63 1 1896687584 21863488 9925488
21:08:11 20889 8192000000 10862678144 11300483072 1929338032 19.062 100 30 54 4 1943041792 6232528 7471232
21:09:36 84956 8192000000 10845611456 11303518208 1864614720 18.541 96 13 26 0 1880262176 12324960 3322496
21:12:12 59189 8192000000 10883675504 11342385152 1950209472 19.228 100 9 18 1 1961601632 8961248 2430912
21:14:12 83255 8192000000 10902388864 11350880256 1966882800 19.361 100 13 26 0 1977957856 7406624 3668432
21:16:40 28821 8192000000 10882731072 11343200256 1948634336 19.216 99 35 70 0 1969431072 9493696 11303040
21:18:11 101454 8192000000 10890973888 11358904320 1959028032 19.298 99 14 28 0 1972882608 9780704 4073872
21:20:54 21922 8192000000 10906111072 11358887936 1923598384 19.016 97 23 46 2 1947309888 17218192 6493312
21:22:24 43079 8192000000 10915928704 11385204736 1986983504 19.520 100 20 40 1 2014006272 20597488 6425280
21:24:07 64935 8192000000 10918006608 11390849024 1973745824 19.415 99 19 38 2 2001405760 21468192 6191744
21:26:58 37001 8192000000 10928983136 11383218176 1984879344 19.503 100 23 46 2 1998659376 7611040 6168992
21:28:35 42275 8192000000 10928636304 11395362816 1979152752 19.458 99 35 68 1 2001341376 12214992 9973632
21:30:22 47281 8192000000 10927122544 11397496832 1983772528 19.495 99 30 60 0 2002066864 10352480 7941856
21:32:19 43968 8192000000 10920232992 11375861760 1968071200 19.370 99 32 64 1 1986485344 9431648 8982496
21:34:07 66709 8192000000 10927471328 11395252224 1957248448 19.284 98 20 40 2 1981288096 18794480 5245168
21:36:15 22151 8192000000 10919399232 11379716096 1983656016 19.494 100 41 82 1 2003862816 8943776 11263024
21:37:37 35073 8192000000 10914117600 11373637632 1979572432 19.461 100 43 85 2 2006916176 15645888 11697856
21:39:15 82166 8192000000 10908379840 11363590144 1975751088 19.431 100 16 32 0 1992566288 12586384 4228816
21:41:52 9439 8192000000 10884462992 11343519744 1944870928 19.186 99 28 55 3 1963327888 10179920 8277040
21:43:02 61830 8192000000 10858316976 11329277952 1893778736 18.776 97 18 36 0 1912845696 14324976 4741984
21:45:07 57268 8192000000 10871205472 11349475328 1945369280 19.190 100 9 19 1 1960416224 12689888 2357056
21:48:09 38597 8192000000 10877172832 11347046400 1923911840 19.018 98 13 26 0 1934001632 6837376 3252416
21:49:48 49799 8192000000 10876991632 11340914688 1948296720 19.213 100 27 54 2 1969994944 13900784 7797440
21:51:58 124666 8192000000 10872399408 11332956160 1800464048 18.018 92 10 20 0 1819984016 16600896 2919072
21:55:15 43280 8192000000 10873611168 11342622720 1913101632 18.932 98 12 24 0 1927170528 11078704 2990192
21:56:59 116442 8192000000 10863132624 11339968512 1886519632 18.718 97 25 50 1 1910426576 16698848 7208096
21:59:56 81771 8192000000 10853724880 11313635328 1864699776 18.541 96 17 34 1 1878495152 8909152 4886224
22:02:19 51448 8192000000 10860176144 11331354624 1909314704 18.901 98 12 24 0 1919247136 6859392 3073040
22:04:12 51240 8192000000 10883596848 11338797056 1909086400 18.899 98 32 62 6 1930190608 12510976 8593232
22:06:06 96154 8192000000 10895052112 11372781568 1957865648 19.289 100 7 14 0 1975019536 15301632 1852256
22:09:41 98176 8192000000 10885284848 11359715328 1892193088 18.763 96 7 14 1 1903799104 10213952 1392064
22:12:20 51534 8192000000 10917136352 11378724864 1978110144 19.450 100 26 53 2 1996689424 11262432 7316848
22:14:13 55043 8192000000 10919227264 11391938560 1987564992 19.525 100 24 47 2 2011315536 16992288 6758256
22:16:09 23867 8192000000 10912846192 11354873856 1935163648 19.108 98 52 104 1 1959998736 9668256 15166832
22:17:38 105936 8192000000 10903712688 11404959744 1917206736 18.964 97 8 17 0 1938151888 18998064 1947088
22:20:24 44642 8192000000 10886339104 11356168192 1921534256 18.999 98 22 43 1 1942329536 14649504 6145776
22:22:09 13936 8192000000 10901290016 11346505728 1935702352 19.112 99 63 119 3 1961942496 8987488 17252656
22:23:25 103025 8192000000 10872743936 11340800000 1877716128 18.647 96 13 26 1 1894768352 13793968 3258256
22:26:23 50495 8192000000 10840646080 11309854720 1899999408 18.826 98 6 12 0 1908873008 7036080 1837520
22:28:22 31167 8192000000 10873787344 11339489280 1944890288 19.186 100 33 66 0 1963334048 9019664 9424096
22:29:57 44414 8192000000 10874198400 11355226112 1959515376 19.302 100 6 12 0 1967906112 6730768 1659968
22:31:42 58814 8192000000 10864356272 11350622208 1919008064 18.979 98 12 23 2 1934517152 11465984 4043104
22:33:42 23653 8192000000 10875599488 11346219008 1947378416 19.206 100 25 48 6 1965133632 10593568 7161648
22:36:25 34155 8192000000 10848867136 11321565184 1918148064 18.972 99 31 62 0 1936068832 9240848 8679920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-08-2021 22:39:21 to 18-08-2021 22:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 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:01, from 18-08-2021 22:39:37 to 18-08-2021 22:39:38.

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          245818
MON$OLDEST_ACTIVE               247052
MON$OLDEST_SNAPSHOT             247052
MON$NEXT_TRANSACTION            247052
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                       2007808
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Wed Aug 18 22:37:54 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Wed Aug 18 22:37:54 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Wed Aug 18 22:38:01 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Wed Aug 18 22:38:01 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
--- end of diff output ---

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

18.08.21 22:39:39. 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-08-18 22:39:42 No severe PSQL-related problems occured
18.08.21 22:39:43. End of report.