Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.03.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16915564 kB
MemAvailable 31492208 kB
Buffers 20 kB
Cached 14662212 kB
SwapCached 0 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-03-18 04:26:57
DTS_END                         2021-03-18 04:26:57.5670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42174
TRN_ID                          234193
ATT_ID                          614
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: 8908.06 21943 1068967
customer order: creation 169.32 441 20318
customer order: refuse 14.68 320 1762
order to supplier: creation 44.28 1429 5313
order to supplier: removal 3.39 107293 407
invoice (draft): creation 45.31 3448 5437
invoice (draft): removal 48.58 896 5829
invoice accept: apply 41.27 17777 4952
invoice accept: cancel 44.13 933 5295
customer reserve: creation 3882.83 171 465940
customer reserve: removal 4326.35 68 519162
realization accept: apply 81.30 158 9756
realization accept: cancel 80.95 114 9714
payment from customer: creation 57.85 107 6942
payment from customer: removal 5.35 96 642
payment to supplier: creation 48.35 111 5802
payment to supplier: removal 2.91 63 349
service: total inventory turnovers 4.23 2900 508
service: total monetary turnovers 6.11 512 733
service: aggregate perf. data 0.85 14951 102
service: refresh index statistics 0.03 287076 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-03-2021 04:27:57 to 18-03-2021 04:27:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4464
TEST_TIME 2 4046
TEST_TIME 3 4083
TEST_TIME 4 4151
TEST_TIME 5 4177
TEST_TIME 6 4379
TEST_TIME 7 4822
TEST_TIME 8 5124
TEST_TIME 9 5374
TEST_TIME 10 5640
TEST_TIME 11 5892
TEST_TIME 12 6111
TEST_TIME 13 6161
TEST_TIME 14 6234
TEST_TIME 15 6331
TEST_TIME 16 6519
TEST_TIME 17 6640
TEST_TIME 18 7004
TEST_TIME 19 7322
TEST_TIME 20 8130
TEST_TIME 21 8574
TEST_TIME 22 8808
TEST_TIME 23 8809
TEST_TIME 24 9075
TEST_TIME 25 10014
TEST_TIME 26 10407
TEST_TIME 27 10255
TEST_TIME 28 10662
TEST_TIME 29 10614
TEST_TIME 30 10328
TEST_TIME 31 10213
TEST_TIME 32 10128
TEST_TIME 33 10086
TEST_TIME 34 10024
TEST_TIME 35 9881
TEST_TIME 36 9771
TEST_TIME 37 9894
TEST_TIME 38 9739
TEST_TIME 39 9609
TEST_TIME 40 9674
TEST_TIME 41 9555
TEST_TIME 42 9705
TEST_TIME 43 9780
TEST_TIME 44 9707
TEST_TIME 45 9908
TEST_TIME 46 9915
TEST_TIME 47 9843
TEST_TIME 48 9854
TEST_TIME 49 9840
TEST_TIME 50 9984
TEST_TIME 51 10052
TEST_TIME 52 10066
TEST_TIME 53 10111
TEST_TIME 54 9975
TEST_TIME 55 9830
TEST_TIME 56 9755
TEST_TIME 57 9649
TEST_TIME 58 9556
TEST_TIME 59 9458
TEST_TIME 60 9365
TEST_TIME 61 9340
TEST_TIME 62 9320
TEST_TIME 63 9253
TEST_TIME 64 9302
TEST_TIME 65 9219
TEST_TIME 66 9295
TEST_TIME 67 9316
TEST_TIME 68 9330
TEST_TIME 69 9274
TEST_TIME 70 9369
TEST_TIME 71 9387
TEST_TIME 72 9471
TEST_TIME 73 9448
TEST_TIME 74 9414
TEST_TIME 75 9344
TEST_TIME 76 9310
TEST_TIME 77 9284
TEST_TIME 78 9220
TEST_TIME 79 9161
TEST_TIME 80 9104
TEST_TIME 81 9105
TEST_TIME 82 9109
TEST_TIME 83 9140
TEST_TIME 84 9087
TEST_TIME 85 9121
TEST_TIME 86 9134
TEST_TIME 87 9116
TEST_TIME 88 9118
TEST_TIME 89 9193
TEST_TIME 90 9212
TEST_TIME 91 9171
TEST_TIME 92 9089
TEST_TIME 93 9038
TEST_TIME 94 8995
TEST_TIME 95 8963
TEST_TIME 96 8949
TEST_TIME 97 8956
TEST_TIME 98 8946
TEST_TIME 99 8926
TEST_TIME 100 8924
TEST_TIME 101 8913
TEST_TIME 102 9016
TEST_TIME 103 8979
TEST_TIME 104 8997
TEST_TIME 105 8998
TEST_TIME 106 9061
TEST_TIME 107 9096
TEST_TIME 108 9094
TEST_TIME 109 9028
TEST_TIME 110 8990
TEST_TIME 111 8962
TEST_TIME 112 8904
TEST_TIME 113 8901
TEST_TIME 114 8859
TEST_TIME 115 8824
TEST_TIME 116 8898
TEST_TIME 117 8895
TEST_TIME 118 8915
TEST_TIME 119 8918
TEST_TIME 120 8910

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-03-2021 04:28:01 to 18-03-2021 04:28:04.

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 1497746 1497746 0 0.00 0 15283 6 0 0
doc_list_biud 1497745 1497745 0 0.00 0 74383 30 0 0
sp_add_invoice_to_stock 5073 4952 121 2.38 43 227049 17777 0 121
sp_cancel_adding_invoice 5295 5295 0 0.00 7 72200 934 0 0
sp_cancel_client_order 1762 1762 0 0.00 5 15934 321 0 0
sp_cancel_customer_reserve 519162 519162 0 0.00 1 23532 69 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 6648 97 0 0
sp_cancel_pay_to_supplier 349 349 0 0.00 3 1879 64 0 0
sp_cancel_supplier_invoice 5833 5829 4 0.06 3 74389 896 0 4
sp_cancel_supplier_order 407 407 0 0.00 12 1066298 107294 0 0
sp_cancel_write_off 9714 9714 0 0.00 1 9034 114 0 0
sp_client_order 20318 20318 0 0.00 4 19942 441 0 0
sp_customer_reserve 465940 465940 0 0.00 0 171323 171 0 0
sp_fill_shopping_cart 31047 31047 0 0.00 1 15515 44 0 0
sp_fill_shopping_cart_clo_res 465929 465929 0 0.00 0 27019 22 0 0
sp_get_clo_for_invoice 4941 4941 0 0.00 0 40623 480 0 0
sp_kill_cost_storno 16000 16000 0 0.00 0 7841 50 0 0
sp_kill_qstorno_ret_qs2qd 1054274 1054274 0 0.00 0 74383 37 0 0
sp_kill_qty_storno 556865 556865 0 0.00 0 74383 79 0 0
sp_lock_selected_doc 20292 20292 0 0.00 0 7604 4 0 0
sp_make_cost_storno 27441 27441 0 0.00 0 15280 47 0 0
sp_multiply_rows_for_pdistr 27440 27440 0 0.00 0 10936 29 0 0
sp_multiply_rows_for_qdistr 20318 20318 0 0.00 0 19926 268 0 0
sp_pay_from_customer 6942 6942 0 0.00 4 23662 108 0 0
sp_pay_to_supplier 5802 5802 0 0.00 5 17857 112 0 0
sp_payment_common 12744 12744 0 0.00 1 12923 17 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 15928 292 0 0
sp_qd_handle_on_invoice_upd_sts 10235 10235 0 0.00 0 30304 350 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 9106 48 0 0
sp_reserve_write_off 9756 9756 0 0.00 7 15235 159 0 0
sp_supplier_invoice 5437 5437 0 0.00 7 105664 3448 0 0
sp_supplier_order 5313 5313 0 0.00 5 26012 1429 0 0
srv_aggregate_perf_data 102 102 0 0.00 2407 146690 14952 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1698 162081 52543 0 0
srv_find_qd_qs_mism 1020449 1020449 0 0.00 0 14052 5 0 0
srv_make_invnt_saldo 508 508 0 0.00 47 104970 2900 0 0
srv_make_money_saldo 733 733 0 0.00 1 17734 513 0 0
srv_recalc_idx_stat 4 4 0 0.00 124310 392884 287077 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4952 4952 0 0.00 0 10003 156 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3566 80 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 8465 11 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 776 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 15482 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 349 349 0 0.00 0 303 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 1199 18 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 33436 1228 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 3155 10 0 0
t$perf-norm:sp_client_order 20318 20318 0 0.00 0 28044 19 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1593 4 0 0
t$perf-norm:sp_pay_from_customer 6942 6942 0 0.00 0 4660 6 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 5355 9 0 0
t$perf-norm:sp_reserve_write_off 9756 9756 0 0.00 0 8219 8 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 5844 15 0 0
t$perf-norm:sp_supplier_order 5314 5314 0 0.00 0 5897 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 591 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 611 11 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 1772 9 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 2355 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10120 9878 242 2.39 0 7356 17 0 242
v_all_customers 36842 36842 0 0.00 0 14 1 0 0
v_all_suppliers 29962 29962 0 0.00 0 24 1 0 0
v_all_wares 40636 40636 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2206 10 0 0
v_cancel_client_order 3524 3524 0 0.00 0 1339 4 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 5310 15 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1064 4 0 0
v_cancel_payment_to_supplier 698 698 0 0.00 0 1518 8 0 0
v_cancel_supplier_invoice 726 718 8 1.10 0 2274 16 0 8
v_cancel_supplier_order 814 814 0 0.00 0 1888 9 0 0
v_cancel_write_off 2024 2024 0 0.00 0 4295 15 0 0
v_max_id_clo_ord 5309 5309 0 0.00 0 410 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 1839 15 0 0
v_max_id_ord_sup 5420 5420 0 0.00 0 6593 6 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 340 2 0 0
v_max_non_paid_realizn 3631 3631 0 0.00 0 8659 6 0 0
v_min_id_clo_ord 5309 5309 0 0.00 0 4172 6 0 0
v_min_id_clo_res 976 976 0 0.00 0 3874 8 0 0
v_min_id_ord_sup 5420 5420 0 0.00 0 8995 9 0 0
v_min_non_paid_invoice 5802 5802 0 0.00 0 3825 7 0 0
v_min_non_paid_realizn 6942 6942 0 0.00 0 4264 12 0 0
v_our_firm 10416 10416 0 0.00 0 11 1 0 0
v_reserve_write_off 19512 19512 0 0.00 0 4122 6 0 0
x_lock_depdocs_on_canc_invoice 10765 10765 0 0.00 0 26392 111 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 5696 285 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 2 25514 1358 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 3 105616 3272 0 0
x_make_qstorno_2100_3300 465940 465940 0 0.00 0 171323 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-03-2021 04:28:05 to 18-03-2021 04:28:09.

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
01:57:01 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 37041 8192000000 9730440544 10029686784 810473104 9.002 100 89 178 0 840154992 8370400 21311488
01:59:47 28403 8192000000 9777267168 10087788544 852535408 9.425 100 89 178 1 886069168 12697728 20836032
02:01:25 19922 8192000000 9832311936 10154426368 892601440 9.825 100 79 158 1 938345920 27937168 17807312
02:02:51 12086 8192000000 9876241536 10214572032 936192160 10.256 100 79 156 2 991093232 38431328 16469744
02:04:07 7813 8192000000 9952788448 10298896384 982914656 10.713 100 85 167 1 1060024912 60468304 16641952
02:05:20 6286 8192000000 9992452768 10348830720 1013725696 11.011 100 77 154 0 1102636832 73596704 15314432
02:06:28 3313 8192000000 9999087040 10362552320 1038926448 11.254 100 80 158 2 1110610944 55124304 16560192
02:07:32 1652 8192000000 10043359440 10412572672 1063259680 11.488 100 93 184 0 1164586224 82181632 19144912
02:08:35 22267 8192000000 10042456320 10417422336 1074867040 11.599 100 60 120 1 1144737248 56673376 13196832
02:09:58 5834 8192000000 10000726352 10394001408 1080303616 11.650 100 45 90 3 1115616288 24981408 10331264
02:11:06 7368 8192000000 10006983104 10413015040 1085431776 11.699 100 24 47 0 1122728576 31810976 5485824
02:12:14 12386 8192000000 10012977904 10427523072 1093802064 11.779 100 40 79 1 1144796208 42545728 8448416
02:13:32 19963 8192000000 10017910832 10407403520 1098713408 11.825 100 64 128 1 1122292736 9807776 13771552
02:14:53 6496 8192000000 10016370880 10422661120 1103738464 11.873 100 29 58 2 1137430448 27287504 6404480
02:16:01 6035 8192000000 10018614864 10419793920 1107169360 11.906 100 41 80 0 1125844128 9254304 9420464
02:17:08 4968 8192000000 9995661664 10400346112 1058884256 11.446 97 25 50 1 1094238304 30186112 5167936
02:18:16 54046 8192000000 9983036240 10391891968 1025122784 11.121 95 26 50 2 1055361760 24970960 5268016
02:20:15 5401 8192000000 9954745184 10338574336 1023714416 11.108 99 33 66 0 1064691504 34047392 6929696
02:21:22 4580 8192000000 9950497952 10333986816 1014149232 11.015 99 34 67 1 1058152752 35918576 8084944
02:22:28 3622 8192000000 9955429200 10342162432 1040140736 11.266 100 46 95 4 1065203520 14906368 10156416
02:23:35 57778 8192000000 9944579600 10351570944 1030490128 11.173 98 40 81 2 1048854688 9094592 9269968
02:25:35 7545 8192000000 9970301824 10376523776 1067392144 11.527 100 29 58 0 1098428304 24164000 6872160
02:26:45 12000 8192000000 9987860704 10382327808 1071609888 11.567 100 23 46 1 1107024176 30315392 5098896
02:28:05 3416 8192000000 10057762624 10451755008 1107728240 11.911 100 61 121 1 1154557392 34539632 12289520
02:29:14 34885 8192000000 10073249440 10480439296 1117933600 12.007 100 56 111 2 1172481184 42815040 11732544
02:30:58 24092 8192000000 10101267488 10515456000 1133247184 12.152 100 36 72 4 1214246464 73748800 7250480
02:32:27 16236 8192000000 10141290672 10555396096 1142210624 12.236 100 56 111 0 1249448096 95411168 11826304
02:33:50 6870 8192000000 10163308064 10581893120 1142744976 12.241 100 48 95 0 1268119136 114301616 11072544
02:35:09 16281 8192000000 10166806528 10577858560 1158377840 12.388 100 53 105 0 1273298928 103893632 11027456
02:36:29 9403 8192000000 10218927776 10638692352 1160985968 12.412 100 70 140 0 1315543792 138928432 15629392
02:37:44 3181 8192000000 10231104480 10642755584 1171991936 12.515 100 66 132 2 1330399952 143066272 15341744
02:38:50 3062 8192000000 10254168064 10669846528 1171411776 12.510 100 65 128 2 1358555408 173371904 13771728
02:39:57 7995 8192000000 10300293392 10709942272 1173025056 12.525 100 68 135 1 1396431280 208481024 14925200
02:41:12 5430 8192000000 10288028736 10697007104 1158258624 12.387 99 61 121 1 1376891984 204613296 14020064
02:42:22 1822 8192000000 10359131568 10765606912 1189027664 12.674 100 90 178 2 1459707456 250226256 20453536
02:43:30 1695 8192000000 10407399776 10815406080 1176415056 12.557 100 75 150 3 1508678320 314531088 17732176
02:44:35 6728 8192000000 10443579136 10871164928 1172333344 12.519 100 52 103 0 1549298160 363411744 13553072
02:45:51 4243 8192000000 10435123616 10880471040 1156035680 12.366 100 66 130 0 1542060464 370265824 15758960
02:46:58 11254 8192000000 10354796928 10784841728 1155116464 12.357 100 53 106 0 1458568368 290739152 12712752
02:48:25 21855 8192000000 10308247344 10752323584 1143604032 12.249 100 37 74 1 1414017536 261549952 8863552
02:49:55 31129 8192000000 10301393280 10785505280 1124836000 12.073 100 17 34 0 1419465728 289523888 5105840
02:51:42 19132 8192000000 10100737648 10522501120 1080800464 11.655 97 25 49 0 1193391152 106973008 5617680
02:53:03 54740 8192000000 10114451392 10556260352 1089331936 11.736 99 9 18 0 1176598544 85157328 2109280
02:54:58 18108 8192000000 10061077440 10488938496 1112584384 11.957 100 28 54 0 1162265424 42457040 7224000
02:56:17 87090 8192000000 10125583312 10573193216 1091407520 11.756 98 55 109 0 1174251040 70943936 11899584
02:59:40 78218 8192000000 10072260160 10510233600 1095268928 11.793 98 22 43 3 1129815584 30124912 4421744
03:02:02 117750 8192000000 10112724496 10569732096 1102738304 11.864 97 24 48 1 1236672016 128910400 5023312
03:05:02 162081 8192000000 10167746544 10663878656 1120399072 12.031 98 20 40 0 1241137936 115971632 4767232
03:09:00 64680 8192000000 10097789504 10556727296 1138632576 12.203 100 16 32 1 1214088352 71618016 3837760
03:11:06 50114 8192000000 10110728240 10576375808 1135684000 12.175 99 21 42 0 1231012176 90864112 4464064
03:13:04 24687 8192000000 10099809904 10532798464 1147443920 12.285 100 45 88 0 1188169760 31052528 9673312
03:14:29 100700 8192000000 10161632192 10647351296 1133693152 12.156 98 14 28 1 1220381168 83297824 3390192
03:17:14 56633 8192000000 10124262416 10593230848 1118016576 12.008 97 18 36 2 1208477792 86599808 3861408
03:19:12 123794 8192000000 10078923856 10606391296 1145670448 12.269 100 12 24 0 1194132816 46100080 2362288
03:22:33 28965 8192000000 10054197136 10520776704 1126003408 12.084 99 9 17 1 1147889008 19971952 1913648
03:24:14 55926 8192000000 10073604304 10530197504 1131558528 12.136 99 17 32 2 1153315760 18800608 2956624
03:26:11 63326 8192000000 10088346080 10557280256 1133254976 12.152 99 15 30 0 1180253344 44000096 2998272
03:28:18 78489 8192000000 10132677504 10583908352 1154180112 12.349 100 22 44 0 1227312464 68752832 4379520
03:30:55 100011 8192000000 10104848224 10589835264 1141562176 12.230 98 34 67 1 1248544704 99685632 7296896
03:33:50 93557 8192000000 10096282912 10548428800 1079025808 11.638 94 11 22 0 1137524080 56104400 2393872
03:36:24 63175 8192000000 10106055712 10613313536 1106285088 11.897 97 17 34 1 1189678096 80000448 3392560
03:38:37 76559 8192000000 10073458096 10495135744 1069901504 11.551 94 22 43 0 1102672800 28731056 4040240
03:40:54 20428 8192000000 10116850928 10535321600 1117849472 12.007 99 55 109 0 1192060112 62845600 11365040
03:42:18 79096 8192000000 10025247664 10499117056 1061303296 11.469 95 23 46 0 1106876864 41288400 4285168
03:44:56 45108 8192000000 10034634192 10490855424 1081335360 11.660 98 5 10 0 1114344336 32205904 803072
03:46:42 66211 8192000000 10101620800 10555396096 1116365312 11.993 100 14 28 1 1178600336 59235040 2999984
03:48:58 67220 8192000000 10035295712 10498306048 1042576096 11.289 94 14 28 0 1078491648 33302448 2613104
03:51:13 63791 8192000000 10045837680 10511335424 1069575632 11.548 97 33 66 0 1160265792 83646624 7043536
03:53:24 62566 8192000000 10085426368 10510458880 1057152640 11.429 96 28 56 2 1124105568 61016464 5936464
03:55:27 81468 8192000000 10056807712 10511462400 1084316912 11.689 98 25 50 0 1130074384 40768016 4989456
03:57:49 115887 8192000000 10057699952 10507898880 1109319104 11.926 99 22 43 1 1151928976 37804864 4805008
04:00:46 44646 8192000000 10044513408 10501738496 1115651936 11.986 99 6 12 1 1137633856 21014464 967456
04:02:34 61007 8192000000 10068695664 10546950144 1123143232 12.057 99 13 26 0 1170260096 44439072 2677792
04:04:35 31681 8192000000 10084396784 10534674432 1132030128 12.140 100 28 56 4 1180874960 42905024 5939808
04:06:08 51523 8192000000 10110446080 10558738432 1139368672 12.210 100 22 44 2 1203141504 59256480 4516352
04:08:04 68662 8192000000 10068676784 10557153280 1126699856 12.090 99 13 26 1 1223627136 94170496 2756784
04:10:20 74872 8192000000 10107930816 10586636288 1111710576 11.949 98 28 56 1 1180436640 62773472 5952592
04:12:42 87720 8192000000 10099113632 10583023616 1118047440 12.009 98 9 18 1 1173925536 53907104 1970992
04:15:10 119825 8192000000 10078535360 10558390272 1135520224 12.173 100 17 34 0 1182313344 43579344 3213776
04:19:38 63756 8192000000 10112569856 10547314688 1119604416 12.023 97 17 34 0 1190631296 67380176 3646704
04:21:48 97612 8192000000 10128104288 10623070208 1085830272 11.703 95 19 38 0 1190115888 99742144 4543472
04:24:27 69579 8192000000 10075953728 10567221248 1080672048 11.654 96 12 26 0 1160311008 76894352 2744608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-03-2021 04:28:11 to 18-03-2021 04:28:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-03-2021 04:28:26 to 18-03-2021 04:28:28.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          231748
MON$OLDEST_ACTIVE               234312
MON$OLDEST_SNAPSHOT             234312
MON$NEXT_TRANSACTION            234312
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-30 07:30:08.6030 GMT
MON$PAGES                       2085472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:01 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:01 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:07 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:08 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:08 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:08 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Mar 18 04:27:08 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

18.03.21 04:28:29. There are 3 logs in /var/tmp/logs-oltp40 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-03-18 04:28:32 No severe PSQL-related problems occured
18.03.21 04:28:33. End of report.