Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 19: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 16587016 kB
MemAvailable 31017276 kB
Buffers 0 kB
Cached 15128564 kB
SwapCached 2688 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-16 19:37:48
DTS_END                         2021-08-16 19:37:48.3690
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/49038
TRN_ID                          241354
ATT_ID                          657
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: 9032.19 19234 1083863
customer order: creation 176.03 366 21124
customer order: refuse 14.25 344 1710
order to supplier: creation 46.19 1287 5543
order to supplier: removal 3.22 102273 386
invoice (draft): creation 47.06 3308 5647
invoice (draft): removal 49.24 781 5909
invoice accept: apply 43.15 19113 5178
invoice accept: cancel 43.83 805 5260
customer reserve: creation 4012.39 188 481487
customer reserve: removal 4294.19 61 515303
realization accept: apply 86.48 174 10378
realization accept: cancel 82.57 130 9908
payment from customer: creation 61.08 153 7329
payment from customer: removal 5.83 93 700
payment to supplier: creation 50.43 154 6052
payment to supplier: removal 3.37 54 404
service: total inventory turnovers 5.08 788 609
service: total monetary turnovers 6.93 23 831
service: aggregate perf. data 0.84 20329 101
service: refresh index statistics 0.03 234247 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 19:40:19 to 16-08-2021 19:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2822
TEST_TIME 2 2374
TEST_TIME 3 2608
TEST_TIME 4 3290
TEST_TIME 5 3518
TEST_TIME 6 3854
TEST_TIME 7 3955
TEST_TIME 8 4240
TEST_TIME 9 4427
TEST_TIME 10 4762
TEST_TIME 11 4974
TEST_TIME 12 5441
TEST_TIME 13 5882
TEST_TIME 14 6156
TEST_TIME 15 6255
TEST_TIME 16 6258
TEST_TIME 17 6457
TEST_TIME 18 6693
TEST_TIME 19 6975
TEST_TIME 20 7744
TEST_TIME 21 8345
TEST_TIME 22 8209
TEST_TIME 23 8582
TEST_TIME 24 8974
TEST_TIME 25 10118
TEST_TIME 26 9929
TEST_TIME 27 9797
TEST_TIME 28 9702
TEST_TIME 29 9565
TEST_TIME 30 9464
TEST_TIME 31 9288
TEST_TIME 32 9209
TEST_TIME 33 9222
TEST_TIME 34 9254
TEST_TIME 35 9210
TEST_TIME 36 9146
TEST_TIME 37 9076
TEST_TIME 38 9060
TEST_TIME 39 9057
TEST_TIME 40 9066
TEST_TIME 41 9113
TEST_TIME 42 9100
TEST_TIME 43 9098
TEST_TIME 44 9080
TEST_TIME 45 9085
TEST_TIME 46 9269
TEST_TIME 47 9241
TEST_TIME 48 9206
TEST_TIME 49 9399
TEST_TIME 50 9375
TEST_TIME 51 9353
TEST_TIME 52 9436
TEST_TIME 53 9424
TEST_TIME 54 9351
TEST_TIME 55 9228
TEST_TIME 56 9151
TEST_TIME 57 9096
TEST_TIME 58 9097
TEST_TIME 59 9052
TEST_TIME 60 8958
TEST_TIME 61 8900
TEST_TIME 62 8975
TEST_TIME 63 9120
TEST_TIME 64 9096
TEST_TIME 65 9140
TEST_TIME 66 9201
TEST_TIME 67 9163
TEST_TIME 68 9212
TEST_TIME 69 9181
TEST_TIME 70 9215
TEST_TIME 71 9257
TEST_TIME 72 9323
TEST_TIME 73 9326
TEST_TIME 74 9426
TEST_TIME 75 9399
TEST_TIME 76 9315
TEST_TIME 77 9216
TEST_TIME 78 9219
TEST_TIME 79 9253
TEST_TIME 80 9199
TEST_TIME 81 9164
TEST_TIME 82 9112
TEST_TIME 83 9046
TEST_TIME 84 9024
TEST_TIME 85 9037
TEST_TIME 86 9084
TEST_TIME 87 9109
TEST_TIME 88 9113
TEST_TIME 89 9174
TEST_TIME 90 9170
TEST_TIME 91 9225
TEST_TIME 92 9294
TEST_TIME 93 9262
TEST_TIME 94 9305
TEST_TIME 95 9355
TEST_TIME 96 9324
TEST_TIME 97 9302
TEST_TIME 98 9257
TEST_TIME 99 9210
TEST_TIME 100 9212
TEST_TIME 101 9160
TEST_TIME 102 9117
TEST_TIME 103 9061
TEST_TIME 104 9050
TEST_TIME 105 9047
TEST_TIME 106 9056
TEST_TIME 107 9056
TEST_TIME 108 9069
TEST_TIME 109 9075
TEST_TIME 110 9083
TEST_TIME 111 9084
TEST_TIME 112 9148
TEST_TIME 113 9152
TEST_TIME 114 9198
TEST_TIME 115 9159
TEST_TIME 116 9159
TEST_TIME 117 9174
TEST_TIME 118 9118
TEST_TIME 119 9092
TEST_TIME 120 9035

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-08-2021 19:40:22 to 16-08-2021 19:40:26.

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 1528316 1528316 0 0.00 0 18356 7 0 0
doc_list_biud 1528315 1528315 0 0.00 0 31375 26 0 0
sp_add_invoice_to_stock 5281 5178 103 1.95 32 242334 19114 0 103
sp_cancel_adding_invoice 5260 5260 0 0.00 5 53676 805 0 0
sp_cancel_client_order 1710 1710 0 0.00 4 10165 345 0 0
sp_cancel_customer_reserve 515303 515303 0 0.00 1 29248 61 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 6080 94 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 2750 55 0 0
sp_cancel_supplier_invoice 5922 5909 13 0.21 1 22614 782 0 13
sp_cancel_supplier_order 386 386 0 0.00 7 1069997 102273 0 0
sp_cancel_write_off 9908 9908 0 0.00 1 18329 130 0 0
sp_client_order 21124 21124 0 0.00 3 28417 367 0 0
sp_customer_reserve 481487 481487 0 0.00 0 92532 189 0 0
sp_fill_shopping_cart 32310 32310 0 0.00 1 15179 36 0 0
sp_fill_shopping_cart_clo_res 481484 481484 0 0.00 0 16927 16 0 0
sp_get_clo_for_invoice 5175 5175 0 0.00 0 25132 337 0 0
sp_kill_cost_storno 16272 16272 0 0.00 0 18017 60 0 0
sp_kill_qstorno_ret_qs2qd 1046809 1046809 0 0.00 0 29243 33 0 0
sp_kill_qty_storno 554029 554029 0 0.00 0 31375 71 0 0
sp_lock_selected_doc 21324 21324 0 0.00 0 7273 6 0 0
sp_make_cost_storno 28934 28934 0 0.00 0 17554 57 0 0
sp_multiply_rows_for_pdistr 28934 28934 0 0.00 0 18348 30 0 0
sp_multiply_rows_for_qdistr 21124 21124 0 0.00 0 14710 169 0 0
sp_pay_from_customer 7329 7329 0 0.00 4 18310 154 0 0
sp_pay_to_supplier 6052 6052 0 0.00 4 25827 155 0 0
sp_payment_common 13381 13381 0 0.00 1 17796 58 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 10140 313 0 0
sp_qd_handle_on_invoice_upd_sts 10435 10435 0 0.00 0 17027 308 0 0
sp_qd_handle_on_reserve_upd_sts 10378 10378 0 0.00 0 31374 52 0 0
sp_reserve_write_off 10378 10378 0 0.00 6 31433 175 0 0
sp_supplier_invoice 5647 5647 0 0.00 7 96185 3308 0 0
sp_supplier_order 5543 5543 0 0.00 5 59055 1288 0 0
srv_aggregate_perf_data 101 101 0 0.00 2760 312738 20330 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1667 146351 46215 0 0
srv_find_qd_qs_mism 1033932 1033932 0 0.00 0 12777 7 0 0
srv_make_invnt_saldo 609 609 0 0.00 36 31037 789 0 0
srv_make_money_saldo 831 831 0 0.00 1 1710 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 187866 270478 234248 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5178 5178 0 0.00 0 49982 208 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 4373 95 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 11341 18 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 4000 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 9033 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 3234 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 1706 10 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 44468 1685 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 5201 20 0 0
t$perf-norm:sp_client_order 21124 21124 0 0.00 0 18493 19 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 7537 17 0 0
t$perf-norm:sp_pay_from_customer 7329 7329 0 0.00 0 10279 14 0 0
t$perf-norm:sp_pay_to_supplier 6052 6052 0 0.00 0 7019 14 0 0
t$perf-norm:sp_reserve_write_off 10378 10378 0 0.00 0 11246 12 0 0
t$perf-norm:sp_supplier_invoice 5647 5647 0 0.00 0 4066 15 0 0
t$perf-norm:sp_supplier_order 5543 5543 0 0.00 0 9632 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 235 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 184 4 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 1778 23 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 982 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10556 10350 206 1.95 0 6992 17 0 206
v_all_customers 38240 38240 0 0.00 0 97 1 0 0
v_all_suppliers 30960 30960 0 0.00 0 49 1 0 0
v_all_wares 42248 42248 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1798 11 0 0
v_cancel_client_order 3420 3420 0 0.00 0 2412 6 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 4847 13 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 2809 8 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2741 7 0 0
v_cancel_supplier_invoice 898 872 26 2.89 0 5995 27 0 26
v_cancel_supplier_order 772 772 0 0.00 0 3025 10 0 0
v_cancel_write_off 2084 2084 0 0.00 0 3075 14 0 0
v_max_id_clo_ord 5543 5543 0 0.00 0 3171 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 8698 34 0 0
v_max_id_ord_sup 5643 5643 0 0.00 0 8027 4 0 0
v_max_non_paid_invoice 1758 1758 0 0.00 0 295 2 0 0
v_max_non_paid_realizn 3945 3945 0 0.00 0 886 3 0 0
v_min_id_clo_ord 5543 5543 0 0.00 0 3171 9 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 7966 27 0 0
v_min_id_ord_sup 5643 5643 0 0.00 0 14015 9 0 0
v_min_non_paid_invoice 6052 6052 0 0.00 0 17707 40 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 17717 53 0 0
v_our_firm 10776 10776 0 0.00 0 20 1 0 0
v_reserve_write_off 20756 20756 0 0.00 0 5748 7 0 0
x_lock_depdocs_on_canc_invoice 10733 10733 0 0.00 0 16161 118 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 3190 302 0 0
x_make_qstorno_1000_1200 5543 5543 0 0.00 1 59048 1213 0 0
x_make_qstorno_1200_2000 5647 5647 0 0.00 3 96113 3175 0 0
x_make_qstorno_2100_3300 481487 481487 0 0.00 0 92529 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 19:40:27 to 16-08-2021 19:40:31.

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
17:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:53 15838 8192000000 10373184544 10687836160 1413890608 14.718 100 88 176 0 1449183776 5968848 29324320
17:10:25 25538 8192000000 10440894608 10771144704 1486287152 15.356 100 87 174 0 1522911072 8349344 28274576
17:11:53 18258 8192000000 10493108736 10833461248 1544195280 15.860 100 88 176 0 1581774736 9847200 27732256
17:13:21 14411 8192000000 10560308800 10918350848 1623514160 16.540 100 84 168 1 1660698080 10729328 26454592
17:14:41 8115 8192000000 10637225776 11009134592 1697536160 17.164 100 72 142 2 1729759744 11244560 20979024
17:15:51 5715 8192000000 10695166848 11075813376 1759017680 17.676 100 74 147 3 1792813600 12416000 21379920
17:17:01 3015 8192000000 10749201184 11130187776 1810397200 18.099 100 90 181 0 1847656432 11857184 25402048
17:18:11 2463 8192000000 10783609792 11171979264 1842785984 18.363 100 87 174 2 1882427920 14940256 24701680
17:19:16 1540 8192000000 10808476192 11196825600 1865053920 18.544 100 89 179 0 1903774880 13919424 24801536
17:20:19 2900 8192000000 10820736928 11212574720 1880844624 18.672 100 88 175 1 1918266960 11746528 25675808
17:21:26 3424 8192000000 10797613136 11209330688 1876702992 18.638 100 50 99 0 1899577296 9140880 13733424
17:22:34 5907 8192000000 10795344608 11216121856 1883333424 18.692 100 35 70 0 1901034640 7976448 9724768
17:23:40 8426 8192000000 10798881872 11222302720 1887945584 18.729 100 29 57 4 1906311504 9870576 8495344
17:24:50 59757 8192000000 10826854112 11245547520 1892479584 18.766 100 26 52 1 1908745152 9031008 7234560
17:26:50 9235 8192000000 10795831024 11218870272 1849877568 18.421 98 28 55 2 1862546048 4803056 7865424
17:28:05 8591 8192000000 10781968688 11208499200 1837737984 18.322 97 22 44 0 1849707264 5196832 6772448
17:29:16 45850 8192000000 10822099232 11215360000 1844025216 18.374 98 68 138 2 1869684816 5501072 20158528
17:31:02 6381 8192000000 10738638848 11144515584 1784060896 17.883 98 50 99 1 1805588288 6484544 15042848
17:32:09 6718 8192000000 10723957936 11147186176 1815881104 18.144 100 34 68 1 1832198560 6735248 9582208
17:33:16 3735 8192000000 10721643600 11134861312 1804288512 18.049 100 26 51 2 1818467600 6144352 8034736
17:34:20 22084 8192000000 10759445136 11171450880 1815852464 18.144 99 33 61 2 1830941888 5560880 9528544
17:35:46 1286 8192000000 10796239088 11195772928 1857275712 18.481 100 73 149 1 1886805872 7336992 22193168
17:36:47 11538 8192000000 10780870464 11207868416 1866196352 18.553 100 29 57 2 1879979616 4997248 8786016
17:38:01 41273 8192000000 10841648112 11258486784 1908412624 18.894 100 38 75 4 1924995936 5766960 10816352
17:39:51 58682 8192000000 10862841872 11294720000 1934134576 19.100 100 41 82 3 1959023088 13530848 11357664
17:41:55 27085 8192000000 10888306224 11321675776 1953260256 19.252 100 39 77 0 1977597552 13322624 11014672
17:43:46 9852 8192000000 10903950864 11343183872 1971597824 19.398 100 41 82 3 2002436976 18638512 12200640
17:44:58 7396 8192000000 10923796224 11345551360 1973081584 19.410 100 58 114 0 2010649312 21261104 16306624
17:46:12 6673 8192000000 10948571264 11382231040 1997961312 19.607 100 48 95 0 2037421792 24780368 14680112
17:47:26 8842 8192000000 10950423552 11394007040 2010778688 19.708 100 48 96 3 2052741216 27098144 14864384
17:48:39 2035 8192000000 10976033792 11402690560 2015515376 19.745 100 62 122 2 2062626944 28825776 18285792
17:49:48 4918 8192000000 10990824768 11426906112 2030317024 19.861 100 69 139 1 2088123632 36573968 21232640
17:50:56 1661 8192000000 10974036752 11390427136 1982513104 19.485 99 89 177 1 2045913296 36827840 26572352
17:52:05 3586 8192000000 10973129024 11383615488 1986416592 19.515 99 83 166 3 2051265104 38965552 25882960
17:53:21 2835 8192000000 10994261392 11414122496 2008055680 19.686 100 77 154 4 2076397488 44625648 23716160
17:54:27 2192 8192000000 10995901744 11408777216 1997201376 19.601 99 83 166 2 2071677536 48537392 25938768
17:55:34 8469 8192000000 10992576784 11427561472 1998900576 19.614 100 68 134 3 2081468224 60256976 22310672
17:56:48 2751 8192000000 10992995024 11409182720 1989945280 19.543 100 87 173 0 2077006256 59473856 27587120
17:57:53 15433 8192000000 10945778688 11397865472 1975957504 19.433 100 31 62 2 2034134480 47244288 10932688
17:59:12 20946 8192000000 10939604144 11384721408 1962694800 19.327 100 27 54 2 2016037728 44143728 9199200
18:00:50 25199 8192000000 10893956368 11347800064 1919404880 18.982 99 19 38 0 1967590496 40729856 7455760
18:02:16 42577 8192000000 10860680128 11320881152 1873108848 18.609 96 16 32 1 1906969824 28979744 4881232
18:03:59 56039 8192000000 10878754944 11330412544 1944784384 19.185 100 51 101 2 1971253552 11327344 15141824
18:06:01 63358 8192000000 10876901808 11329236992 1929425744 19.062 99 21 42 0 1943956528 8648656 5882128
18:08:08 85328 8192000000 10824528896 11276742656 1810525264 18.100 93 24 46 2 1824176064 7431232 6219568
18:10:33 21418 8192000000 10869738000 11334909952 1944067376 19.179 100 25 50 0 1968899648 16909440 7922832
18:12:25 47417 8192000000 10880463568 11347386368 1943363616 19.174 99 13 26 0 1956673248 9471008 3838624
18:14:13 28392 8192000000 10864404912 11331608576 1943153392 19.172 100 19 38 3 1962785984 14093664 5538928
18:15:44 81616 8192000000 10895595264 11353448448 1963274048 19.332 100 25 50 1 1980274448 10158976 6841424
18:18:15 84733 8192000000 10921857568 11359068160 1954232064 19.260 99 14 28 0 1973030960 14814256 3984640
18:20:45 13687 8192000000 10898588352 11362066432 1978471680 19.453 100 26 50 1 1993688832 7706096 7511056
18:22:01 40036 8192000000 10910128800 11372867584 1962231264 19.324 99 20 40 1 1980139680 12291200 5617216
18:23:56 12700 8192000000 10933403776 11382296576 1990220240 19.546 100 39 76 1 2007258048 5669888 11367920
18:25:13 67743 8192000000 10900154736 11370954752 1918517920 18.975 96 18 36 0 1938694080 15160560 5015600
18:27:27 24995 8192000000 10923634768 11375898624 1980183056 19.466 100 46 89 4 2001749280 8828176 12738048
18:28:55 58214 8192000000 10908072752 11373420544 1931108048 19.076 97 29 59 4 1954943968 14911328 8924592
18:30:54 102108 8192000000 10913894560 11364233216 1942744656 19.169 98 38 75 0 1963280688 9720448 10815584
18:34:00 15333 8192000000 10902519536 11362054144 1975288192 19.427 100 34 69 1 1992009584 7947504 8773888
18:35:15 35604 8192000000 10867711952 11346800640 1950052720 19.227 99 12 24 0 1965140928 11841184 3247024
18:37:19 63758 8192000000 10906138416 11381309440 1928224784 19.053 97 14 28 0 1946384992 14276880 3883328
18:39:23 45927 8192000000 10908040016 11393753088 1954594192 19.263 99 8 16 0 1978886112 22125664 2166256
18:41:09 52166 8192000000 10899465696 11358437376 1923196288 19.012 97 17 34 3 1939911520 11633984 5081248
18:43:16 45758 8192000000 10886608064 11363086336 1931374224 19.078 98 15 30 0 1954537200 18330064 4832912
18:45:19 38005 8192000000 10878787248 11336777728 1915129856 18.948 98 28 56 0 1933510704 10678960 7701888
18:47:02 48002 8192000000 10847433904 11309531136 1857954672 18.487 96 30 59 3 1881371456 14643504 8773280
18:48:53 69105 8192000000 10836777904 11308146688 1806233328 18.065 94 26 52 0 1831251856 17989744 7028784
18:51:04 100647 8192000000 10886329408 11313786880 1864883296 18.543 97 22 44 1 1885534768 13728384 6923088
18:53:47 118963 8192000000 10862181056 11313111040 1906180192 18.876 98 34 68 0 1932281104 17192768 8908144
18:56:46 39497 8192000000 10850204496 11326713856 1933964432 19.099 100 5 9 1 1943302704 7710064 1628208
18:58:26 37698 8192000000 10853438304 11321090048 1907996144 18.891 98 4 8 0 1915044384 6034512 1013728
19:00:04 56020 8192000000 10894779856 11361501184 1960828928 19.313 100 21 41 3 1980980912 13882928 6269056
19:02:20 82980 8192000000 10889486880 11365941248 1940229616 19.149 99 11 22 0 1961036800 17437104 3370080
19:04:45 41636 8192000000 10884906544 11349450752 1931445248 19.078 99 10 20 1 1946180768 10688464 4047056
19:06:26 59075 8192000000 10915694720 11368710144 1968752768 19.376 100 24 48 1 1985673936 9928000 6993168
19:08:42 87564 8192000000 10919245728 11401637888 1992805232 19.566 100 26 52 3 2013954624 13502560 7646832
19:11:10 100505 8192000000 10905218320 11369959424 1849249808 18.416 93 11 22 0 1873157168 20667936 3239424
19:13:53 9720 8192000000 10904098848 11350724608 1958505536 19.294 100 62 119 3 1985751680 11163936 16082208
19:15:04 90426 8192000000 10907729040 11365294080 1970563776 19.390 100 51 101 0 1996735408 12555488 13616144
19:17:35 56369 8192000000 10871096720 11351928832 1958361376 19.293 100 5 10 0 1966203312 6574400 1267536
19:19:32 61777 8192000000 10875511936 11353399296 1933453216 19.094 98 12 24 1 1944226512 7342272 3431024
19:21:34 58589 8192000000 10882578032 11367661568 1937997504 19.131 98 11 22 0 1948234080 7226608 3009968
19:23:34 37669 8192000000 10897475968 11370065920 1961118656 19.315 99 13 26 0 1972633440 8145536 3369248
19:25:12 50226 8192000000 10887645872 11364556800 1927390752 19.046 98 9 17 1 1942402496 12048784 2962960
19:27:02 81779 8192000000 10889635184 11361636352 1875668800 18.630 95 22 44 1 1893785584 12401936 5714848
19:29:33 128902 8192000000 10868831440 11338276864 1844192208 18.375 94 15 30 1 1862811200 13619760 4999232
19:32:43 17507 8192000000 10889479584 11341959168 1930304272 19.069 99 39 78 0 1950985904 9433552 11248080
19:34:02 146351 8192000000 10874412784 11343142912 1894195696 18.780 97 16 32 0 1915077856 15988752 4893408
19:37:30 46123 8192000000 10032373872 10421284864 1160595040 12.409 59 4 8 0 1168610768 7173520 842208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-08-2021 19:40:32 to 16-08-2021 19:40:47.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2021 19:40:48 to 16-08-2021 19:40:50.

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          241774
MON$OLDEST_ACTIVE               242594
MON$OLDEST_SNAPSHOT             242594
MON$NEXT_TRANSACTION            242594
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                       2000832
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:351: 
:352: mscs-test-161.budzdorov.ru    Mon Aug 16 19:38:22 2021
:353:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:354: 
:355: 
:356: mscs-test-161.budzdorov.ru    Mon Aug 16 19:38:33 2021
:357:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:358: 
--- end of diff output ---

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

16.08.21 19:40:51. 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-16 19:40:54 No severe PSQL-related problems occured
16.08.21 19:40:55. End of report.