Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2020 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16613136 kB
MemAvailable 31380016 kB
Buffers 20 kB
Cached 14942016 kB
SwapCached 2836 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 2020-11-05 04:26:54
DTS_END                         2020-11-05 04:26:54.2450
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(68:17)
IP                              ::1/46398
TRN_ID                          243190
ATT_ID                          707
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: 9154.14 23764 1098497
customer order: creation 175.75 430 21090
customer order: refuse 14.93 288 1792
order to supplier: creation 48.07 1298 5768
order to supplier: removal 3.42 117898 410
invoice (draft): creation 47.37 3348 5684
invoice (draft): removal 53.08 906 6370
invoice accept: apply 42.16 16310 5059
invoice accept: cancel 47.64 809 5717
customer reserve: creation 3824.17 161 458900
customer reserve: removal 4601.24 69 552149
realization accept: apply 83.82 163 10058
realization accept: cancel 82.79 118 9935
payment from customer: creation 59.59 115 7151
payment from customer: removal 5.52 100 662
payment to supplier: creation 50.03 119 6004
payment to supplier: removal 3.69 104 443
service: total inventory turnovers 4.08 3062 490
service: total monetary turnovers 5.74 587 689
service: aggregate perf. data 1.02 14636 122
service: refresh index statistics 0.03 314760 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-11-2020 04:27:38 to 05-11-2020 04:27:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2261
TEST_TIME 2 4166
TEST_TIME 3 3967
TEST_TIME 4 3878
TEST_TIME 5 3730
TEST_TIME 6 4171
TEST_TIME 7 4307
TEST_TIME 8 4340
TEST_TIME 9 4485
TEST_TIME 10 4587
TEST_TIME 11 4784
TEST_TIME 12 5153
TEST_TIME 13 5295
TEST_TIME 14 5388
TEST_TIME 15 5781
TEST_TIME 16 5961
TEST_TIME 17 6048
TEST_TIME 18 6346
TEST_TIME 19 7045
TEST_TIME 20 7340
TEST_TIME 21 7454
TEST_TIME 22 7597
TEST_TIME 23 8795
TEST_TIME 24 9119
TEST_TIME 25 9693
TEST_TIME 26 11009
TEST_TIME 27 10965
TEST_TIME 28 10943
TEST_TIME 29 10826
TEST_TIME 30 10739
TEST_TIME 31 10490
TEST_TIME 32 10458
TEST_TIME 33 10145
TEST_TIME 34 10117
TEST_TIME 35 9997
TEST_TIME 36 10089
TEST_TIME 37 10097
TEST_TIME 38 10010
TEST_TIME 39 9863
TEST_TIME 40 9823
TEST_TIME 41 9725
TEST_TIME 42 9889
TEST_TIME 43 9802
TEST_TIME 44 9680
TEST_TIME 45 9589
TEST_TIME 46 9623
TEST_TIME 47 9762
TEST_TIME 48 9686
TEST_TIME 49 9679
TEST_TIME 50 9835
TEST_TIME 51 9734
TEST_TIME 52 9759
TEST_TIME 53 9729
TEST_TIME 54 9555
TEST_TIME 55 9650
TEST_TIME 56 9567
TEST_TIME 57 9493
TEST_TIME 58 9367
TEST_TIME 59 9334
TEST_TIME 60 9371
TEST_TIME 61 9470
TEST_TIME 62 9404
TEST_TIME 63 9388
TEST_TIME 64 9558
TEST_TIME 65 9576
TEST_TIME 66 9545
TEST_TIME 67 9599
TEST_TIME 68 9730
TEST_TIME 69 9660
TEST_TIME 70 9758
TEST_TIME 71 9705
TEST_TIME 72 9586
TEST_TIME 73 9554
TEST_TIME 74 9549
TEST_TIME 75 9454
TEST_TIME 76 9386
TEST_TIME 77 9348
TEST_TIME 78 9319
TEST_TIME 79 9312
TEST_TIME 80 9343
TEST_TIME 81 9433
TEST_TIME 82 9397
TEST_TIME 83 9452
TEST_TIME 84 9430
TEST_TIME 85 9423
TEST_TIME 86 9464
TEST_TIME 87 9487
TEST_TIME 88 9424
TEST_TIME 89 9330
TEST_TIME 90 9265
TEST_TIME 91 9287
TEST_TIME 92 9265
TEST_TIME 93 9240
TEST_TIME 94 9265
TEST_TIME 95 9241
TEST_TIME 96 9223
TEST_TIME 97 9233
TEST_TIME 98 9185
TEST_TIME 99 9184
TEST_TIME 100 9314
TEST_TIME 101 9357
TEST_TIME 102 9338
TEST_TIME 103 9414
TEST_TIME 104 9386
TEST_TIME 105 9319
TEST_TIME 106 9253
TEST_TIME 107 9221
TEST_TIME 108 9172
TEST_TIME 109 9188
TEST_TIME 110 9165
TEST_TIME 111 9134
TEST_TIME 112 9136
TEST_TIME 113 9135
TEST_TIME 114 9120
TEST_TIME 115 9096
TEST_TIME 116 9154
TEST_TIME 117 9134
TEST_TIME 118 9118
TEST_TIME 119 9177
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2020 04:27:41 to 05-11-2020 04:27:45.

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 1524355 1524355 0 0.00 0 20032 6 0 0
doc_list_biud 1524353 1524353 0 0.00 0 50687 31 0 0
sp_add_invoice_to_stock 5131 5059 72 1.40 44 172357 16310 0 72
sp_cancel_adding_invoice 5717 5717 0 0.00 4 56993 809 0 0
sp_cancel_client_order 1792 1792 0 0.00 5 8262 289 0 0
sp_cancel_customer_reserve 552149 552149 0 0.00 1 26350 70 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 5237 101 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 7417 104 0 0
sp_cancel_supplier_invoice 6377 6370 7 0.10 2 35303 907 0 7
sp_cancel_supplier_order 410 410 0 0.00 7 1172671 117899 0 0
sp_cancel_write_off 9935 9935 0 0.00 1 10315 118 0 0
sp_client_order 21090 21090 0 0.00 4 29673 430 0 0
sp_customer_reserve 458900 458900 0 0.00 0 87662 162 0 0
sp_fill_shopping_cart 32522 32522 0 0.00 1 19268 40 0 0
sp_fill_shopping_cart_clo_res 458894 458894 0 0.00 0 25215 19 0 0
sp_get_clo_for_invoice 5053 5053 0 0.00 0 59332 416 0 0
sp_kill_cost_storno 16757 16757 0 0.00 0 7084 55 0 0
sp_kill_qstorno_ret_qs2qd 1121013 1121013 0 0.00 0 35297 37 0 0
sp_kill_qty_storno 591484 591484 0 0.00 0 50687 80 0 0
sp_lock_selected_doc 20984 20984 0 0.00 0 8202 6 0 0
sp_make_cost_storno 28266 28266 0 0.00 0 16835 55 0 0
sp_multiply_rows_for_pdistr 28266 28266 0 0.00 0 7583 32 0 0
sp_multiply_rows_for_qdistr 21090 21090 0 0.00 0 14556 256 0 0
sp_pay_from_customer 7151 7151 0 0.00 4 14166 115 0 0
sp_pay_to_supplier 6004 6004 0 0.00 5 16841 120 0 0
sp_payment_common 13155 13155 0 0.00 1 7062 18 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 8142 261 0 0
sp_qd_handle_on_invoice_upd_sts 10770 10770 0 0.00 0 50686 366 0 0
sp_qd_handle_on_reserve_upd_sts 10058 10058 0 0.00 0 12093 42 0 0
sp_reserve_write_off 10058 10058 0 0.00 6 15227 163 0 0
sp_supplier_invoice 5684 5684 0 0.00 8 134339 3349 0 0
sp_supplier_order 5768 5768 0 0.00 6 41084 1298 0 0
srv_aggregate_perf_data 122 122 0 0.00 2673 201608 14637 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1053 118288 49444 0 0
srv_find_qd_qs_mism 1050800 1050800 0 0.00 0 19942 5 0 0
srv_make_invnt_saldo 490 490 0 0.00 58 117608 3063 0 0
srv_make_money_saldo 689 689 0 0.00 1 16883 587 0 0
srv_recalc_idx_stat 4 4 0 0.00 59237 441927 314760 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5059 5059 0 0.00 0 32388 152 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 2063 69 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 5015 20 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2189 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 716 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 344 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 375 8 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 11968 1263 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2744 10 0 0
t$perf-norm:sp_client_order 21090 21090 0 0.00 0 7367 17 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 7100 14 0 0
t$perf-norm:sp_pay_from_customer 7151 7151 0 0.00 0 4257 7 0 0
t$perf-norm:sp_pay_to_supplier 6004 6004 0 0.00 0 6923 10 0 0
t$perf-norm:sp_reserve_write_off 10058 10058 0 0.00 0 5828 7 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 24660 19 0 0
t$perf-norm:sp_supplier_order 5768 5768 0 0.00 0 19147 20 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 269 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 43 1 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 668 7 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 1653 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10246 10102 144 1.40 0 5379 17 0 144
v_all_customers 38624 38624 0 0.00 0 16 1 0 0
v_all_suppliers 31456 31456 0 0.00 0 21 1 0 0
v_all_wares 42178 42178 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 954 7 0 0
v_cancel_client_order 3584 3584 0 0.00 0 1619 3 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 5187 10 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1341 6 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 7405 18 0 0
v_cancel_supplier_invoice 910 896 14 1.53 0 5080 19 0 14
v_cancel_supplier_order 820 820 0 0.00 0 1423 6 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4791 22 0 0
v_max_id_clo_ord 5765 5765 0 0.00 0 221 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 3066 15 0 0
v_max_id_ord_sup 5668 5668 0 0.00 0 3425 3 0 0
v_max_non_paid_invoice 1709 1709 0 0.00 0 58 2 0 0
v_max_non_paid_realizn 3656 3656 0 0.00 0 2593 3 0 0
v_min_id_clo_ord 5765 5765 0 0.00 0 3721 8 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 4207 11 0 0
v_min_id_ord_sup 5668 5668 0 0.00 0 7225 6 0 0
v_min_non_paid_invoice 6004 6004 0 0.00 0 6009 13 0 0
v_min_non_paid_realizn 7151 7151 0 0.00 0 7047 13 0 0
v_our_firm 10544 10544 0 0.00 0 14 1 0 0
v_reserve_write_off 20116 20116 0 0.00 0 4786 6 0 0
x_lock_depdocs_on_canc_invoice 11639 11639 0 0.00 0 12776 106 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 23691 326 0 0
x_make_qstorno_1000_1200 5768 5768 0 0.00 2 41076 1222 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 3 126909 3202 0 0
x_make_qstorno_2100_3300 458900 458900 0 0.00 0 87661 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2020 04:27:46 to 05-11-2020 04:27:50.

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:56:57 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:58 28410 8192000000 9720159424 10016006144 796190624 8.858 100 89 178 0 826404912 7917648 22296640
01:59:33 22395 8192000000 9757741248 10064265216 831727024 9.217 100 85 168 2 864233392 12164656 20341712
02:01:09 14198 8192000000 9801654064 10122526720 865105520 9.551 100 74 148 1 903913024 22166432 16641072
02:02:32 11564 8192000000 9875346960 10211848192 921663904 10.112 100 79 158 0 986744192 47906400 17173888
02:03:50 7230 8192000000 9942552864 10289446912 971511104 10.601 100 77 154 4 1052160032 64560096 16088832
02:05:00 3325 8192000000 9965201744 10325147648 1002601040 10.904 100 80 159 1 1080957840 63021968 15334832
02:06:06 2526 8192000000 9994414160 10358353920 1030167424 11.170 100 88 173 2 1109558432 60976368 18414640
02:07:17 2611 8192000000 10023292928 10393796608 1061935648 11.475 100 89 174 2 1134637280 54027376 18674256
02:08:22 1883 8192000000 10053285952 10427244544 1078602864 11.634 100 86 172 1 1163059104 66604000 17852240
02:09:26 5510 8192000000 10005449984 10401456128 1076332752 11.613 100 43 86 0 1131770592 46644768 8793072
02:10:32 4249 8192000000 10007657376 10397478912 1080291664 11.650 100 46 92 2 1129265952 38258736 10715552
02:11:37 11359 8192000000 10009784720 10410577920 1084504480 11.690 100 31 62 4 1117967984 26940016 6523488
02:12:51 37977 8192000000 10021845744 10404921344 1088181232 11.725 100 65 132 5 1119922768 16777920 14963616
02:14:29 10282 8192000000 10003077232 10411675648 1087812752 11.722 100 31 62 0 1129980560 35765088 6402720
02:15:40 5393 8192000000 10012595792 10416910336 1084329600 11.689 99 39 79 1 1109581456 16745424 8506432
02:16:47 9288 8192000000 10015054416 10421350400 1073362944 11.584 98 36 69 2 1112323344 31323120 7637280
02:18:03 37473 8192000000 9988409024 10400337920 1058239360 11.440 98 54 106 1 1081882880 12048624 11594896
02:19:43 10896 8192000000 9936686416 10322747392 969430896 10.581 94 41 81 2 996675040 18252880 8991264
02:20:57 11952 8192000000 9927274160 10312736768 981895248 10.703 96 26 51 1 999409840 11901520 5613072
02:22:10 5665 8192000000 9949546960 10323873792 1015590912 11.029 98 42 83 2 1042092032 16637280 9863840
02:23:18 50951 8192000000 9952624944 10354864128 1040557072 11.270 100 26 51 2 1073295200 27021744 5716384
02:25:10 4422 8192000000 9963848816 10350440448 1053766880 11.397 100 33 65 3 1074870096 14423248 6679968
02:26:15 7979 8192000000 9987699104 10382573568 1073176704 11.582 100 18 37 2 1092114144 15446064 3491376
02:27:28 15636 8192000000 10027888960 10420776960 1097615216 11.815 100 48 93 0 1129379392 21945968 9818208
02:28:59 68338 8192000000 10054920816 10460004352 1113492224 11.965 100 41 80 1 1184863424 62313808 9057392
02:31:11 37493 8192000000 10111869408 10516766720 1129565200 12.117 100 43 86 2 1222837808 84058848 9213760
02:32:57 22489 8192000000 10171117264 10572689408 1144808656 12.261 100 44 88 0 1272827104 118894768 9123680
02:34:29 21485 8192000000 10206404416 10610413568 1144009280 12.253 99 55 110 0 1304209872 148338384 11862208
02:35:56 5048 8192000000 10267125232 10672840704 1167074256 12.469 100 69 136 1 1373409360 190835760 15499344
02:37:10 12041 8192000000 10315126048 10725924864 1167002608 12.469 100 61 121 1 1413157328 231049456 15105264
02:38:26 8911 8192000000 10315314048 10734022656 1176703632 12.559 100 45 86 1 1416335696 229137424 10494640
02:39:54 2347 8192000000 10365247184 10771963904 1178391632 12.575 100 69 138 2 1462908288 268476656 16040000
02:41:01 1050 8192000000 10396687920 10816782336 1181676944 12.606 100 74 147 0 1503714512 305095440 16942128
02:42:06 2401 8192000000 10418953136 10810724352 1151572480 12.324 100 79 154 1 1518275376 349068816 17634080
02:43:14 1786 8192000000 10474830144 10870607872 1142929008 12.243 99 81 162 2 1565454976 403171984 19353984
02:44:23 1441 8192000000 10500936752 10898345984 1158009328 12.385 100 80 159 0 1593409584 416079744 19320512
02:45:27 5989 8192000000 10502401584 10923016192 1164382336 12.444 100 54 107 1 1612338320 434207952 13748032
02:46:36 13000 8192000000 10516982432 10952646656 1154748800 12.354 100 44 88 0 1594854960 428618544 11487616
02:47:59 9567 8192000000 10540586416 10991243264 1157613584 12.381 100 54 108 0 1648612432 477528752 13470096
02:49:21 16024 8192000000 10490794144 10972758016 1126112192 12.085 99 32 64 1 1625150816 490010768 9027856
02:50:48 23670 8192000000 10308609744 10773999616 1090414512 11.747 98 18 36 2 1443796512 348266448 5115552
02:52:13 54814 8192000000 10048887200 10482716672 1110423056 11.936 99 6 12 0 1212508864 100317328 1768480
02:54:35 94341 8192000000 10067872208 10487521280 1074548176 11.595 95 14 26 0 1106122928 28627840 2946912
02:57:12 118288 8192000000 10085617008 10547261440 1104369968 11.879 97 21 42 0 1159649888 50976048 4303872
03:01:15 67496 8192000000 10096798896 10585169920 1113506080 11.966 97 24 47 1 1202586560 83256672 5823808
03:03:32 32247 8192000000 10081104752 10502991872 1142796864 12.242 100 40 74 4 1176728576 25469280 8462432
03:05:10 83328 8192000000 10101556640 10559979520 1120193280 12.029 98 12 24 0 1191198992 68410464 2595248
03:08:21 48077 8192000000 10060155152 10488090624 1136563264 12.183 99 33 67 1 1175293536 30721216 8009056
03:10:20 71282 8192000000 10140757600 10583986176 1153595984 12.343 100 21 43 1 1232337280 74489584 4251712
03:12:39 52711 8192000000 10104347216 10628042752 1138548752 12.202 99 11 22 0 1230043280 89140064 2354464
03:14:37 27734 8192000000 10114834592 10563735552 1156062144 12.366 100 25 51 5 1207803056 46327936 5412976
03:16:08 82253 8192000000 10083339520 10525261824 1136536640 12.183 99 11 22 0 1209802672 70643920 2622112
03:18:34 38180 8192000000 10118235184 10585083904 1124557280 12.070 98 27 54 0 1196888480 66694160 5637040
03:20:28 101548 8192000000 10090535952 10614501376 1139540080 12.211 100 22 44 0 1227563360 83110528 4912752
03:23:11 40553 8192000000 10083592512 10543902720 1119957440 12.027 98 16 32 2 1176398112 52325760 4114912
03:25:16 37693 8192000000 10124830112 10572603392 1111396528 11.946 97 23 46 0 1225157824 108666464 5094832
03:26:57 70503 8192000000 10069687248 10537164800 1087780384 11.722 97 15 27 2 1184396784 93433072 3183328
03:29:12 75392 8192000000 10079571424 10611621888 1087376064 11.718 97 19 38 0 1200147152 108604528 4166560
03:32:32 35838 8192000000 10115528464 10529370112 1096151984 11.801 98 33 66 0 1177479312 74264768 7062560
03:34:29 69070 8192000000 10071568288 10496335872 1086822944 11.712 97 23 44 0 1140277968 49344960 4110064
03:36:40 50422 8192000000 10068557248 10508447744 1104934000 11.884 99 30 60 0 1159601136 48291408 6375728
03:38:32 106819 8192000000 10140216736 10621509632 1112720016 11.958 99 29 58 0 1220504320 101888272 5896032
03:42:52 55474 8192000000 10073797584 10523398144 1099213952 11.830 98 11 21 1 1135951408 34386512 2350944
03:46:00 96940 8192000000 10082854000 10564259840 1084580160 11.691 97 16 33 0 1211738256 123519936 3638160
03:48:38 40891 8192000000 10084639584 10515243008 1080994768 11.657 97 29 58 0 1151027040 64236432 5795840
03:50:21 59489 8192000000 10055994640 10484862976 1074161920 11.592 97 18 36 0 1104535952 27184816 3189216
03:52:22 37301 8192000000 10088955472 10550603776 1114337744 11.973 100 31 62 2 1208904768 88309664 6257360
03:54:01 99959 8192000000 10092331136 10563284992 1120584528 12.033 100 20 40 0 1181292960 56719360 3989072
03:56:49 83555 8192000000 10101075872 10588770304 1126560688 12.089 99 15 30 0 1200324048 70742576 3020784
03:59:28 49121 8192000000 10078881808 10516680704 1117763648 12.006 98 22 44 1 1184115184 61980512 4371024
04:01:20 46195 8192000000 10072659824 10506981376 1091517520 11.757 96 22 44 3 1121610352 25813552 4279280
04:03:07 48070 8192000000 10115901264 10570407936 1143019328 12.244 100 42 82 0 1213006896 61305056 8682512
04:05:01 43582 8192000000 10169295232 10598686720 1117585344 12.004 98 22 43 2 1223218896 100957536 4676016
04:07:15 64859 8192000000 10078886272 10504781824 1101989632 11.857 97 14 28 0 1144088224 39266960 2831632
04:09:25 99634 8192000000 10072816608 10517065728 1035027328 11.217 91 12 23 0 1100146592 62718608 2400656
04:12:06 117437 8192000000 10071792960 10525499392 1130426304 12.125 100 20 40 2 1184775760 50199072 4150384
04:16:05 74677 8192000000 10031761552 10481913856 1048963024 11.351 94 5 10 1 1073183344 23201872 1018448
04:18:19 36016 8192000000 10038550576 10485448704 1078396336 11.632 97 20 40 2 1126143792 43061008 4686448
04:19:56 46243 8192000000 10096732368 10529759232 1111495536 11.947 99 28 55 2 1171629136 54400288 5733312
04:21:43 65733 8192000000 10085345760 10582528000 1084458400 11.690 97 20 40 0 1161355888 72484976 4412512
04:23:49 41598 8192000000 10099466640 10532352000 1095551936 11.795 98 26 52 0 1194672400 93597168 5523296
04:25:31 83035 8192000000 10101079760 10542555136 1124016416 12.065 100 23 47 0 1183011872 53601056 5394400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-11-2020 04:27:52 to 05-11-2020 04:28:06.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2020 04:28:07 to 05-11-2020 04:28:09.

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          241445
MON$OLDEST_ACTIVE               243439
MON$OLDEST_SNAPSHOT             243439
MON$NEXT_TRANSACTION            243439
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                       2111808
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             810
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2244 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.2244 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Thu Nov  5 04:27:07 2020
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
--- end of diff output ---

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

05.11.20 04:28:10. 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
2020-11-05 04:28:12 No severe PSQL-related problems occured
05.11.20 04:28:14. End of report.