Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.2020 07:37
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 17007992 kB
MemAvailable 31509404 kB
Buffers 0 kB
Cached 14556296 kB
SwapCached 2076 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-30 07:37:15
DTS_END                         2020-11-30 07:37:15.4470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52496
TRN_ID                          231929
ATT_ID                          608
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: 9010.49 21681 1081259
customer order: creation 166.32 451 19958
customer order: refuse 14.48 277 1737
order to supplier: creation 43.58 1349 5229
order to supplier: removal 3.38 122165 405
invoice (draft): creation 44.16 3350 5299
invoice (draft): removal 51.71 958 6205
invoice accept: apply 40.14 17806 4817
invoice accept: cancel 46.78 886 5613
customer reserve: creation 3767.92 171 452150
customer reserve: removal 4554.43 72 546531
realization accept: apply 80.61 164 9673
realization accept: cancel 73.90 127 8868
payment from customer: creation 57.59 112 6911
payment from customer: removal 5.53 86 663
payment to supplier: creation 46.00 133 5520
payment to supplier: removal 3.32 82 398
service: total inventory turnovers 4.02 2929 482
service: total monetary turnovers 5.71 472 685
service: aggregate perf. data 0.92 14332 110
service: refresh index statistics 0.04 267698 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-11-2020 07:37:55 to 30-11-2020 07:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2962
TEST_TIME 2 3738
TEST_TIME 3 5151
TEST_TIME 4 5061
TEST_TIME 5 5134
TEST_TIME 6 5416
TEST_TIME 7 5407
TEST_TIME 8 5504
TEST_TIME 9 5387
TEST_TIME 10 5320
TEST_TIME 11 5751
TEST_TIME 12 5737
TEST_TIME 13 5917
TEST_TIME 14 6778
TEST_TIME 15 6684
TEST_TIME 16 6967
TEST_TIME 17 7568
TEST_TIME 18 8062
TEST_TIME 19 8164
TEST_TIME 20 8539
TEST_TIME 21 9108
TEST_TIME 22 10110
TEST_TIME 23 10381
TEST_TIME 24 10706
TEST_TIME 25 12341
TEST_TIME 26 12250
TEST_TIME 27 11846
TEST_TIME 28 11717
TEST_TIME 29 11505
TEST_TIME 30 11275
TEST_TIME 31 11155
TEST_TIME 32 11056
TEST_TIME 33 10857
TEST_TIME 34 10755
TEST_TIME 35 10600
TEST_TIME 36 10445
TEST_TIME 37 10322
TEST_TIME 38 10311
TEST_TIME 39 10257
TEST_TIME 40 10259
TEST_TIME 41 10209
TEST_TIME 42 10060
TEST_TIME 43 10032
TEST_TIME 44 10162
TEST_TIME 45 10190
TEST_TIME 46 10120
TEST_TIME 47 10071
TEST_TIME 48 10220
TEST_TIME 49 10289
TEST_TIME 50 10284
TEST_TIME 51 10217
TEST_TIME 52 10116
TEST_TIME 53 9965
TEST_TIME 54 9868
TEST_TIME 55 9776
TEST_TIME 56 9687
TEST_TIME 57 9611
TEST_TIME 58 9614
TEST_TIME 59 9590
TEST_TIME 60 9560
TEST_TIME 61 9531
TEST_TIME 62 9469
TEST_TIME 63 9511
TEST_TIME 64 9601
TEST_TIME 65 9598
TEST_TIME 66 9661
TEST_TIME 67 9650
TEST_TIME 68 9742
TEST_TIME 69 9707
TEST_TIME 70 9757
TEST_TIME 71 9652
TEST_TIME 72 9554
TEST_TIME 73 9520
TEST_TIME 74 9423
TEST_TIME 75 9378
TEST_TIME 76 9339
TEST_TIME 77 9311
TEST_TIME 78 9339
TEST_TIME 79 9347
TEST_TIME 80 9370
TEST_TIME 81 9408
TEST_TIME 82 9385
TEST_TIME 83 9387
TEST_TIME 84 9354
TEST_TIME 85 9390
TEST_TIME 86 9370
TEST_TIME 87 9510
TEST_TIME 88 9417
TEST_TIME 89 9360
TEST_TIME 90 9296
TEST_TIME 91 9225
TEST_TIME 92 9197
TEST_TIME 93 9148
TEST_TIME 94 9115
TEST_TIME 95 9143
TEST_TIME 96 9102
TEST_TIME 97 9142
TEST_TIME 98 9193
TEST_TIME 99 9139
TEST_TIME 100 9172
TEST_TIME 101 9138
TEST_TIME 102 9159
TEST_TIME 103 9119
TEST_TIME 104 9218
TEST_TIME 105 9203
TEST_TIME 106 9180
TEST_TIME 107 9128
TEST_TIME 108 9107
TEST_TIME 109 9107
TEST_TIME 110 9044
TEST_TIME 111 9047
TEST_TIME 112 9013
TEST_TIME 113 9041
TEST_TIME 114 9010
TEST_TIME 115 9043
TEST_TIME 116 9055
TEST_TIME 117 9080
TEST_TIME 118 9069
TEST_TIME 119 9076
TEST_TIME 120 9014

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-11-2020 07:37:59 to 30-11-2020 07:38:02.

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 1500285 1500285 0 0.00 0 15319 7 0 0
doc_list_biud 1500285 1500285 0 0.00 0 71121 33 0 0
sp_add_invoice_to_stock 4927 4817 110 2.23 47 172930 17807 0 110
sp_cancel_adding_invoice 5613 5613 0 0.00 4 75893 886 0 0
sp_cancel_client_order 1737 1737 0 0.00 5 10073 277 0 0
sp_cancel_customer_reserve 546531 546531 0 0.00 1 71125 73 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 10114 86 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2371 83 0 0
sp_cancel_supplier_invoice 6216 6205 11 0.17 2 34742 959 0 11
sp_cancel_supplier_order 405 405 0 0.00 9 1235090 122166 0 0
sp_cancel_write_off 8868 8868 0 0.00 1 9431 128 0 0
sp_client_order 19958 19958 0 0.00 4 66999 451 0 0
sp_customer_reserve 452150 452150 0 0.00 0 70277 171 0 0
sp_fill_shopping_cart 30472 30472 0 0.00 1 25859 43 0 0
sp_fill_shopping_cart_clo_res 452145 452145 0 0.00 0 26921 21 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 36964 489 0 0
sp_kill_cost_storno 15542 15542 0 0.00 0 14250 63 0 0
sp_kill_qstorno_ret_qs2qd 1108540 1108540 0 0.00 0 71113 39 0 0
sp_kill_qty_storno 583844 583844 0 0.00 0 71120 83 0 0
sp_lock_selected_doc 20134 20134 0 0.00 0 11643 8 0 0
sp_make_cost_storno 26916 26916 0 0.00 0 15313 52 0 0
sp_multiply_rows_for_pdistr 26916 26916 0 0.00 0 12531 32 0 0
sp_multiply_rows_for_qdistr 19958 19958 0 0.00 0 66988 261 0 0
sp_pay_from_customer 6911 6911 0 0.00 4 14093 113 0 0
sp_pay_to_supplier 5520 5520 0 0.00 5 14065 133 0 0
sp_payment_common 12431 12431 0 0.00 1 8892 18 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 10037 246 0 0
sp_qd_handle_on_invoice_upd_sts 10425 10425 0 0.00 0 34682 372 0 0
sp_qd_handle_on_reserve_upd_sts 9673 9673 0 0.00 0 19705 50 0 0
sp_reserve_write_off 9673 9673 0 0.00 6 19721 165 0 0
sp_supplier_invoice 5299 5299 0 0.00 6 117168 3351 0 0
sp_supplier_order 5229 5229 0 0.00 5 69560 1350 0 0
srv_aggregate_perf_data 110 110 0 0.00 2735 169135 14332 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1058 195224 48448 0 0
srv_find_qd_qs_mism 1035328 1035328 0 0.00 0 13455 6 0 0
srv_make_invnt_saldo 482 482 0 0.00 43 108226 2930 0 0
srv_make_money_saldo 685 685 0 0.00 1 13128 473 0 0
srv_recalc_idx_stat 5 5 0 0.00 181931 483147 267698 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4817 4817 0 0.00 0 11077 131 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 2013 70 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 2601 10 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3872 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 9566 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 5779 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 641 8 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 11202 1184 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 4063 12 0 0
t$perf-norm:sp_client_order 19958 19958 0 0.00 0 17047 18 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 673 3 0 0
t$perf-norm:sp_pay_from_customer 6911 6911 0 0.00 0 10081 12 0 0
t$perf-norm:sp_pay_to_supplier 5520 5520 0 0.00 0 7679 9 0 0
t$perf-norm:sp_reserve_write_off 9673 9673 0 0.00 0 8869 11 0 0
t$perf-norm:sp_supplier_invoice 5299 5299 0 0.00 0 8871 17 0 0
t$perf-norm:sp_supplier_order 5229 5229 0 0.00 0 7182 13 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 228 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 79 2 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 1144 10 0 0
t$perf-norm:srv_make_money_saldo 685 685 0 0.00 0 2934 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 6 4 0 0
v_add_invoice_to_stock 9840 9620 220 2.23 0 10365 18 0 220
v_all_customers 36606 36606 0 0.00 0 17 1 0 0
v_all_suppliers 29366 29366 0 0.00 0 15 1 0 0
v_all_wares 39910 39910 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 3416 17 0 0
v_cancel_client_order 3474 3474 0 0.00 0 3572 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2149 9 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 4660 11 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 615 5 0 0
v_cancel_supplier_invoice 804 782 22 2.73 0 3781 16 0 22
v_cancel_supplier_order 810 810 0 0.00 0 2928 7 0 0
v_cancel_write_off 1960 1960 0 0.00 0 844 11 0 0
v_max_id_clo_ord 5228 5228 0 0.00 0 566 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 2302 12 0 0
v_max_id_ord_sup 5289 5289 0 0.00 0 5995 7 0 0
v_max_non_paid_invoice 1354 1354 0 0.00 0 181 2 0 0
v_max_non_paid_realizn 3569 3569 0 0.00 0 4579 6 0 0
v_min_id_clo_ord 5228 5228 0 0.00 0 8477 13 0 0
v_min_id_clo_res 965 965 0 0.00 0 11739 25 0 0
v_min_id_ord_sup 5289 5289 0 0.00 0 3617 6 0 0
v_min_non_paid_invoice 5520 5520 0 0.00 0 6083 12 0 0
v_min_non_paid_realizn 6911 6911 0 0.00 0 5836 12 0 0
v_our_firm 9988 9988 0 0.00 0 57 1 0 0
v_reserve_write_off 19344 19344 0 0.00 0 2799 6 0 0
x_lock_depdocs_on_canc_invoice 11427 11427 0 0.00 0 20557 106 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 3404 299 0 0
x_make_qstorno_1000_1200 5229 5229 0 0.00 2 67716 1276 0 0
x_make_qstorno_1200_2000 5299 5299 0 0.00 3 117112 3179 0 0
x_make_qstorno_2100_3300 452150 452150 0 0.00 0 70274 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2020 07:38:03 to 30-11-2020 07:38:07.

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
05:07:19 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:08:20 39585 8192000000 9731255200 10027700224 799162640 8.888 100 82 164 2 827064288 7701136 20200512
05:10:13 22432 8192000000 9766282000 10075852800 835432144 9.254 100 89 177 0 870936480 14179808 21324528
05:11:44 21842 8192000000 9821513824 10146258944 879599136 9.696 100 79 159 0 925895952 28195104 18101712
05:13:12 9836 8192000000 9895612288 10233618432 927032720 10.165 100 81 162 1 1000517968 56499024 16986224
05:14:28 8044 8192000000 9943987280 10295795712 969237984 10.579 100 74 148 2 1056774080 72159776 15376320
05:15:39 7105 8192000000 9972666032 10333724672 1000806768 10.886 100 74 148 1 1085679360 70163840 14708752
05:16:47 1717 8192000000 10015168176 10380750848 1037311360 11.239 100 81 160 5 1122846048 69093440 16441248
05:17:50 1737 8192000000 10029258896 10399399936 1051052592 11.371 100 90 178 0 1144105280 74617568 18435120
05:18:54 4929 8192000000 9989347728 10370129920 1066083984 11.515 100 53 104 1 1108364400 30626672 11653744
05:20:00 6711 8192000000 9983732832 10381590528 1069856288 11.551 100 29 58 0 1100019056 24122448 6040320
05:21:07 60255 8192000000 9981459616 10377830400 1076967664 11.619 100 38 76 3 1111058720 25210032 8881024
05:23:08 5795 8192000000 10011816592 10414297088 1086642384 11.711 100 39 79 2 1122431120 27569776 8218960
05:24:15 4289 8192000000 10011054496 10413744128 1089339264 11.736 100 56 113 3 1134967856 33931072 11697520
05:25:21 5293 8192000000 10002170848 10406772736 1081069792 11.658 99 35 70 3 1112250288 23712656 7467840
05:26:28 56937 8192000000 9990638048 10394062848 1037887536 11.244 95 25 49 1 1078882480 35452768 5542176
05:28:28 7797 8192000000 9968944880 10368561152 1031666080 11.184 98 29 59 2 1062021936 23840880 6514976
05:29:36 4071 8192000000 9944668144 10333097984 1015705280 11.031 97 40 80 1 1036238304 12168544 8364480
05:30:40 7628 8192000000 9944947184 10325295104 988092160 10.763 96 46 91 4 1020287728 21184304 11011264
05:31:49 58045 8192000000 9926518032 10323632128 976795584 10.653 95 16 32 1 1002308816 22264944 3248288
05:33:48 12703 8192000000 9963087536 10360676352 1036177808 11.228 99 46 93 3 1069127728 22359760 10590160
05:35:04 10276 8192000000 9985648144 10382286848 1057746800 11.435 100 37 74 0 1091076704 25084320 8245584
05:36:18 6672 8192000000 9977672256 10379034624 1062970336 11.485 100 39 76 3 1086056304 14792448 8293520
05:37:25 72290 8192000000 10010507472 10412527616 1078501824 11.633 100 34 68 0 1106074736 20635616 6937296
05:39:42 12658 8192000000 10068723840 10468429824 1108129024 11.915 100 51 100 0 1175256384 56957008 10170352
05:40:57 17179 8192000000 10084976848 10497445888 1126391232 12.087 100 34 66 2 1199530816 66094336 7045248
05:42:19 4049 8192000000 10157354640 10562514944 1143609152 12.249 100 53 104 2 1271105376 116990000 10506224
05:43:26 9636 8192000000 10208839696 10613305344 1156983104 12.375 100 69 138 1 1310747264 139051120 14713040
05:44:39 4542 8192000000 10247681280 10658246656 1172797648 12.523 100 55 110 0 1353420528 167918640 12704240
05:45:48 6277 8192000000 10284862496 10701082624 1183404416 12.622 100 55 109 0 1390151024 194327824 12418784
05:46:58 1562 8192000000 10339811504 10753441792 1186422688 12.650 100 61 121 1 1444576368 244929328 13224352
05:48:03 4715 8192000000 10406357600 10823774208 1197930880 12.757 100 68 135 1 1514418624 300761232 15726512
05:49:14 5572 8192000000 10419364848 10834309120 1196298016 12.742 100 64 128 1 1514303824 303316304 14689504
05:50:26 1057 8192000000 10487213520 10893389824 1187110016 12.656 99 89 172 3 1578042848 370400144 20532688
05:51:32 3517 8192000000 10546482512 10980290560 1194541216 12.726 100 60 119 1 1650642944 441728912 14372816
05:52:44 5170 8192000000 10567844736 10975100928 1187845872 12.663 100 81 162 3 1659986048 452856288 19283888
05:53:53 10639 8192000000 10610142608 11037802496 1195724096 12.737 100 56 110 0 1707859888 498268816 13866976
05:55:10 11015 8192000000 10571213072 10993700864 1151901424 12.327 98 56 110 1 1638528768 472798512 13828832
05:56:24 14262 8192000000 10554747952 10989555712 1165968880 12.459 100 38 76 1 1651263680 474723936 10570864
05:57:40 29396 8192000000 10579940528 11033686016 1152437120 12.332 99 36 72 1 1669897360 506603136 10857104
05:59:12 27890 8192000000 10450709280 10965307392 1137070480 12.188 98 25 50 0 1559599504 415762960 6766064
06:01:15 52886 8192000000 10235049984 10833039360 1096359904 11.803 97 15 30 0 1479064096 378234432 4469760
06:03:09 107371 8192000000 10048341584 10465345536 1061988432 11.476 95 18 36 0 1112406848 46394336 4024080
06:05:57 40223 8192000000 10023831200 10458914816 1074954336 11.599 97 17 34 0 1108374096 29839792 3579968
06:07:38 63589 8192000000 10054168944 10522345472 1101284144 11.850 99 21 42 1 1158123200 52526528 4312528
06:09:43 83966 8192000000 10059421360 10502787072 1083936464 11.685 98 14 28 0 1131882240 44555248 3390528
06:12:49 75339 8192000000 10106695840 10560090112 1096830544 11.808 97 19 37 0 1202112032 101227024 4054464
06:16:57 92083 8192000000 10058216960 10555678720 1120925616 12.036 99 10 20 1 1151293632 28839280 1528736
06:19:44 66462 8192000000 10138915072 10577326080 1134964064 12.168 99 36 72 1 1219149024 76314464 7870496
06:21:58 80483 8192000000 10095224816 10548580352 1121837696 12.044 98 33 65 3 1166248320 38000048 6410576
06:24:20 29190 8192000000 10132168368 10581127168 1151862032 12.327 100 29 58 0 1227463136 69520976 6080128
06:25:50 195223 8192000000 10097554512 10584326144 1120413920 12.031 97 18 36 0 1187776784 63650064 3712800
06:30:12 91718 8192000000 10069376080 10530701312 1154054112 12.348 100 22 44 2 1186695696 28393280 4248304
06:33:26 112078 8192000000 10113024176 10597933056 1161217696 12.415 100 14 28 0 1207471328 43051280 3202352
06:36:22 55523 8192000000 10059044304 10540179456 1125444016 12.078 97 12 24 0 1173525552 45360032 2721504
06:38:22 32935 8192000000 10112589792 10565619712 1151705328 12.326 99 24 48 4 1195476112 38174816 5595968
06:39:55 49966 8192000000 10151474176 10615808000 1149613312 12.306 99 20 40 0 1256351888 102332832 4405744
06:41:53 53684 8192000000 10088947520 10527760384 1118779088 12.015 96 15 30 0 1165440624 44152240 2509296
06:43:48 74291 8192000000 10101247392 10588921856 1096581376 11.805 95 14 28 1 1148752112 49117280 3053456
06:46:04 59712 8192000000 10082726080 10498449408 1065692448 11.511 93 34 69 1 1100568544 27254448 7621648
06:48:06 107619 8192000000 10066302048 10494013440 1118366512 12.012 99 30 60 3 1164282912 39240240 6676160
06:50:55 44647 8192000000 10054535200 10513342464 1112170592 11.953 99 5 10 0 1142087968 28855728 1061648
06:52:47 68383 8192000000 10082979984 10523648000 1102964528 11.866 98 19 37 1 1166150048 59043904 4141616
06:54:58 49703 8192000000 10115123440 10565103616 1114983344 11.980 98 24 48 2 1201540608 81249072 5308192
06:56:49 73538 8192000000 10077491024 10531938304 1108909440 11.922 98 22 44 2 1211962960 98284064 4769456
06:59:04 38887 8192000000 10071347632 10507276288 1084790624 11.693 96 26 52 2 1131583856 41881776 4911456
07:00:44 33077 8192000000 10072434496 10486804480 1086185136 11.706 97 31 62 0 1129780048 37838032 5756880
07:02:21 45906 8192000000 10082544256 10536497152 1080661440 11.654 97 21 42 1 1161049328 75741600 4646288
07:04:20 109888 8192000000 10042816864 10471440384 1090728368 11.750 98 13 26 0 1146695024 53045776 2920880
07:07:20 54875 8192000000 10021281120 10470391808 1102576256 11.862 99 10 20 0 1138624208 34041744 2006208
07:09:19 75960 8192000000 10029996528 10484703232 1053662896 11.396 95 8 14 1 1080912496 25649152 1600448
07:11:36 85231 8192000000 10087974992 10552500224 1086388048 11.708 96 20 40 0 1164931680 74248384 4295248
07:14:02 67015 8192000000 10106909680 10577321984 1120154720 12.028 99 25 50 1 1195645776 70372192 5118864
07:17:05 27876 8192000000 10111022240 10540978176 1131875520 12.139 100 37 74 1 1195626336 56067808 7683008
07:18:33 14668 8192000000 10101284768 10520616960 1137479760 12.192 100 55 110 1 1198548032 48919152 12149120
07:19:55 57660 8192000000 10131458368 10549395456 1112893680 11.960 97 21 42 1 1184286736 66895072 4497984
07:22:00 60577 8192000000 10075509344 10506645504 1120751296 12.034 98 22 44 1 1147195360 21471904 4972160
07:24:01 29164 8192000000 10150714464 10576310272 1147117696 12.282 100 59 116 1 1217260496 57959264 12183536
07:26:25 74473 8192000000 10099408224 10575101952 1147350704 12.285 100 4 8 0 1195081904 46901024 830176
07:29:59 24314 8192000000 10116228432 10546487296 1130746976 12.128 99 36 72 1 1205444816 66940416 7757424
07:31:42 35600 8192000000 10126882096 10567417856 1115086528 11.981 97 25 51 2 1178119696 58122208 4910960
07:33:19 46605 8192000000 10094523328 10555777024 1101478704 11.852 97 22 44 1 1156334992 49976880 4879408
07:35:09 51565 8192000000 10096402368 10536316928 1102886000 11.865 97 23 46 2 1157018416 49396528 4735888
07:37:01 19768 8192000000 9060942768 9298395136 200100976 2.384 18 9 18 0 225781872 23984816 1696080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-11-2020 07:38:09 to 30-11-2020 07:38:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-11-2020 07:38:24 to 30-11-2020 07:38:26.

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          232988
MON$OLDEST_ACTIVE               232989
MON$OLDEST_SNAPSHOT             232989
MON$NEXT_TRANSACTION            232989
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                       2082016
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             793
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2281 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.2281 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Nov 30 07:37:22 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Nov 30 07:37:25 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Nov 30 07:37:28 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

30.11.20 07:38:27. 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-30 07:38:29 No severe PSQL-related problems occured
30.11.20 07:38:31. End of report.