Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.08.2021 16: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 16210860 kB
MemAvailable 30909772 kB
Buffers 0 kB
Cached 15453048 kB
SwapCached 1324 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-28 16:37:48
DTS_END                         2021-08-28 16:37:48.7010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38646
TRN_ID                          233829
ATT_ID                          617
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: 9217.46 15911 1106095
customer order: creation 168.98 337 20278
customer order: refuse 14.13 382 1695
order to supplier: creation 45.01 1247 5401
order to supplier: removal 3.33 101644 400
invoice (draft): creation 43.67 3540 5240
invoice (draft): removal 52.73 811 6327
invoice accept: apply 40.34 19671 4841
invoice accept: cancel 47.73 806 5728
customer reserve: creation 3798.94 191 455873
customer reserve: removal 4713.93 56 565672
realization accept: apply 81.84 171 9821
realization accept: cancel 83.52 103 10022
payment from customer: creation 56.96 117 6835
payment from customer: removal 5.07 91 608
payment to supplier: creation 46.21 150 5545
payment to supplier: removal 3.36 79 403
service: total inventory turnovers 4.53 1493 544
service: total monetary turnovers 6.36 24 763
service: aggregate perf. data 0.79 21672 95
service: refresh index statistics 0.03 165641 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-08-2021 16:38:46 to 28-08-2021 16:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4639
TEST_TIME 2 5116
TEST_TIME 3 4821
TEST_TIME 4 5404
TEST_TIME 5 5960
TEST_TIME 6 5740
TEST_TIME 7 5592
TEST_TIME 8 5941
TEST_TIME 9 6014
TEST_TIME 10 6339
TEST_TIME 11 6507
TEST_TIME 12 6538
TEST_TIME 13 6543
TEST_TIME 14 6572
TEST_TIME 15 7034
TEST_TIME 16 7369
TEST_TIME 17 7164
TEST_TIME 18 7394
TEST_TIME 19 7683
TEST_TIME 20 8179
TEST_TIME 21 8187
TEST_TIME 22 9067
TEST_TIME 23 8954
TEST_TIME 24 9151
TEST_TIME 25 9145
TEST_TIME 26 9249
TEST_TIME 27 9303
TEST_TIME 28 9315
TEST_TIME 29 9373
TEST_TIME 30 9903
TEST_TIME 31 10822
TEST_TIME 32 10522
TEST_TIME 33 10342
TEST_TIME 34 10264
TEST_TIME 35 10089
TEST_TIME 36 10011
TEST_TIME 37 9928
TEST_TIME 38 9960
TEST_TIME 39 9941
TEST_TIME 40 9762
TEST_TIME 41 9786
TEST_TIME 42 9793
TEST_TIME 43 9760
TEST_TIME 44 9718
TEST_TIME 45 9766
TEST_TIME 46 9625
TEST_TIME 47 9712
TEST_TIME 48 9547
TEST_TIME 49 9515
TEST_TIME 50 9431
TEST_TIME 51 9369
TEST_TIME 52 9359
TEST_TIME 53 9374
TEST_TIME 54 9386
TEST_TIME 55 9370
TEST_TIME 56 9288
TEST_TIME 57 9324
TEST_TIME 58 9327
TEST_TIME 59 9233
TEST_TIME 60 9150
TEST_TIME 61 9040
TEST_TIME 62 9128
TEST_TIME 63 9036
TEST_TIME 64 9143
TEST_TIME 65 9129
TEST_TIME 66 9146
TEST_TIME 67 9232
TEST_TIME 68 9249
TEST_TIME 69 9276
TEST_TIME 70 9388
TEST_TIME 71 9327
TEST_TIME 72 9387
TEST_TIME 73 9547
TEST_TIME 74 9556
TEST_TIME 75 9540
TEST_TIME 76 9543
TEST_TIME 77 9439
TEST_TIME 78 9394
TEST_TIME 79 9368
TEST_TIME 80 9346
TEST_TIME 81 9286
TEST_TIME 82 9330
TEST_TIME 83 9339
TEST_TIME 84 9311
TEST_TIME 85 9321
TEST_TIME 86 9280
TEST_TIME 87 9315
TEST_TIME 88 9300
TEST_TIME 89 9310
TEST_TIME 90 9262
TEST_TIME 91 9309
TEST_TIME 92 9363
TEST_TIME 93 9356
TEST_TIME 94 9335
TEST_TIME 95 9396
TEST_TIME 96 9522
TEST_TIME 97 9490
TEST_TIME 98 9499
TEST_TIME 99 9479
TEST_TIME 100 9426
TEST_TIME 101 9396
TEST_TIME 102 9422
TEST_TIME 103 9364
TEST_TIME 104 9395
TEST_TIME 105 9342
TEST_TIME 106 9287
TEST_TIME 107 9274
TEST_TIME 108 9250
TEST_TIME 109 9245
TEST_TIME 110 9186
TEST_TIME 111 9196
TEST_TIME 112 9216
TEST_TIME 113 9217
TEST_TIME 114 9216
TEST_TIME 115 9222
TEST_TIME 116 9238
TEST_TIME 117 9235
TEST_TIME 118 9264
TEST_TIME 119 9256
TEST_TIME 120 9219

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-08-2021 16:38:50 to 28-08-2021 16:38:53.

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 1528287 1528287 0 0.00 0 20626 6 0 0
doc_list_biud 1528286 1528286 0 0.00 0 49901 27 0 0
sp_add_invoice_to_stock 4946 4841 105 2.12 43 165723 19672 0 105
sp_cancel_adding_invoice 5728 5728 0 0.00 3 54201 807 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 13266 382 0 0
sp_cancel_customer_reserve 565672 565672 0 0.00 1 49902 57 0 0
sp_cancel_pay_from_customer 608 608 0 0.00 3 5353 91 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2711 80 0 0
sp_cancel_supplier_invoice 6335 6327 8 0.12 2 18461 812 0 8
sp_cancel_supplier_order 400 400 0 0.00 7 1017996 101645 0 0
sp_cancel_write_off 10022 10022 0 0.00 1 9400 104 0 0
sp_client_order 20278 20278 0 0.00 3 20423 337 0 0
sp_customer_reserve 455873 455873 0 0.00 0 49578 191 0 0
sp_fill_shopping_cart 30913 30913 0 0.00 1 11235 39 0 0
sp_fill_shopping_cart_clo_res 455870 455870 0 0.00 0 20540 16 0 0
sp_get_clo_for_invoice 4837 4837 0 0.00 0 32387 346 0 0
sp_kill_cost_storno 16761 16761 0 0.00 0 9398 51 0 0
sp_kill_qstorno_ret_qs2qd 1148093 1148093 0 0.00 0 49895 31 0 0
sp_kill_qty_storno 604502 604502 0 0.00 0 49901 67 0 0
sp_lock_selected_doc 20218 20218 0 0.00 0 8622 6 0 0
sp_make_cost_storno 27038 27038 0 0.00 0 17936 55 0 0
sp_multiply_rows_for_pdistr 27038 27038 0 0.00 0 19568 30 0 0
sp_multiply_rows_for_qdistr 20278 20278 0 0.00 0 20411 148 0 0
sp_pay_from_customer 6835 6835 0 0.00 4 19393 118 0 0
sp_pay_to_supplier 5545 5545 0 0.00 5 19577 150 0 0
sp_payment_common 12380 12380 0 0.00 1 19387 39 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 13253 354 0 0
sp_qd_handle_on_invoice_upd_sts 10565 10565 0 0.00 0 11893 321 0 0
sp_qd_handle_on_reserve_upd_sts 9821 9821 0 0.00 0 10300 53 0 0
sp_reserve_write_off 9821 9821 0 0.00 6 10311 171 0 0
sp_supplier_invoice 5240 5240 0 0.00 6 70442 3541 0 0
sp_supplier_order 5401 5401 0 0.00 5 40735 1248 0 0
srv_aggregate_perf_data 95 95 0 0.00 3219 311405 21673 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2018 130755 46954 0 0
srv_find_qd_qs_mism 1059702 1059702 0 0.00 0 20615 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 36 141147 1494 0 0
srv_make_money_saldo 763 763 0 0.00 1 917 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 71497 263294 165642 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4843 4843 0 0.00 0 16571 191 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 5829 102 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 3398 19 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2416 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 608 608 0 0.00 0 9180 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 903 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 430 7 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 16788 1548 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 560 5 0 0
t$perf-norm:sp_client_order 20278 20278 0 0.00 0 16657 20 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 829 4 0 0
t$perf-norm:sp_pay_from_customer 6835 6835 0 0.00 0 7781 10 0 0
t$perf-norm:sp_pay_to_supplier 5545 5545 0 0.00 0 9218 14 0 0
t$perf-norm:sp_reserve_write_off 9821 9821 0 0.00 0 6395 11 0 0
t$perf-norm:sp_supplier_invoice 5240 5240 0 0.00 0 8079 20 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 16865 27 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 361 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 208 10 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 1821 27 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 727 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 9882 9672 210 2.12 0 5647 20 0 210
v_all_customers 36978 36978 0 0.00 0 107 1 0 0
v_all_suppliers 29318 29318 0 0.00 0 95 1 0 0
v_all_wares 40550 40550 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 225 6 0 0
v_cancel_client_order 3390 3390 0 0.00 0 2157 6 0 0
v_cancel_customer_prepayment 1216 1216 0 0.00 0 1347 6 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 3194 8 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1452 7 0 0
v_cancel_supplier_invoice 806 790 16 1.98 0 3598 19 0 16
v_cancel_supplier_order 800 800 0 0.00 0 871 4 0 0
v_cancel_write_off 2006 2006 0 0.00 0 4628 14 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 3391 2 0 0
v_max_id_clo_res 940 940 0 0.00 0 1840 16 0 0
v_max_id_ord_sup 5237 5237 0 0.00 0 2044 3 0 0
v_max_non_paid_invoice 1521 1521 0 0.00 0 680 3 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 7959 6 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 1666 5 0 0
v_min_id_clo_res 940 940 0 0.00 0 5157 18 0 0
v_min_id_ord_sup 5237 5237 0 0.00 0 3397 4 0 0
v_min_non_paid_invoice 5545 5545 0 0.00 0 10657 29 0 0
v_min_non_paid_realizn 6835 6835 0 0.00 0 9643 20 0 0
v_our_firm 10190 10190 0 0.00 0 14 1 0 0
v_reserve_write_off 19636 19636 0 0.00 0 6691 6 0 0
x_lock_depdocs_on_canc_invoice 11660 11660 0 0.00 0 18511 118 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 14598 454 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 1 40712 1164 0 0
x_make_qstorno_1200_2000 5240 5240 0 0.00 3 70421 3399 0 0
x_make_qstorno_2100_3300 455873 455873 0 0.00 0 49577 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-08-2021 16:38:54 to 28-08-2021 16:38:58.

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
14:07:52 21 8192000000 8806861472 8819412992 12292832 0.149 2 2 3 0 12472720 67584 112304
14:08:53 21475 8192000000 10357757920 10676908032 1413344496 14.714 100 86 172 0 1446503584 5408832 27750256
14:10:28 15245 8192000000 10419146608 10746056704 1475181888 15.259 100 88 176 0 1512043584 7935040 28926656
14:11:47 15848 8192000000 10469467856 10807382016 1524041472 15.685 100 88 176 0 1561533984 9205360 28287152
14:13:10 16509 8192000000 10578528672 10934870016 1636068368 16.646 100 79 157 2 1669785824 10347504 23369952
14:14:31 13565 8192000000 10625066960 10993958912 1688738432 17.091 100 74 148 1 1722885952 12448704 21698816
14:15:48 6226 8192000000 10687882912 11065499648 1748744384 17.591 100 86 172 1 1785904880 12603328 24557168
14:16:57 3458 8192000000 10729602368 11107856384 1793027168 17.957 100 86 172 3 1831512896 14613200 23872528
14:18:01 2923 8192000000 10763712704 11151466496 1829257168 18.253 100 80 157 2 1862491024 12164416 21069440
14:19:05 1717 8192000000 10821259520 11207245824 1879867680 18.664 100 92 186 2 1918161008 12904960 25388368
14:20:07 31195 8192000000 10813801120 11218784256 1881451104 18.677 100 50 100 2 1903846672 7983424 14412144
14:21:39 10979 8192000000 10804666736 11236257792 1892700160 18.768 100 21 41 1 1904978752 6674128 5604464
14:22:51 12453 8192000000 10809821584 11245215744 1903123328 18.851 100 14 28 0 1913188896 5661216 4404352
14:24:03 73061 8192000000 10824817312 11261214720 1915719168 18.953 100 29 59 1 1929626496 5375616 8531712
14:26:24 7045 8192000000 10834208016 11262386176 1913132304 18.932 100 31 62 2 1928307888 5579120 9596464
14:27:36 7754 8192000000 10789897392 11231715328 1859193008 18.497 98 29 56 3 1874266272 6835760 8237504
14:28:46 8773 8192000000 10784186320 11215278080 1858413792 18.490 99 24 47 1 1870969536 6342752 6212992
14:29:58 18077 8192000000 10751486944 11160969216 1782234704 17.868 96 47 93 0 1801612192 6284000 13093488
14:31:17 7012 8192000000 10677188960 11089829888 1723369280 17.380 96 28 56 1 1738683248 7394624 7919344
14:32:24 8275 8192000000 10737360832 11152044032 1798992848 18.006 99 22 44 4 1811334448 5587600 6754000
14:33:35 20368 8192000000 10741784368 11163398144 1811728720 18.110 99 29 57 1 1825742992 5448672 8565600
14:34:56 20853 8192000000 10751492112 11178176512 1822458720 18.198 99 17 34 3 1832411200 5380256 4572224
14:36:18 8010 8192000000 10767050176 11198119936 1855993136 18.471 100 16 30 1 1866084336 5229824 4861376
14:37:27 10259 8192000000 10793747392 11221024768 1879632768 18.662 100 14 28 0 1888546640 5178848 3735024
14:38:41 36885 8192000000 10834702544 11259834368 1911526000 18.919 100 56 111 2 1937020736 9983824 15510912
14:40:28 32396 8192000000 10862080640 11288293376 1925904800 19.034 100 55 110 3 1952004160 10747904 15351456
14:42:08 7579 8192000000 10898839872 11317944320 1952015376 19.243 100 62 124 0 1984779888 14609760 18154752
14:43:23 16381 8192000000 10901352464 11331444736 1960277856 19.308 100 52 104 0 1989413680 14613392 14522432
14:44:42 2428 8192000000 10930900592 11347767296 1977832832 19.448 100 67 134 3 2016921760 19827184 19261744
14:45:48 14092 8192000000 10902127040 11345469440 1966255296 19.356 100 26 52 0 1992849312 18887472 7706544
14:47:15 8423 8192000000 10933230464 11354775552 1956430496 19.278 99 64 128 2 1995286448 20302016 18553936
14:48:35 2802 8192000000 10941576208 11366305792 1987263216 19.522 100 76 148 0 2029707168 20572624 21871328
14:49:42 5987 8192000000 10946256624 11367133184 1970998240 19.393 99 62 119 1 2014408304 25779472 17630592
14:50:53 2885 8192000000 10952309984 11360489472 1979610736 19.462 100 84 168 2 2032567648 27781632 25175280
14:52:01 2952 8192000000 10957151072 11368607744 1980448944 19.468 100 84 166 0 2039053456 33741616 24862896
14:53:14 2017 8192000000 10940016656 11359694848 1958900544 19.297 99 70 140 0 2011666352 29890544 22875264
14:54:21 7613 8192000000 10933542432 11352756224 1970145584 19.387 100 73 144 0 2021817184 29770560 21901040
14:55:38 4922 8192000000 10933208624 11355975680 1958126784 19.291 99 60 120 0 2013964464 37224768 18612912
14:56:46 9393 8192000000 10911491456 11346935808 1931032704 19.075 99 37 74 0 1987009360 43482672 12493984
14:57:59 4527 8192000000 10893925728 11317661696 1886341360 18.716 97 61 119 3 1946502928 40804336 19357232
14:59:11 18372 8192000000 10874027296 11315707904 1899119680 18.819 99 34 67 2 1951320672 40471584 11729408
15:00:34 5715 8192000000 10893084960 11318112256 1928486592 19.055 100 59 118 2 1978157648 32027088 17643968
15:01:44 13248 8192000000 10891280352 11333988352 1932736032 19.089 100 34 68 1 1978599856 34869696 10994128
15:03:26 14969 8192000000 10898107760 11341873152 1938385776 19.134 100 30 59 2 1983954736 36271072 9297888
15:04:44 15945 8192000000 10909136640 11359821824 1951836672 19.241 100 26 52 1 1996866704 36269744 8760288
15:06:10 34395 8192000000 10907571952 11364872192 1931257104 19.077 100 15 30 0 1985591552 48128032 6206416
15:07:53 36982 8192000000 10893532320 11356762112 1927389936 19.046 98 22 44 0 1968652288 33770416 7491936
15:09:32 27567 8192000000 10889949008 11339595776 1969693712 19.383 99 40 80 1 1987589792 7442320 10453760
15:11:11 63120 8192000000 10891300256 11343794176 1931017296 19.075 98 16 33 1 1948395056 13224448 4153312
15:13:27 71647 8192000000 10906394688 11363758080 1951197808 19.236 99 24 47 2 1977361872 18652144 7511920
15:15:45 77607 8192000000 10891379168 11360276480 1932808992 19.089 98 18 36 0 1948663920 10440688 5414240
15:18:03 130755 8192000000 10914795392 11394228224 1979523328 19.461 100 10 21 0 2003172096 21202608 2446160
15:21:20 52139 8192000000 10911044640 11369721856 1969035504 19.378 99 41 82 2 1991706016 10580480 12090032
15:23:44 67206 8192000000 10902890976 11379953664 1987753664 19.526 100 24 48 1 2006572400 12021776 6796960
15:25:53 39095 8192000000 10931351376 11395899392 1978958576 19.456 99 34 68 1 2007669696 18829280 9881840
15:27:35 43027 8192000000 10929703040 11395743744 1998130064 19.608 100 21 42 0 2021194288 17095840 5968384
15:29:23 63952 8192000000 10933343392 11422420992 2005005232 19.662 100 15 31 1 2032166560 22421504 4739824
15:31:28 59882 8192000000 10919764288 11409858560 1974528592 19.421 99 8 16 1 1993942752 17060656 2353504
15:33:38 64460 8192000000 10919119600 11397816320 1994243536 19.577 100 19 38 1 2013987568 13891536 5852496
15:35:56 49357 8192000000 10896845888 11371184128 1946992480 19.203 98 10 20 1 1955718352 5932192 2793680
15:38:02 88026 8192000000 10901941648 11390455808 1964017216 19.338 99 8 16 1 1977204048 11215616 1971216
15:41:06 59686 8192000000 10895350832 11381661696 1931073552 19.075 97 14 28 1 1952052608 16998432 3980624
15:43:07 50419 8192000000 10916755648 11383664640 1950645712 19.232 98 38 76 1 1974335040 13266720 10422608
15:45:00 110577 8192000000 10911983136 11377172480 1886310384 18.716 95 20 40 1 1909161264 17183664 5667216
15:47:51 35949 8192000000 10927012896 11381186560 1959291024 19.300 99 18 37 0 1972629568 8356192 4982352
15:49:31 64798 8192000000 10905356784 11385069568 1946155856 19.196 99 9 18 1 1970705952 20239168 4310928
15:51:37 78556 8192000000 10888814112 11350982656 1879360240 18.660 95 11 22 0 1893153968 10580528 3213200
15:53:57 83080 8192000000 10864382448 11314700288 1876942720 18.640 97 21 42 2 1892555600 9247648 6365232
15:56:42 71620 8192000000 10841477568 11318743040 1881784368 18.680 97 21 42 1 1902787888 15123168 5880352
15:59:08 64165 8192000000 10834433824 11317792768 1872576096 18.605 96 17 33 0 1898113600 20854208 4683296
16:01:13 73541 8192000000 10835762512 11307442176 1876966816 18.641 97 12 24 1 1889114400 8511472 3636112
16:03:35 70692 8192000000 10878943360 11340701696 1914147600 18.940 98 23 46 1 1930027520 9339408 6540512
16:05:58 68560 8192000000 10870406592 11331620864 1872667904 18.606 96 25 49 2 1891055680 11036048 7351728
16:08:07 46280 8192000000 10896970432 11356524544 1938829056 19.137 99 38 76 2 1967835552 18340960 10665536
16:09:59 27024 8192000000 10909289056 11370418176 1969349680 19.380 100 29 58 1 1985340912 7771920 8219312
16:11:27 88078 8192000000 10884710912 11362074624 1886667568 18.719 96 22 44 2 1920018688 26852720 6498400
16:14:47 112217 8192000000 10891230256 11340222464 1892357824 18.765 96 16 28 1 1907042912 10148288 4536800
16:17:42 106378 8192000000 10915197936 11388104704 1982032336 19.481 100 27 54 2 2002200016 12404560 7763120
16:21:53 101805 8192000000 10909363552 11395653632 1927169360 19.044 97 8 16 0 1942396960 12779168 2448432
16:24:41 70431 8192000000 10877948368 11372421120 1927821712 19.049 97 9 18 0 1939985376 9622864 2540800
16:26:58 56616 8192000000 10897115440 11377016832 1951056928 19.235 98 30 59 1 1967449552 7974176 8418448
16:29:06 71285 8192000000 10902571040 11384115200 1958122240 19.291 99 12 24 1 1974320624 12803296 3395088
16:31:18 46955 8192000000 10901929920 11371077632 1932892144 19.090 98 17 33 1 1945154144 7629104 4632896
16:33:15 64487 8192000000 10849312704 11322007552 1862117840 18.520 95 20 40 2 1878586864 10382512 6086512
16:35:21 34042 8192000000 10870175568 11337314304 1916394928 18.958 98 26 52 0 1933848608 10342896 7110784
16:37:00 55070 8192000000 9778960944 10132439040 906700544 9.965 47 11 22 0 918527472 8659056 3167872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-08-2021 16:39:00 to 28-08-2021 16:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-08-2021 16:39:16 to 28-08-2021 16:39:18.

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          231660
MON$OLDEST_ACTIVE               234255
MON$OLDEST_SNAPSHOT             234255
MON$NEXT_TRANSACTION            234255
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                       2029664
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 ---
:634: 
:635: mscs-test-161.budzdorov.ru    Sat Aug 28 16:37:59 2021
:636:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:637: 
:638: 
:639: mscs-test-161.budzdorov.ru    Sat Aug 28 16:38:09 2021
:640:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:641: 
--- end of diff output ---

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

28.08.21 16:39:19. 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-28 16:39:21 No severe PSQL-related problems occured
28.08.21 16:39:22. End of report.