Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2021 10:28
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16886248 kB
MemAvailable 31569888 kB
Buffers 0 kB
Cached 14779876 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-16 10:26:54
DTS_END                         2021-03-16 10:26:54.0360
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/43016
TRN_ID                          215546
ATT_ID                          619
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: 8671.45 27837 1040574
customer order: creation 156.31 435 18757
customer order: refuse 13.53 365 1623
order to supplier: creation 41.28 1545 4954
order to supplier: removal 3.11 141559 373
invoice (draft): creation 41.13 4056 4935
invoice (draft): removal 51.70 1007 6204
invoice accept: apply 37.88 20429 4546
invoice accept: cancel 46.73 933 5608
customer reserve: creation 3445.28 202 413433
customer reserve: removal 4553.41 76 546409
realization accept: apply 76.51 212 9181
realization accept: cancel 87.46 132 10495
payment from customer: creation 55.58 143 6670
payment from customer: removal 4.67 92 560
payment to supplier: creation 44.68 151 5361
payment to supplier: removal 2.73 96 327
service: total inventory turnovers 3.62 4106 434
service: total monetary turnovers 4.99 808 599
service: aggregate perf. data 0.85 16164 102
service: refresh index statistics 0.03 364236 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-03-2021 10:28:24 to 16-03-2021 10:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1642
TEST_TIME 2 3403
TEST_TIME 3 4516
TEST_TIME 4 4850
TEST_TIME 5 4626
TEST_TIME 6 4748
TEST_TIME 7 5130
TEST_TIME 8 5115
TEST_TIME 9 5145
TEST_TIME 10 5140
TEST_TIME 11 5623
TEST_TIME 12 5719
TEST_TIME 13 5731
TEST_TIME 14 6003
TEST_TIME 15 6270
TEST_TIME 16 6397
TEST_TIME 17 6856
TEST_TIME 18 7087
TEST_TIME 19 7072
TEST_TIME 20 7474
TEST_TIME 21 7379
TEST_TIME 22 7406
TEST_TIME 23 7932
TEST_TIME 24 7897
TEST_TIME 25 7861
TEST_TIME 26 8001
TEST_TIME 27 7898
TEST_TIME 28 8134
TEST_TIME 29 8066
TEST_TIME 30 8165
TEST_TIME 31 8816
TEST_TIME 32 9740
TEST_TIME 33 10311
TEST_TIME 34 11007
TEST_TIME 35 10785
TEST_TIME 36 10636
TEST_TIME 37 10456
TEST_TIME 38 10370
TEST_TIME 39 10310
TEST_TIME 40 10187
TEST_TIME 41 10053
TEST_TIME 42 9905
TEST_TIME 43 9880
TEST_TIME 44 9863
TEST_TIME 45 9737
TEST_TIME 46 9661
TEST_TIME 47 9541
TEST_TIME 48 9580
TEST_TIME 49 9433
TEST_TIME 50 9283
TEST_TIME 51 9249
TEST_TIME 52 9153
TEST_TIME 53 9103
TEST_TIME 54 9111
TEST_TIME 55 9047
TEST_TIME 56 8969
TEST_TIME 57 8862
TEST_TIME 58 8965
TEST_TIME 59 8999
TEST_TIME 60 9087
TEST_TIME 61 9070
TEST_TIME 62 9135
TEST_TIME 63 9099
TEST_TIME 64 9165
TEST_TIME 65 9168
TEST_TIME 66 9158
TEST_TIME 67 9117
TEST_TIME 68 9145
TEST_TIME 69 9173
TEST_TIME 70 9182
TEST_TIME 71 9200
TEST_TIME 72 9273
TEST_TIME 73 9293
TEST_TIME 74 9214
TEST_TIME 75 9096
TEST_TIME 76 9052
TEST_TIME 77 9002
TEST_TIME 78 8947
TEST_TIME 79 8903
TEST_TIME 80 8892
TEST_TIME 81 8835
TEST_TIME 82 8828
TEST_TIME 83 8796
TEST_TIME 84 8757
TEST_TIME 85 8729
TEST_TIME 86 8727
TEST_TIME 87 8781
TEST_TIME 88 8843
TEST_TIME 89 8807
TEST_TIME 90 8770
TEST_TIME 91 8796
TEST_TIME 92 8865
TEST_TIME 93 8904
TEST_TIME 94 8878
TEST_TIME 95 8828
TEST_TIME 96 8810
TEST_TIME 97 8770
TEST_TIME 98 8722
TEST_TIME 99 8705
TEST_TIME 100 8650
TEST_TIME 101 8689
TEST_TIME 102 8688
TEST_TIME 103 8679
TEST_TIME 104 8660
TEST_TIME 105 8800
TEST_TIME 106 8816
TEST_TIME 107 8813
TEST_TIME 108 8911
TEST_TIME 109 8950
TEST_TIME 110 8927
TEST_TIME 111 8879
TEST_TIME 112 8822
TEST_TIME 113 8762
TEST_TIME 114 8726
TEST_TIME 115 8736
TEST_TIME 116 8687
TEST_TIME 117 8673
TEST_TIME 118 8665
TEST_TIME 119 8688
TEST_TIME 120 8675

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-03-2021 10:28:28 to 16-03-2021 10:28:31.

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 1424267 1424267 0 0.00 0 19368 7 0 0
doc_list_biud 1424262 1424262 0 0.00 0 105103 37 0 0
sp_add_invoice_to_stock 4652 4546 106 2.27 36 237739 20429 0 106
sp_cancel_adding_invoice 5608 5608 0 0.00 5 86442 934 0 0
sp_cancel_client_order 1623 1623 0 0.00 5 8676 365 0 0
sp_cancel_customer_reserve 546409 546409 0 0.00 1 68722 76 0 0
sp_cancel_pay_from_customer 560 560 0 0.00 3 6719 92 0 0
sp_cancel_pay_to_supplier 327 327 0 0.00 3 3586 97 0 0
sp_cancel_supplier_invoice 6216 6204 12 0.19 2 105108 1008 0 12
sp_cancel_supplier_order 373 373 0 0.00 12 1221895 141559 0 0
sp_cancel_write_off 10495 10495 0 0.00 1 8169 132 0 0
sp_client_order 18757 18757 0 0.00 4 34633 435 0 0
sp_customer_reserve 413433 413433 0 0.00 0 55465 203 0 0
sp_fill_shopping_cart 28633 28633 0 0.00 1 43702 55 0 0
sp_fill_shopping_cart_clo_res 413429 413429 0 0.00 0 28967 21 0 0
sp_get_clo_for_invoice 4540 4540 0 0.00 0 36264 411 0 0
sp_kill_cost_storno 16990 16990 0 0.00 0 6836 67 0 0
sp_kill_qstorno_ret_qs2qd 1109890 1109890 0 0.00 0 105102 41 0 0
sp_kill_qty_storno 584430 584430 0 0.00 0 105103 88 0 0
sp_lock_selected_doc 18911 18911 0 0.00 0 7828 5 0 0
sp_make_cost_storno 25750 25750 0 0.00 0 19355 70 0 0
sp_multiply_rows_for_pdistr 25749 25749 0 0.00 0 8309 36 0 0
sp_multiply_rows_for_qdistr 18755 18755 0 0.00 0 30652 237 0 0
sp_pay_from_customer 6670 6670 0 0.00 4 26295 143 0 0
sp_pay_to_supplier 5361 5361 0 0.00 5 18779 151 0 0
sp_payment_common 12031 12031 0 0.00 1 12010 28 0 0
sp_qd_handle_on_cancel_clo 1623 1623 0 0.00 0 8670 333 0 0
sp_qd_handle_on_invoice_upd_sts 10145 10145 0 0.00 0 24464 449 0 0
sp_qd_handle_on_reserve_upd_sts 9181 9181 0 0.00 0 8412 56 0 0
sp_reserve_write_off 9181 9181 0 0.00 7 20911 212 0 0
sp_supplier_invoice 4935 4935 0 0.00 7 109774 4056 0 0
sp_supplier_order 4954 4954 0 0.00 6 58923 1546 0 0
srv_aggregate_perf_data 102 102 0 0.00 2669 134859 16164 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1073 139064 46953 0 0
srv_find_qd_qs_mism 997895 997895 0 0.00 0 18879 6 0 0
srv_make_invnt_saldo 434 434 0 0.00 33 114412 4107 0 0
srv_make_money_saldo 599 599 0 0.00 1 15490 808 0 0
srv_recalc_idx_stat 3 3 0 0.00 273907 492303 364237 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4546 4546 0 0.00 0 11954 228 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 5588 104 0 0
t$perf-norm:sp_cancel_client_order 1623 1623 0 0.00 0 4113 20 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 1814 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 560 560 0 0.00 0 4267 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 327 327 0 0.00 0 4660 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1264 21 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 21170 1873 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 5793 10 0 0
t$perf-norm:sp_client_order 18757 18757 0 0.00 0 6430 24 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 6505 18 0 0
t$perf-norm:sp_pay_from_customer 6670 6670 0 0.00 0 7389 10 0 0
t$perf-norm:sp_pay_to_supplier 5361 5361 0 0.00 0 10554 18 0 0
t$perf-norm:sp_reserve_write_off 9181 9181 0 0.00 0 9238 16 0 0
t$perf-norm:sp_supplier_invoice 4935 4935 0 0.00 0 7059 24 0 0
t$perf-norm:sp_supplier_order 4954 4954 0 0.00 0 7670 22 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 2218 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 6887 46 0 0
t$perf-norm:srv_make_money_saldo 599 599 0 0.00 0 961 10 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9282 9070 212 2.28 0 12343 24 0 212
v_all_customers 34878 34878 0 0.00 0 25 1 0 0
v_all_suppliers 27372 27372 0 0.00 0 15 1 0 0
v_all_wares 37504 37504 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 5994 19 0 0
v_cancel_client_order 3246 3246 0 0.00 0 2676 6 0 0
v_cancel_customer_prepayment 1120 1120 0 0.00 0 4042 8 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 6538 17 0 0
v_cancel_payment_to_supplier 654 654 0 0.00 0 2077 8 0 0
v_cancel_supplier_invoice 740 716 24 3.24 0 1572 17 0 24
v_cancel_supplier_order 746 746 0 0.00 0 2094 9 0 0
v_cancel_write_off 1898 1898 0 0.00 0 1714 15 0 0
v_max_id_clo_ord 4951 4951 0 0.00 0 437 1 0 0
v_max_id_clo_res 955 955 0 0.00 0 3005 18 0 0
v_max_id_ord_sup 4929 4929 0 0.00 0 7862 8 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 1247 3 0 0
v_max_non_paid_realizn 3274 3274 0 0.00 0 8341 8 0 0
v_min_id_clo_ord 4951 4951 0 0.00 0 7206 11 0 0
v_min_id_clo_res 955 955 0 0.00 0 4914 22 0 0
v_min_id_ord_sup 4929 4929 0 0.00 0 7527 8 0 0
v_min_non_paid_invoice 5361 5361 0 0.00 0 8471 19 0 0
v_min_non_paid_realizn 6670 6670 0 0.00 0 10358 20 0 0
v_our_firm 9416 9416 0 0.00 0 25 1 0 0
v_reserve_write_off 18362 18362 0 0.00 0 3837 8 0 0
x_lock_depdocs_on_canc_invoice 11454 11454 0 0.00 0 15762 118 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 17458 472 0 0
x_make_qstorno_1000_1200 4954 4954 0 0.00 2 58913 1448 0 0
x_make_qstorno_1200_2000 4935 4935 0 0.00 3 103353 3837 0 0
x_make_qstorno_2100_3300 413433 413433 0 0.00 0 55464 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-03-2021 10:28:32 to 16-03-2021 10:28:36.

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
07:56:57 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:57:58 20028 8192000000 9704455168 9997357056 783139776 8.725 100 87 173 1 811601440 6949328 21512336
07:59:24 20584 8192000000 9754335600 10062458880 838268656 9.282 100 81 162 0 867947632 10440064 19238912
08:00:58 18795 8192000000 9798794496 10118295552 872726960 9.627 100 84 167 0 908049280 15998816 19323504
08:02:28 17659 8192000000 9840933776 10173202432 909630768 9.994 100 85 170 0 956951184 28222112 19098304
08:03:53 12909 8192000000 9896811232 10241912832 947795056 10.369 100 72 144 0 1016080000 52698880 15586064
08:05:12 3891 8192000000 9930054976 10284482560 990212128 10.784 100 66 132 1 1045066832 41556816 13297888
08:06:19 5795 8192000000 9999083680 10356678656 1015304656 11.027 100 87 174 1 1107617168 75094672 17217840
08:07:27 2816 8192000000 10031965760 10396561408 1038606912 11.251 100 86 172 1 1144327808 87981472 17739424
08:08:32 1794 8192000000 10025958656 10397069312 1057858432 11.436 100 93 187 1 1147522688 70813712 18850544
08:09:36 2086 8192000000 10049063344 10420731904 1068180784 11.535 100 91 182 1 1161310736 73958016 19171936
08:10:40 4520 8192000000 10030300400 10410704896 1070391536 11.556 100 69 139 1 1144306560 59578480 14336544
08:11:47 5053 8192000000 9975719536 10371215360 1078728832 11.635 100 46 95 2 1103886688 14312384 10845472
08:12:52 6507 8192000000 9991573760 10393833472 1086367888 11.708 100 44 89 0 1122998112 27411248 9218976
08:14:01 63901 8192000000 9995861760 10406117376 1088187040 11.725 100 22 44 1 1109409328 16716624 4505664
08:16:05 6332 8192000000 9994951328 10398461952 1094597968 11.786 100 37 74 1 1119022000 15954240 8469792
08:17:14 6996 8192000000 10001425888 10408402944 1096094896 11.801 100 29 58 1 1121219792 18736016 6388880
08:18:21 5616 8192000000 9999737152 10389987328 1073058048 11.581 98 34 64 3 1098039872 17606640 7375184
08:19:28 51415 8192000000 9974882656 10384760832 1034342560 11.210 96 45 88 1 1068444992 23808848 10293584
08:21:23 10451 8192000000 9920472512 10308083712 977128656 10.656 94 23 46 1 996349520 14043040 5177824
08:22:35 10463 8192000000 9931993152 10319151104 987901904 10.761 96 37 74 0 1018827696 23132864 7792928
08:23:47 5238 8192000000 9942626976 10323877888 1020107952 11.073 100 44 87 0 1049952032 20180544 9663536
08:24:56 36742 8192000000 9953901136 10346889216 1039645344 11.261 100 39 78 1 1068822976 20647424 8530208
08:26:40 4720 8192000000 9976478960 10372235264 1073200336 11.583 100 29 58 1 1095691488 15678032 6813120
08:27:48 19174 8192000000 10016241392 10413551616 1083155600 11.678 100 42 83 0 1121739568 29730608 8853360
08:29:11 13047 8192000000 10038904112 10435518464 1101245888 11.849 100 51 102 0 1150338256 38376896 10715472
08:30:31 58198 8192000000 10070587088 10486599680 1113705968 11.967 100 33 66 1 1185809456 65434800 6668688
08:32:32 7643 8192000000 10100484000 10511106048 1122698192 12.052 100 61 118 2 1220615248 85093824 12823232
08:33:44 3018 8192000000 10114506736 10523160576 1134377136 12.163 100 56 112 1 1221276464 75190064 11709264
08:34:50 6759 8192000000 10162950624 10574290944 1142311856 12.237 100 68 130 2 1276454208 119477664 14664688
08:36:03 10818 8192000000 10181080016 10595753984 1157258352 12.378 100 53 104 0 1291991040 123506080 11226608
08:37:16 11291 8192000000 10190015888 10610991104 1158816800 12.392 100 50 101 1 1304450896 134189728 11444368
08:38:35 3127 8192000000 10230202496 10638745600 1171378944 12.510 100 63 126 1 1336573536 151433920 13760672
08:39:44 3454 8192000000 10257856048 10665533440 1168768832 12.485 100 76 151 2 1360576512 174937584 16870096
08:40:56 7202 8192000000 10267624336 10684698624 1164685520 12.447 99 63 125 0 1356308048 178193248 13429280
08:42:08 4273 8192000000 10293273776 10714251264 1178568096 12.577 100 70 140 1 1396883360 202450816 15864448
08:43:17 1665 8192000000 10314084576 10718203904 1167830384 12.477 100 79 152 1 1416778160 230903600 18044176
08:44:27 1239 8192000000 10306694928 10721779712 1168594912 12.484 100 71 140 0 1407145712 223383440 15167360
08:45:36 6542 8192000000 10299830112 10717515776 1171579152 12.512 100 47 94 0 1401789952 219340896 10869904
08:46:47 4970 8192000000 10288275568 10717364224 1156340592 12.369 99 36 72 1 1395303696 229968176 8994928
08:47:57 1069 8192000000 10382425664 10789224448 1170430800 12.501 100 90 180 3 1487451024 296521872 20498352
08:49:04 2946 8192000000 10403835104 10823008256 1163346960 12.435 100 95 191 1 1496500704 311338432 21815312
08:50:14 4975 8192000000 10378248144 10788343808 1145160240 12.264 99 59 117 1 1461747904 302639952 13947712
08:51:24 7759 8192000000 10445245424 10877259776 1173448432 12.529 100 49 97 2 1552002752 366807488 11746832
08:52:38 3792 8192000000 10514288560 10933837824 1166725248 12.466 100 56 112 1 1612976528 432577824 13673456
08:54:21 20311 8192000000 10558664032 11011932160 1144874064 12.261 98 46 93 1 1648248704 491489824 11884816
08:55:47 10514 8192000000 10594182480 11024838656 1148277648 12.293 100 43 86 0 1687340896 527320752 11742496
08:57:04 21443 8192000000 10587777936 11065167872 1133304208 12.152 99 38 75 3 1686382656 542842400 10236048
08:58:40 23818 8192000000 10333253824 10910732288 1144986992 12.262 100 19 38 1 1490119344 340266320 4866032
09:00:07 38429 8192000000 10107785760 10643746816 1136868544 12.186 99 11 22 1 1302105728 162952464 2284720
09:01:59 89112 8192000000 10078900000 10527793152 1114980080 11.980 99 19 37 1 1160814288 42387696 3446512
09:04:32 57822 8192000000 10060778736 10548191232 1105674496 11.891 99 16 30 2 1170898880 61742336 3482048
09:06:32 105398 8192000000 10074405952 10522226688 1111387568 11.946 98 11 22 0 1154889440 41313712 2188160
09:09:19 66872 8192000000 10060709424 10523631616 1118028864 12.008 99 11 22 1 1157186912 36889008 2269040
09:11:39 84143 8192000000 10074125392 10524069888 1101624496 11.853 97 22 45 0 1146478672 39908336 4945840
09:14:15 107326 8192000000 10038773280 10500648960 1108619200 11.919 99 32 64 0 1141333712 25649040 7065472
09:17:25 21831 8192000000 10065433024 10514718720 1118788800 12.016 99 29 58 0 1173907600 48969920 6148880
09:18:59 62908 8192000000 10112681680 10573045760 1134875424 12.167 100 40 79 0 1205455568 60792880 9787264
09:21:15 101946 8192000000 10160485568 10624053248 1131436512 12.135 98 24 48 1 1235672224 98973456 5262256
09:23:57 71844 8192000000 10142067648 10673270784 1139529664 12.211 100 16 32 1 1277116704 134158752 3428288
09:26:29 71361 8192000000 10088812480 10590932992 1126165424 12.085 98 13 26 0 1196417600 67554160 2698016
09:28:46 125877 8192000000 10049625328 10514886656 1115544672 11.985 97 11 22 0 1155901760 38197200 2159888
09:32:01 61154 8192000000 10039108336 10494967808 1058685312 11.444 93 22 42 1 1089557312 26044688 4827312
09:34:04 15396 8192000000 10097721104 10527137792 1132454976 12.144 100 45 90 0 1175162048 33835760 8871312
09:35:22 55428 8192000000 10077802272 10526699520 1108520080 11.918 98 7 12 1 1155064416 45086224 1458112
09:37:21 89207 8192000000 10123644016 10608332800 1101167312 11.849 97 6 12 0 1156823440 54507168 1148960
09:39:51 129923 8192000000 10054574992 10509262848 1117025680 11.999 99 19 38 1 1173578032 52445616 4106736
09:43:14 93190 8192000000 10106156832 10594828288 1145666512 12.269 100 13 24 1 1208273856 60188000 2419344
09:46:41 23547 8192000000 10040531280 10476163072 1086464320 11.709 95 12 22 0 1109199936 19715456 3020160
09:48:10 85143 8192000000 10051149888 10497576960 1093424480 11.775 97 10 20 0 1148787328 53714240 1648608
09:50:37 30768 8192000000 10085274592 10527498240 1088540208 11.729 96 25 50 0 1142659504 48463744 5655552
09:52:12 72297 8192000000 10109541072 10556788736 1092566416 11.767 97 21 42 1 1174871216 77904288 4400512
09:54:33 55560 8192000000 10071543536 10551611392 1109988736 11.932 98 14 28 1 1174250000 61109696 3151568
09:56:29 53747 8192000000 10155613696 10615480320 1112856496 11.959 97 16 32 0 1209645488 93505808 3283184
09:58:23 70565 8192000000 10077577776 10529796096 1098554832 11.824 97 16 32 0 1176178384 74733152 2890400
10:00:36 16028 8192000000 10123647520 10539933696 1110606192 11.938 99 54 108 1 1185924800 64725424 10593184
10:01:54 72847 8192000000 10067000320 10529030144 1094751408 11.788 98 42 84 0 1174692144 71585152 8355584
10:04:40 70153 8192000000 10096066256 10576023552 1124579392 12.070 100 11 22 1 1202906304 75990960 2335952
10:06:51 59609 8192000000 10091755216 10571038720 1077409248 11.623 96 21 42 2 1178158656 96056000 4693408
10:08:51 56390 8192000000 10161970880 10609754112 1125002784 12.074 99 27 54 3 1213245984 81695312 6547888
10:10:57 97237 8192000000 10088759520 10576084992 1123290944 12.058 99 13 26 2 1228284640 101883168 3110528
10:13:36 37073 8192000000 10146029728 10626699264 1146078176 12.273 100 14 28 1 1250417488 100989952 3349360
10:15:19 95341 8192000000 10071792832 10511380480 1114619984 11.976 98 8 16 0 1162037024 45936720 1480320
10:17:56 139064 8192000000 10093833504 10548269056 1127674928 12.099 99 31 61 1 1189896384 55507648 6713808
10:21:15 70833 8192000000 10089716400 10570395648 1143656960 12.250 100 15 29 2 1206951168 59961376 3332832
10:23:40 68820 8192000000 10099491920 10564300800 1129262528 12.114 99 12 24 1 1178927248 47064304 2600416
10:25:53 74700 8192000000 9259654128 9609113600 385085344 4.489 34 4 8 0 425583664 39869616 628704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-03-2021 10:28:38 to 16-03-2021 10:28:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2021 10:28:54 to 16-03-2021 10:28:56.

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          214718
MON$OLDEST_ACTIVE               215961
MON$OLDEST_SNAPSHOT             215961
MON$NEXT_TRANSACTION            215961
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                       2099904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 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.2387 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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Tue Mar 16 10:27:12 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Tue Mar 16 10:27:26 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Tue Mar 16 10:27:28 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
--- end of diff output ---

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

16.03.21 10:28:57. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-16 10:28:59 No severe PSQL-related problems occured
16.03.21 10:29:00. End of report.