Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.06.2022 01:40
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 16356116 kB
MemAvailable 31220804 kB
Buffers 0 kB
Cached 15392568 kB
SwapCached 2496 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.10
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-06-30 01:38:18
DTS_END                         2022-06-30 01:38:18.4970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34530
TRN_ID                          232935
ATT_ID                          670
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: 9016.18 23180 1081942
customer order: creation 161.69 394 19403
customer order: refuse 14.28 375 1713
order to supplier: creation 43.45 1323 5214
order to supplier: removal 3.27 98968 392
invoice (draft): creation 43.53 3519 5223
invoice (draft): removal 51.47 804 6176
invoice accept: apply 40.63 17672 4875
invoice accept: cancel 46.98 860 5637
customer reserve: creation 3712.16 176 445459
customer reserve: removal 4615.37 55 553844
realization accept: apply 78.58 195 9430
realization accept: cancel 83.06 115 9967
payment from customer: creation 54.93 142 6591
payment from customer: removal 5.83 90 700
payment to supplier: creation 46.44 143 5573
payment to supplier: removal 3.12 84 374
service: total inventory turnovers 4.68 1189 562
service: total monetary turnovers 5.85 64 702
service: aggregate perf. data 0.86 21065 103
service: refresh index statistics 0.03 316359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-06-2022 01:41:07 to 30-06-2022 01:41:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5570
TEST_TIME 2 4263
TEST_TIME 3 3750
TEST_TIME 4 4640
TEST_TIME 5 4653
TEST_TIME 6 5118
TEST_TIME 7 5494
TEST_TIME 8 6000
TEST_TIME 9 6137
TEST_TIME 10 6322
TEST_TIME 11 6589
TEST_TIME 12 6494
TEST_TIME 13 6579
TEST_TIME 14 6645
TEST_TIME 15 6754
TEST_TIME 16 6771
TEST_TIME 17 6850
TEST_TIME 18 7004
TEST_TIME 19 7303
TEST_TIME 20 7522
TEST_TIME 21 7745
TEST_TIME 22 7911
TEST_TIME 23 8443
TEST_TIME 24 8647
TEST_TIME 25 9096
TEST_TIME 26 9753
TEST_TIME 27 9805
TEST_TIME 28 9938
TEST_TIME 29 9705
TEST_TIME 30 9921
TEST_TIME 31 10059
TEST_TIME 32 9880
TEST_TIME 33 9784
TEST_TIME 34 9617
TEST_TIME 35 9515
TEST_TIME 36 9421
TEST_TIME 37 9455
TEST_TIME 38 9332
TEST_TIME 39 9462
TEST_TIME 40 9380
TEST_TIME 41 9385
TEST_TIME 42 9224
TEST_TIME 43 9213
TEST_TIME 44 9135
TEST_TIME 45 9222
TEST_TIME 46 9344
TEST_TIME 47 9198
TEST_TIME 48 9058
TEST_TIME 49 9013
TEST_TIME 50 8842
TEST_TIME 51 8794
TEST_TIME 52 8809
TEST_TIME 53 8793
TEST_TIME 54 8796
TEST_TIME 55 8852
TEST_TIME 56 8783
TEST_TIME 57 8909
TEST_TIME 58 8929
TEST_TIME 59 8866
TEST_TIME 60 8897
TEST_TIME 61 9105
TEST_TIME 62 9105
TEST_TIME 63 9125
TEST_TIME 64 9235
TEST_TIME 65 9278
TEST_TIME 66 9331
TEST_TIME 67 9441
TEST_TIME 68 9377
TEST_TIME 69 9256
TEST_TIME 70 9212
TEST_TIME 71 9189
TEST_TIME 72 9153
TEST_TIME 73 9143
TEST_TIME 74 9113
TEST_TIME 75 9066
TEST_TIME 76 9027
TEST_TIME 77 9029
TEST_TIME 78 9020
TEST_TIME 79 9052
TEST_TIME 80 9080
TEST_TIME 81 9085
TEST_TIME 82 9141
TEST_TIME 83 9133
TEST_TIME 84 9135
TEST_TIME 85 9205
TEST_TIME 86 9180
TEST_TIME 87 9242
TEST_TIME 88 9278
TEST_TIME 89 9232
TEST_TIME 90 9372
TEST_TIME 91 9275
TEST_TIME 92 9200
TEST_TIME 93 9193
TEST_TIME 94 9161
TEST_TIME 95 9130
TEST_TIME 96 9137
TEST_TIME 97 9114
TEST_TIME 98 9101
TEST_TIME 99 9060
TEST_TIME 100 9060
TEST_TIME 101 9148
TEST_TIME 102 9141
TEST_TIME 103 9142
TEST_TIME 104 9169
TEST_TIME 105 9213
TEST_TIME 106 9293
TEST_TIME 107 9247
TEST_TIME 108 9303
TEST_TIME 109 9294
TEST_TIME 110 9343
TEST_TIME 111 9326
TEST_TIME 112 9254
TEST_TIME 113 9244
TEST_TIME 114 9223
TEST_TIME 115 9210
TEST_TIME 116 9160
TEST_TIME 117 9133
TEST_TIME 118 9102
TEST_TIME 119 9055
TEST_TIME 120 9018

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-06-2022 01:41:11 to 30-06-2022 01:41: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 1495272 1495272 0 0.00 0 23325 6 0 0
doc_list_biud 1495267 1495267 0 0.00 0 62803 26 0 0
sp_add_invoice_to_stock 4965 4875 90 1.81 37 177240 17672 0 90
sp_cancel_adding_invoice 5637 5637 0 0.00 3 71572 861 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 10613 375 0 0
sp_cancel_customer_reserve 553844 553844 0 0.00 0 52334 55 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 11096 91 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 2 7385 84 0 0
sp_cancel_supplier_invoice 6183 6176 7 0.11 1 63896 805 0 7
sp_cancel_supplier_order 392 392 0 0.00 6 1015591 98969 0 0
sp_cancel_write_off 9967 9967 0 0.00 1 17796 116 0 0
sp_client_order 19403 19403 0 0.00 3 23492 394 0 0
sp_customer_reserve 445459 445459 0 0.00 0 67420 177 0 0
sp_fill_shopping_cart 29826 29826 0 0.00 1 12408 40 0 0
sp_fill_shopping_cart_clo_res 445455 445455 0 0.00 0 19666 16 0 0
sp_get_clo_for_invoice 4871 4871 0 0.00 0 31850 380 0 0
sp_kill_cost_storno 16678 16678 0 0.00 0 15778 57 0 0
sp_kill_qstorno_ret_qs2qd 1124223 1124223 0 0.00 0 62801 30 0 0
sp_kill_qty_storno 592030 592030 0 0.00 0 62802 66 0 0
sp_lock_selected_doc 19884 19884 0 0.00 0 10794 6 0 0
sp_make_cost_storno 26465 26465 0 0.00 0 12168 57 0 0
sp_multiply_rows_for_pdistr 26465 26465 0 0.00 0 23316 33 0 0
sp_multiply_rows_for_qdistr 19403 19403 0 0.00 0 20867 185 0 0
sp_pay_from_customer 6591 6591 0 0.00 3 23608 142 0 0
sp_pay_to_supplier 5573 5573 0 0.00 4 21625 144 0 0
sp_payment_common 12164 12164 0 0.00 1 23583 57 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 10600 344 0 0
sp_qd_handle_on_invoice_upd_sts 10508 10508 0 0.00 0 18382 336 0 0
sp_qd_handle_on_reserve_upd_sts 9430 9430 0 0.00 0 8827 65 0 0
sp_reserve_write_off 9430 9430 0 0.00 6 23727 196 0 0
sp_supplier_invoice 5223 5223 0 0.00 6 90175 3519 0 0
sp_supplier_order 5214 5214 0 0.00 5 41335 1323 0 0
srv_aggregate_perf_data 103 103 0 0.00 2519 511067 21065 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1265 204722 48385 0 0
srv_find_qd_qs_mism 1037038 1037038 0 0.00 0 10319 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 35 57330 1189 0 0
srv_make_money_saldo 702 702 0 0.00 1 26376 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 154346 522086 316360 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4875 4875 0 0.00 0 8732 172 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 4204 76 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 2532 11 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 8473 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 3712 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 7207 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 485 12 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 21991 1602 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 1962 7 0 0
t$perf-norm:sp_client_order 19403 19403 0 0.00 0 6843 17 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 1269 6 0 0
t$perf-norm:sp_pay_from_customer 6591 6591 0 0.00 0 7225 8 0 0
t$perf-norm:sp_pay_to_supplier 5573 5573 0 0.00 0 2541 9 0 0
t$perf-norm:sp_reserve_write_off 9430 9430 0 0.00 0 7364 13 0 0
t$perf-norm:sp_supplier_invoice 5223 5223 0 0.00 0 7031 17 0 0
t$perf-norm:sp_supplier_order 5214 5214 0 0.00 0 5425 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 249 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 76 2 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1191 22 0 0
t$perf-norm:srv_make_money_saldo 702 702 0 0.00 0 3265 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9918 9738 180 1.81 0 6889 16 0 180
v_all_customers 35010 35010 0 0.00 0 25 1 0 0
v_all_suppliers 29074 29074 0 0.00 0 20 1 0 0
v_all_wares 38806 38806 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1149 7 0 0
v_cancel_client_order 3426 3426 0 0.00 0 2389 4 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 458 4 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 3522 7 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 1087 7 0 0
v_cancel_supplier_invoice 748 734 14 1.87 0 2198 20 0 14
v_cancel_supplier_order 784 784 0 0.00 0 1581 8 0 0
v_cancel_write_off 1872 1872 0 0.00 0 1043 11 0 0
v_max_id_clo_ord 5214 5214 0 0.00 0 1651 2 0 0
v_max_id_clo_res 965 965 0 0.00 0 4992 20 0 0
v_max_id_ord_sup 5209 5209 0 0.00 0 4924 3 0 0
v_max_non_paid_invoice 1459 1459 0 0.00 0 763 2 0 0
v_max_non_paid_realizn 3704 3704 0 0.00 0 8181 9 0 0
v_min_id_clo_ord 5214 5214 0 0.00 0 6144 8 0 0
v_min_id_clo_res 965 965 0 0.00 0 550 3 0 0
v_min_id_ord_sup 5209 5209 0 0.00 0 6827 6 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 21600 39 0 0
v_min_non_paid_realizn 6591 6591 0 0.00 0 20848 52 0 0
v_our_firm 9570 9570 0 0.00 0 14 1 0 0
v_reserve_write_off 18860 18860 0 0.00 0 2817 5 0 0
x_lock_depdocs_on_canc_invoice 11446 11446 0 0.00 0 24935 113 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 12250 349 0 0
x_make_qstorno_1000_1200 5214 5214 0 0.00 1 41321 1248 0 0
x_make_qstorno_1200_2000 5223 5223 0 0.00 2 84771 3372 0 0
x_make_qstorno_2100_3300 445459 445459 0 0.00 0 67419 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-06-2022 01:41:15 to 30-06-2022 01:41: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
23:08:22 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 24735 8192000000 9899557392 10209951744 977932112 10.664 100 84 168 0 1006316528 5835616 22548800
23:10:57 26632 8192000000 9949299552 10272645120 1031824256 11.186 100 86 172 0 1062385056 8250288 22310512
23:12:28 10748 8192000000 9975525648 10307825664 1063986336 11.495 100 80 160 1 1093489456 9542448 19960672
23:13:44 13426 8192000000 10037593888 10387111936 1124626576 12.071 100 73 146 0 1152261104 10602512 17032016
23:15:00 7606 8192000000 10095617744 10454740992 1178031744 12.572 100 79 155 1 1207538912 11567040 17940128
23:16:10 4783 8192000000 10132839216 10503974912 1220898480 12.970 100 80 160 0 1251415632 13030592 17486560
23:17:17 2585 8192000000 10161152496 10535034880 1244745072 13.190 100 88 173 1 1277681152 14196480 18739600
23:18:21 1972 8192000000 10186918736 10566799360 1272686832 13.446 100 82 165 2 1304504320 13679776 18137712
23:19:25 1077 8192000000 10213497632 10597683200 1295006112 13.650 100 84 167 0 1327321888 12965120 19350656
23:20:29 1131 8192000000 10218320960 10608656384 1308097616 13.769 100 85 167 4 1338756304 12080992 18577696
23:21:34 764 8192000000 10234908096 10626486272 1317275776 13.852 100 82 160 1 1347629392 11682640 18670976
23:22:36 5345 8192000000 10215349504 10622832640 1325335088 13.925 100 38 77 1 1339064736 5141792 8587856
23:23:43 14597 8192000000 10218394000 10641260544 1329631936 13.964 100 12 24 1 1341286336 9435072 2219328
23:24:59 64832 8192000000 10251610960 10662936576 1341465664 14.071 100 45 86 1 1357292848 5494848 10332336
23:27:05 7977 8192000000 10228214240 10650120192 1324007472 13.913 99 24 48 0 1335992992 6619168 5366352
23:28:14 7313 8192000000 10214455040 10635632640 1320755984 13.884 100 17 32 0 1329761776 5384160 3621632
23:29:22 7549 8192000000 10160239472 10563776512 1229874400 13.053 96 12 26 0 1237676336 5083376 2718560
23:30:32 26076 8192000000 10151616160 10557042688 1232376240 13.076 97 44 86 1 1248364736 5558288 10430208
23:31:59 5312 8192000000 10140137136 10545967104 1250579104 13.244 100 31 62 1 1263277312 5128544 7569664
23:33:06 8679 8192000000 10156664768 10563076096 1235587328 13.106 97 22 46 0 1245693472 4813664 5292480
23:34:15 6950 8192000000 10178050576 10582810624 1279050848 13.504 100 30 59 0 1291775968 5072096 7653024
23:35:27 39351 8192000000 10171018944 10579914752 1259605568 13.326 98 34 68 0 1272791200 5516432 7669200
23:37:07 13317 8192000000 10181758208 10596798464 1290838240 13.612 100 16 32 0 1299953696 4948432 4167024
23:38:23 21009 8192000000 10226105728 10634375168 1323706176 13.910 100 42 84 0 1338920016 5449808 9764032
23:39:50 117409 8192000000 10253417456 10673496064 1343469776 14.089 100 31 62 0 1357712256 7065600 7176880
23:42:59 8442 8192000000 10271398464 10688389120 1362696944 14.262 100 62 124 0 1387797392 10913152 14187296
23:44:19 27995 8192000000 10283567056 10705944576 1368526112 14.314 100 41 82 1 1393024976 14823792 9675072
23:45:50 5451 8192000000 10281270336 10702364672 1370485552 14.331 100 44 87 0 1397006288 16264496 10256240
23:47:02 6461 8192000000 10294633776 10717093888 1372382704 14.348 100 48 97 2 1403580240 19766464 11431072
23:48:14 13267 8192000000 10315565408 10741391360 1390613152 14.511 100 58 114 1 1427977552 23413776 13950624
23:49:36 8308 8192000000 10285219872 10705559552 1352066112 14.166 98 52 100 0 1389111568 24715824 12329632
23:51:01 4563 8192000000 10298542192 10721640448 1375078032 14.373 100 46 91 0 1411622080 24950464 11593584
23:52:10 4103 8192000000 10299272464 10714836992 1364920864 14.282 100 56 106 1 1404791424 26687152 13183408
23:53:21 4215 8192000000 10306642896 10720260096 1368429968 14.313 100 59 118 1 1414701024 31604992 14666064
23:54:30 2220 8192000000 10291878016 10699337728 1349603072 14.144 99 68 135 2 1400044576 34089248 16352256
23:55:38 1264 8192000000 10327400848 10726076416 1369532192 14.323 100 80 157 0 1427826288 39214368 19079728
23:56:45 2147 8192000000 10336755552 10742128640 1376617616 14.386 100 72 143 0 1438321888 43480304 18223968
23:57:53 2730 8192000000 10358535936 10767687680 1396069776 14.560 100 83 165 0 1462719088 45757136 20892176
23:59:00 7984 8192000000 10337432288 10761232384 1382305120 14.437 100 44 88 1 1443095888 49118880 11671888
00:00:24 11182 8192000000 10330863152 10750738432 1376489680 14.385 100 51 98 1 1435593360 45278944 13824736
00:01:39 33038 8192000000 10300595168 10726154240 1355465536 14.197 99 28 56 1 1408912960 45316864 8130560
00:03:15 21163 8192000000 10290815888 10724290560 1361007008 14.246 100 38 76 0 1403575216 32765616 9802592
00:04:41 10390 8192000000 10303207376 10737426432 1366241152 14.293 100 49 95 3 1408583584 30542544 11799888
00:06:01 36416 8192000000 10273378160 10709680128 1351024128 14.157 100 16 32 0 1377277248 21634736 4618384
00:07:56 16422 8192000000 10253317056 10696048640 1344148320 14.095 100 23 46 1 1367392736 17640464 5603952
00:09:44 94046 8192000000 10269635552 10710368256 1350707696 14.154 99 16 32 1 1364759696 10668896 3383104
00:12:24 74733 8192000000 10272438496 10707398656 1344737840 14.100 98 25 48 0 1365112336 14705120 5669376
00:14:54 83977 8192000000 10275240128 10713825280 1364359904 14.276 100 20 40 0 1383630176 14293856 4976416
00:17:21 204721 8192000000 10297089744 10758926336 1371112048 14.337 99 10 20 0 1395953808 22489696 2352064
00:21:48 23044 8192000000 10325060864 10763800576 1400993840 14.604 100 34 63 0 1426152784 17522320 7636624
00:23:11 71840 8192000000 10295621392 10770374656 1385602960 14.467 99 12 25 0 1409961040 21510976 2847104
00:26:28 8634 8192000000 10282769408 10741092352 1382292128 14.437 99 12 24 0 1390792096 5887696 2612272
00:27:43 80359 8192000000 10296191312 10758651904 1393527152 14.537 100 14 28 0 1410761600 13590688 3643760
00:30:04 66284 8192000000 10286335104 10758369280 1388026304 14.488 99 8 16 0 1400467472 10181488 2259680
00:32:11 58374 8192000000 10297261216 10747944960 1380397200 14.420 99 21 41 0 1394390480 9654576 4338704
00:34:17 33798 8192000000 10304031552 10755813376 1386457072 14.474 99 41 80 0 1406482096 10731856 9293168
00:35:56 57622 8192000000 10312932992 10768670720 1404084944 14.631 100 17 32 0 1421653344 13931488 3636912
00:37:59 97362 8192000000 10301163728 10761547776 1347048640 14.121 96 12 24 0 1372225264 22588576 2588048
00:41:12 59976 8192000000 10302486912 10757816320 1366745824 14.298 99 10 20 0 1388565200 19727552 2091824
00:43:23 98971 8192000000 10287092032 10741997568 1333942272 14.003 97 13 24 0 1352892832 16319808 2630752
00:46:03 115182 8192000000 10281422544 10717896704 1307765040 13.766 95 15 30 1 1323587872 12748128 3074704
00:49:06 13881 8192000000 10262884096 10713694208 1349277488 14.141 99 38 76 0 1369369376 12011488 8080400
00:50:21 105478 8192000000 10256713792 10720911360 1325286896 13.925 97 11 23 0 1342081680 14544672 2250112
00:53:06 49075 8192000000 10266112400 10717057024 1349873184 14.146 98 11 23 0 1360253392 7706832 2673376
00:54:56 33248 8192000000 10279095184 10737131520 1383285008 14.446 100 20 40 0 1398812704 10770848 4756848
00:56:44 51867 8192000000 10280684736 10734882816 1329821872 13.966 96 28 56 0 1348087312 11455136 6810304
00:58:38 47629 8192000000 10279594224 10733068288 1368455008 14.313 99 27 54 0 1387430432 12898544 6076880
01:00:36 37994 8192000000 10271773456 10705526784 1312591008 13.810 96 32 64 1 1328297760 8385040 7321712
01:02:15 42588 8192000000 10283323008 10726514688 1371617824 14.342 100 33 66 0 1391891664 12697392 7576448
01:04:08 30947 8192000000 10273341712 10707849216 1330606112 13.973 97 35 67 0 1348912464 9826672 8479680
01:05:50 60663 8192000000 10271443888 10722521088 1356681504 14.208 100 21 42 0 1375682688 14622672 4378512
01:08:58 87612 8192000000 10268835440 10711371776 1347083808 14.121 98 26 52 0 1369235936 16234128 5918000
01:11:26 55779 8192000000 10272385568 10736918528 1368833632 14.317 99 12 24 0 1379229872 8008304 2387936
01:13:22 57821 8192000000 10272024816 10726432768 1357336960 14.213 98 9 18 0 1365964592 6785424 1842208
01:15:22 39455 8192000000 10291035552 10744274944 1378125248 14.400 99 15 29 1 1387973440 6508128 3340064
01:17:02 63218 8192000000 10319986080 10769260544 1389025600 14.497 99 16 32 0 1410181344 16971680 4184064
01:19:16 85649 8192000000 10321200432 10778263552 1406300384 14.651 100 18 37 0 1430553392 20614416 3638592
01:21:44 41432 8192000000 10316746720 10770747392 1401978384 14.613 100 34 68 1 1427819296 17638304 8202608
01:23:32 96762 8192000000 10327702080 10798059520 1412701664 14.708 100 16 32 0 1431585440 15431248 3452528
01:26:09 61624 8192000000 10322481968 10782437376 1397858752 14.576 99 42 84 1 1421776512 14765120 9152640
01:28:11 64488 8192000000 10304745136 10762805248 1342853056 14.083 95 28 56 0 1361150640 12130032 6167552
01:30:16 93596 8192000000 10291649072 10750996480 1380895136 14.425 99 25 50 0 1395357936 9128352 5334448
01:33:08 53079 8192000000 10284238320 10743697408 1355726496 14.199 97 11 22 0 1373475168 15618192 2130480
01:35:13 65447 8192000000 10292950560 10748076032 1358429840 14.223 97 23 44 0 1382146128 18547696 5168592
01:37:22 65248 8192000000 9901663456 10307104768 1019299088 11.065 74 10 20 0 1033709168 12179424 2230656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-06-2022 01:41:21 to 30-06-2022 01:41:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-06-2022 01:41:36 to 30-06-2022 01:41: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          229580
MON$OLDEST_ACTIVE               234547
MON$OLDEST_SNAPSHOT             234547
MON$NEXT_TRANSACTION            234547
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                       2017856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33605 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33605 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33605 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33605 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:38 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:39 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:40 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:44 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:46 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:46 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Thu Jun 30 01:38:47 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
--- end of diff output ---

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

30.06.22 01:41: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
2022-06-30 01:41:42 No severe PSQL-related problems occured
30.06.22 01:41:43. End of report.