Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2020 13:29
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 16558048 kB
MemAvailable 31443228 kB
Buffers 0 kB
Cached 15133372 kB
SwapCached 3484 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-15 13:26:54
DTS_END                         2020-11-15 13:26:54.8780
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/39360
TRN_ID                          235060
ATT_ID                          605
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.80 23037 1081296
customer order: creation 171.19 358 20543
customer order: refuse 15.02 362 1802
order to supplier: creation 45.71 1513 5485
order to supplier: removal 3.24 129284 389
invoice (draft): creation 46.15 3964 5538
invoice (draft): removal 50.70 1078 6084
invoice accept: apply 41.28 18129 4953
invoice accept: cancel 45.25 1038 5430
customer reserve: creation 3816.77 178 458012
customer reserve: removal 4486.89 74 538427
realization accept: apply 83.53 193 10023
realization accept: cancel 77.58 117 9310
payment from customer: creation 58.89 125 7067
payment from customer: removal 5.43 81 652
payment to supplier: creation 48.77 117 5852
payment to supplier: removal 3.62 91 434
service: total inventory turnovers 3.63 4185 435
service: total monetary turnovers 6.28 662 753
service: aggregate perf. data 0.86 15283 103
service: refresh index statistics 0.03 283915 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2020 13:29:40 to 15-11-2020 13:29:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2732
TEST_TIME 2 3833
TEST_TIME 3 4335
TEST_TIME 4 5298
TEST_TIME 5 5216
TEST_TIME 6 5420
TEST_TIME 7 5358
TEST_TIME 8 5364
TEST_TIME 9 5152
TEST_TIME 10 5051
TEST_TIME 11 5195
TEST_TIME 12 5579
TEST_TIME 13 5451
TEST_TIME 14 5628
TEST_TIME 15 5830
TEST_TIME 16 6324
TEST_TIME 17 6379
TEST_TIME 18 6574
TEST_TIME 19 6637
TEST_TIME 20 6936
TEST_TIME 21 7154
TEST_TIME 22 7436
TEST_TIME 23 7439
TEST_TIME 24 7526
TEST_TIME 25 7763
TEST_TIME 26 7989
TEST_TIME 27 7920
TEST_TIME 28 8010
TEST_TIME 29 8114
TEST_TIME 30 8200
TEST_TIME 31 8192
TEST_TIME 32 8551
TEST_TIME 33 8872
TEST_TIME 34 9502
TEST_TIME 35 9846
TEST_TIME 36 10078
TEST_TIME 37 10257
TEST_TIME 38 10075
TEST_TIME 39 10143
TEST_TIME 40 10019
TEST_TIME 41 9882
TEST_TIME 42 9750
TEST_TIME 43 9855
TEST_TIME 44 9940
TEST_TIME 45 9875
TEST_TIME 46 9784
TEST_TIME 47 9750
TEST_TIME 48 9899
TEST_TIME 49 9805
TEST_TIME 50 9626
TEST_TIME 51 9482
TEST_TIME 52 9344
TEST_TIME 53 9325
TEST_TIME 54 9306
TEST_TIME 55 9265
TEST_TIME 56 9267
TEST_TIME 57 9265
TEST_TIME 58 9243
TEST_TIME 59 9183
TEST_TIME 60 9121
TEST_TIME 61 9068
TEST_TIME 62 9061
TEST_TIME 63 9122
TEST_TIME 64 9119
TEST_TIME 65 9151
TEST_TIME 66 9175
TEST_TIME 67 9194
TEST_TIME 68 9190
TEST_TIME 69 9131
TEST_TIME 70 9163
TEST_TIME 71 9323
TEST_TIME 72 9363
TEST_TIME 73 9350
TEST_TIME 74 9327
TEST_TIME 75 9359
TEST_TIME 76 9452
TEST_TIME 77 9447
TEST_TIME 78 9340
TEST_TIME 79 9238
TEST_TIME 80 9216
TEST_TIME 81 9196
TEST_TIME 82 9123
TEST_TIME 83 9072
TEST_TIME 84 9047
TEST_TIME 85 9031
TEST_TIME 86 9027
TEST_TIME 87 9041
TEST_TIME 88 9065
TEST_TIME 89 9029
TEST_TIME 90 9001
TEST_TIME 91 9107
TEST_TIME 92 9143
TEST_TIME 93 9084
TEST_TIME 94 9134
TEST_TIME 95 9157
TEST_TIME 96 9231
TEST_TIME 97 9198
TEST_TIME 98 9251
TEST_TIME 99 9240
TEST_TIME 100 9255
TEST_TIME 101 9190
TEST_TIME 102 9134
TEST_TIME 103 9102
TEST_TIME 104 9051
TEST_TIME 105 9047
TEST_TIME 106 8993
TEST_TIME 107 8963
TEST_TIME 108 8971
TEST_TIME 109 8974
TEST_TIME 110 8965
TEST_TIME 111 8953
TEST_TIME 112 8964
TEST_TIME 113 8933
TEST_TIME 114 8986
TEST_TIME 115 9010
TEST_TIME 116 8997
TEST_TIME 117 9054
TEST_TIME 118 9068
TEST_TIME 119 9088
TEST_TIME 120 9012

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-11-2020 13:29:43 to 15-11-2020 13:29:47.

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 1505376 1505376 0 0.00 0 14885 7 0 0
doc_list_biud 1505367 1505367 0 0.00 0 109395 34 0 0
sp_add_invoice_to_stock 5063 4953 110 2.17 25 136460 18129 0 110
sp_cancel_adding_invoice 5430 5430 0 0.00 5 107971 1038 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 11809 362 0 0
sp_cancel_customer_reserve 538427 538427 0 0.00 1 25981 74 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 2028 82 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 3420 92 0 0
sp_cancel_supplier_invoice 6093 6084 9 0.14 3 109401 1078 0 9
sp_cancel_supplier_order 389 389 0 0.00 9 1260405 129285 0 0
sp_cancel_write_off 9310 9310 0 0.00 1 8939 117 0 0
sp_client_order 20543 20543 0 0.00 4 37254 358 0 0
sp_customer_reserve 458012 458012 0 0.00 0 85375 178 0 0
sp_fill_shopping_cart 31543 31543 0 0.00 1 12818 46 0 0
sp_fill_shopping_cart_clo_res 458010 458010 0 0.00 0 17701 15 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 38995 345 0 0
sp_kill_cost_storno 15826 15826 0 0.00 0 5549 63 0 0
sp_kill_qstorno_ret_qs2qd 1092637 1092637 0 0.00 0 109394 40 0 0
sp_kill_qty_storno 576408 576408 0 0.00 0 109395 87 0 0
sp_lock_selected_doc 20725 20725 0 0.00 0 9827 6 0 0
sp_make_cost_storno 27892 27892 0 0.00 0 13356 55 0 0
sp_multiply_rows_for_pdistr 27891 27891 0 0.00 0 7176 35 0 0
sp_multiply_rows_for_qdistr 20543 20543 0 0.00 0 37240 179 0 0
sp_pay_from_customer 7067 7067 0 0.00 4 14284 125 0 0
sp_pay_to_supplier 5852 5852 0 0.00 5 9361 118 0 0
sp_payment_common 12917 12917 0 0.00 1 14279 26 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 11799 339 0 0
sp_qd_handle_on_invoice_upd_sts 10377 10377 0 0.00 0 22712 433 0 0
sp_qd_handle_on_reserve_upd_sts 10019 10019 0 0.00 0 22275 62 0 0
sp_reserve_write_off 10023 10023 0 0.00 7 22286 194 0 0
sp_supplier_invoice 5538 5538 0 0.00 8 99228 3964 0 0
sp_supplier_order 5485 5485 0 0.00 6 33858 1514 0 0
srv_aggregate_perf_data 103 103 0 0.00 2771 173885 15284 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2759 118720 42572 0 0
srv_find_qd_qs_mism 1033898 1033898 0 0.00 0 14884 6 0 0
srv_make_invnt_saldo 435 435 0 0.00 42 123912 4185 0 0
srv_make_money_saldo 753 753 0 0.00 1 16929 663 0 0
srv_recalc_idx_stat 4 4 0 0.00 97771 469056 283915 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4953 4953 0 0.00 0 13399 173 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 6147 93 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 8738 21 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 6976 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 3444 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 463 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1367 16 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 16538 1912 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1159 3 0 0
t$perf-norm:sp_client_order 20543 20543 0 0.00 0 17902 19 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 2525 7 0 0
t$perf-norm:sp_pay_from_customer 7067 7067 0 0.00 0 10587 17 0 0
t$perf-norm:sp_pay_to_supplier 5852 5852 0 0.00 0 10782 12 0 0
t$perf-norm:sp_reserve_write_off 10023 10023 0 0.00 0 6915 11 0 0
t$perf-norm:sp_supplier_invoice 5538 5538 0 0.00 0 6812 18 0 0
t$perf-norm:sp_supplier_order 5485 5485 0 0.00 0 4194 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 209 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 81 4 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 4525 20 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 2509 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10114 9894 220 2.17 0 4329 16 0 220
v_all_customers 37372 37372 0 0.00 0 56 1 0 0
v_all_suppliers 30464 30464 0 0.00 0 19 1 0 0
v_all_wares 41086 41086 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2611 14 0 0
v_cancel_client_order 3604 3604 0 0.00 0 2998 6 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 2021 8 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 496 5 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 1565 6 0 0
v_cancel_supplier_invoice 836 818 18 2.15 0 348 13 0 18
v_cancel_supplier_order 778 778 0 0.00 0 1596 6 0 0
v_cancel_write_off 1966 1966 0 0.00 0 3999 14 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 1149 2 0 0
v_max_id_clo_res 909 909 0 0.00 0 12458 38 0 0
v_max_id_ord_sup 5523 5523 0 0.00 0 6971 5 0 0
v_max_non_paid_invoice 1619 1619 0 0.00 0 355 2 0 0
v_max_non_paid_realizn 3767 3767 0 0.00 0 11856 5 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 6351 10 0 0
v_min_id_clo_res 909 909 0 0.00 0 3227 9 0 0
v_min_id_ord_sup 5523 5523 0 0.00 0 7992 11 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 5030 14 0 0
v_min_non_paid_realizn 7066 7066 0 0.00 0 9575 20 0 0
v_our_firm 10312 10312 0 0.00 0 56 1 0 0
v_reserve_write_off 20038 20038 0 0.00 0 2623 6 0 0
x_lock_depdocs_on_canc_invoice 11105 11105 0 0.00 0 12497 121 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 3693 322 0 0
x_make_qstorno_1000_1200 5485 5485 0 0.00 2 33806 1422 0 0
x_make_qstorno_1200_2000 5538 5538 0 0.00 3 99220 3790 0 0
x_make_qstorno_2100_3300 458011 458011 0 0.00 0 85374 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2020 13:29:48 to 15-11-2020 13:29:52.

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
10:56:58 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:58:00 25390 8192000000 9716681152 10009161728 794709744 8.843 100 91 180 1 823888816 7496192 21682880
10:59:31 13801 8192000000 9750839216 10051608576 826416800 9.163 100 93 186 0 860327040 11558592 22351648
11:00:49 14946 8192000000 9772616640 10084171776 844560384 9.346 100 81 162 0 880317248 16880736 18876128
11:02:11 16401 8192000000 9857603456 10180882432 898122384 9.880 100 80 159 1 955083520 39730800 17230336
11:03:30 11395 8192000000 9915212768 10255519744 941016688 10.303 100 88 176 1 1021825312 61838704 18969920
11:04:44 7013 8192000000 9938219648 10287579136 980122256 10.685 100 77 153 1 1048715584 53647584 14945744
11:05:53 2549 8192000000 9991088128 10348642304 1009620048 10.972 100 79 158 3 1101942032 75947472 16374512
11:07:02 2451 8192000000 10007791536 10373058560 1030592064 11.174 100 90 179 2 1117534128 68560208 18381856
11:08:07 1674 8192000000 10017224576 10383921152 1058542480 11.443 100 92 184 1 1134058336 56708128 18807728
11:09:09 11582 8192000000 10017172624 10394017792 1063404160 11.489 100 72 146 3 1126551184 47596208 15550816
11:10:21 6327 8192000000 10010410096 10393681920 1068864272 11.541 100 49 97 0 1115212304 36124672 10223360
11:11:29 8007 8192000000 9989421312 10393726976 1073977072 11.590 100 28 56 1 1111846928 32240560 5629296
11:12:38 5346 8192000000 10005873088 10400153600 1078387312 11.632 100 41 84 2 1111157536 24592128 8178096
11:13:44 16513 8192000000 10000218624 10387824640 1086495600 11.709 100 59 116 3 1109483536 10670688 12317248
11:15:02 7242 8192000000 10025786560 10431725568 1095213488 11.792 100 43 86 3 1124626560 20843392 8569680
11:16:14 6551 8192000000 9998512544 10395930624 1068075664 11.534 98 39 76 2 1096448224 20429536 7943024
11:17:27 72360 8192000000 9965899056 10377289728 1006809264 10.944 93 25 50 2 1035565024 23400496 5355264
11:19:42 3521 8192000000 9968788784 10347966464 1047133408 11.333 100 43 84 2 1075507248 19151360 9222480
11:20:47 4459 8192000000 9952378128 10331410432 1021139168 11.083 99 58 115 3 1051262672 17168448 12955056
11:21:51 8936 8192000000 9925358928 10307870720 980159968 10.686 97 28 55 2 1011807840 26152992 5494880
11:23:06 20805 8192000000 9929521264 10335444992 1019178368 11.064 99 15 28 2 1040768464 18460800 3129296
11:24:27 8855 8192000000 9942729408 10346319872 1032520080 11.193 100 36 72 0 1062322128 21519808 8282240
11:25:36 10189 8192000000 9986936368 10391056384 1053553664 11.395 100 52 104 3 1093935792 28912336 11469792
11:26:47 38495 8192000000 9983116048 10386944000 1079556816 11.643 100 33 66 0 1106251328 19221680 7472832
11:28:29 9661 8192000000 10039260032 10438311936 1093021504 11.771 100 53 106 1 1147064256 43358752 10684000
11:29:43 37458 8192000000 10070876240 10492080128 1105550336 11.890 100 55 108 0 1173484144 56969872 10963936
11:31:31 11799 8192000000 10060715248 10472075264 1117880496 12.007 100 44 88 0 1162658592 36221680 8556416
11:32:48 17465 8192000000 10093991632 10502930432 1130924784 12.130 100 46 92 0 1195839952 54843296 10071872
11:34:09 15715 8192000000 10108551760 10522337280 1140662336 12.222 100 39 78 3 1217935712 68735024 8538352
11:35:38 15192 8192000000 10140722448 10551685120 1147742544 12.288 100 49 94 0 1236795712 78410576 10642592
11:37:01 9603 8192000000 10151134784 10562617344 1153693264 12.344 100 54 105 1 1263202944 97421808 12087872
11:38:16 7789 8192000000 10193731808 10607063040 1152404224 12.332 99 53 105 2 1289074736 124568784 12101728
11:39:28 15599 8192000000 10187654816 10604470272 1145958512 12.272 99 51 102 3 1290194160 132759200 11476448
11:41:01 3066 8192000000 10258397120 10666106880 1163230480 12.434 100 72 141 1 1355519184 175822224 16466480
11:42:11 6138 8192000000 10271054336 10678439936 1161398896 12.416 100 80 160 2 1372990032 192553472 19037664
11:43:28 4502 8192000000 10266919616 10674712576 1157978800 12.384 99 67 130 1 1357603520 184952256 14672464
11:44:41 4101 8192000000 10256277360 10674356224 1142704992 12.241 99 66 129 0 1361263072 203699616 14858464
11:45:52 11181 8192000000 10273110320 10703736832 1148428320 12.295 100 47 94 0 1386337616 225981424 11927872
11:47:09 9369 8192000000 10260423312 10705784832 1157404272 12.379 100 38 76 2 1387307744 220858784 9044688
11:48:28 8684 8192000000 10276974384 10704207872 1131692112 12.137 100 41 82 1 1390491744 248785936 10013696
11:49:48 2758 8192000000 10278429392 10681049088 1133099584 12.151 99 77 154 1 1377722240 226802192 17820464
11:50:57 3239 8192000000 10319299136 10715897856 1132432320 12.144 100 89 177 1 1419555152 266432736 20690096
11:52:04 15919 8192000000 10310934208 10751295488 1121111744 12.037 99 38 76 2 1407609424 276824384 9673296
11:53:23 13104 8192000000 10349383824 10772234240 1124163296 12.066 99 37 74 1 1443634544 309645392 9825856
11:54:42 9823 8192000000 10382363712 10802343936 1121854672 12.045 99 34 68 0 1471627040 341005680 8766688
11:55:59 5343 8192000000 10417417968 10833256448 1124411696 12.069 99 43 85 0 1511160048 376163104 10585248
11:57:07 11974 8192000000 10459409232 10886443008 1136911472 12.186 100 41 83 1 1553817632 406937216 9968944
11:58:34 23440 8192000000 10436758160 10897219584 1125177424 12.076 99 23 46 1 1525186272 393748944 6259904
12:00:01 16391 8192000000 10348215376 10864271360 1127479840 12.098 100 26 52 1 1456628160 322605648 6542672
12:01:19 80745 8192000000 10157207424 10595123200 1124948128 12.074 100 33 66 1 1286618624 154408496 7262000
12:03:42 73493 8192000000 10069382688 10534653952 1127815472 12.101 100 8 16 0 1203880208 74456368 1608368
12:06:06 92289 8192000000 10119718208 10555781120 1123989520 12.065 99 33 66 0 1176745536 45476880 7279136
12:09:06 108443 8192000000 10074651968 10563457024 1115468128 11.984 98 9 18 1 1167136048 49982816 1685104
12:12:08 41302 8192000000 10079048384 10495410176 1140419888 12.219 100 37 72 3 1172669840 23328576 8921376
12:13:56 67566 8192000000 10088384464 10621054976 1139728576 12.213 99 18 36 2 1226679984 82760416 4190992
12:16:04 108317 8192000000 10081189952 10523676672 1122471984 12.050 98 26 52 1 1147133040 19237200 5423856
12:19:09 9038 8192000000 10102880512 10546565120 1150156992 12.311 100 19 38 0 1190408768 35907600 4344176
12:20:19 40364 8192000000 10120883744 10555736064 1159277728 12.396 100 52 104 1 1228972160 58326144 11368288
12:22:04 28804 8192000000 10139213680 10582093824 1150349600 12.313 100 38 74 2 1225263792 67132480 7781712
12:23:38 58260 8192000000 10098631408 10567843840 1145517216 12.267 99 10 19 0 1189895984 42465712 1913056
12:25:37 34135 8192000000 10113241552 10579324928 1139067152 12.207 99 14 28 0 1213026960 70866656 3093152
12:27:59 84101 8192000000 10065278736 10608607232 1130711936 12.128 99 7 13 1 1204438800 72287184 1439680
12:30:23 46661 8192000000 10061246352 10515152896 1077336224 11.622 95 12 24 0 1127879888 48261072 2282592
12:32:10 51872 8192000000 10095953712 10564476928 1112785472 11.959 98 14 28 0 1182754880 67062784 2906624
12:34:04 36471 8192000000 10069458816 10508201984 1104462752 11.880 97 28 56 3 1147929376 37365600 6101024
12:35:41 33325 8192000000 10145070224 10593783808 1126213088 12.086 100 44 88 2 1242411808 106842560 9356160
12:37:14 74378 8192000000 10067928720 10566897664 1077584448 11.624 96 7 14 0 1148896704 69467760 1844496
12:39:34 46426 8192000000 10041082000 10480635904 1064945088 11.504 96 23 46 1 1111298688 41851328 4502272
12:41:22 88504 8192000000 10063573040 10562191360 1091507792 11.757 99 19 38 1 1158112736 62910768 3694176
12:44:09 112586 8192000000 10033628048 10460520448 1079068496 11.639 97 30 60 2 1118771216 33617120 6085600
12:47:02 62821 8192000000 10037046608 10505965568 1100053424 11.838 100 10 21 0 1142330816 40481152 1796240
12:49:05 85294 8192000000 10077944720 10531840000 1066153328 11.515 96 20 40 0 1130371568 59680432 4537808
12:51:46 49797 8192000000 10034431872 10498617344 1070125216 11.553 97 22 45 3 1141307776 66441936 4740624
12:53:38 60445 8192000000 10035896592 10472062976 1068090592 11.534 97 17 33 0 1110280960 38766480 3423888
12:55:39 30577 8192000000 10078837472 10504159232 1105289840 11.888 100 41 82 0 1148811296 34583488 8937968
12:57:13 62592 8192000000 10087784656 10593828864 1106506128 11.899 99 20 41 3 1209590752 98229712 4854912
12:59:16 37967 8192000000 10090188832 10517032960 1123163536 12.057 100 33 64 2 1162402448 32338128 6900784
13:00:55 79934 8192000000 10099319248 10630844416 1107543360 11.909 98 21 42 2 1235084896 122923776 4617760
13:03:16 78446 8192000000 10100332624 10563149824 1116984592 11.998 99 25 50 2 1168144480 46331312 4828576
13:05:46 94990 8192000000 10046450896 10531409920 1103452512 11.870 98 19 38 0 1154967072 47392512 4122048
13:08:59 50258 8192000000 10055868112 10511147008 1114862320 11.978 99 16 32 0 1144293520 26155536 3275664
13:10:50 76175 8192000000 10047183056 10523848704 1134818864 12.167 100 21 42 1 1177941648 38230576 4892208
13:13:12 68756 8192000000 10102003920 10570387456 1131147616 12.132 99 16 32 0 1180621232 46149424 3324192
13:15:24 30584 8192000000 10069783568 10524844032 1138969280 12.206 99 29 58 1 1169716896 25114032 5633584
13:17:00 27611 8192000000 10087524544 10534158336 1137884048 12.196 99 18 36 1 1184756288 43252288 3619952
13:18:32 44746 8192000000 10115082096 10560110592 1135531024 12.173 98 22 44 2 1188948608 48854656 4562928
13:20:21 118720 8192000000 10136698480 10601910272 1058795264 11.445 92 19 38 2 1163785216 100903408 4086544
13:23:20 31881 8192000000 10081577488 10515783680 1103966496 11.875 97 24 46 3 1133926880 25291152 4669232
13:24:53 73256 8192000000 10097396080 10601410560 1102087488 11.857 97 9 18 0 1150634592 46690448 1856656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2020 13:29:54 to 15-11-2020 13:30:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-11-2020 13:30:10 to 15-11-2020 13:30:12.

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          234094
MON$OLDEST_ACTIVE               235173
MON$OLDEST_SNAPSHOT             235173
MON$NEXT_TRANSACTION            235173
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                       2126976
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             795
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
--- end of diff output ---

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

15.11.20 13:30:13. 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-15 13:30:15 No severe PSQL-related problems occured
15.11.20 13:30:16. End of report.