Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 07:38
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16126684 kB
MemAvailable 31214120 kB
Buffers 0 kB
Cached 15568496 kB
SwapCached 2484 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.2
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 2022-06-22 07:37:18
DTS_END                         2022-06-22 07:37:18.1190
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/43378
TRN_ID                          219055
ATT_ID                          652
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: 8775.89 22391 1053107
customer order: creation 169.52 483 20342
customer order: refuse 13.85 377 1662
order to supplier: creation 43.93 1401 5271
order to supplier: removal 3.26 154754 391
invoice (draft): creation 44.63 3235 5355
invoice (draft): removal 49.33 902 5920
invoice accept: apply 40.73 24407 4887
invoice accept: cancel 43.88 1130 5266
customer reserve: creation 3774.88 232 452986
customer reserve: removal 4302.73 92 516327
realization accept: apply 81.48 286 9778
realization accept: cancel 81.38 151 9766
payment from customer: creation 57.39 172 6887
payment from customer: removal 5.53 122 664
payment to supplier: creation 47.79 218 5735
payment to supplier: removal 3.35 108 402
service: total inventory turnovers 4.28 3512 514
service: total monetary turnovers 7.12 360 854
service: aggregate perf. data 0.79 17103 95
service: refresh index statistics 0.04 238775 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 07:38:41 to 22-06-2022 07:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6134
TEST_TIME 2 5425
TEST_TIME 3 5639
TEST_TIME 4 5235
TEST_TIME 5 5292
TEST_TIME 6 5190
TEST_TIME 7 5254
TEST_TIME 8 5202
TEST_TIME 9 5167
TEST_TIME 10 5518
TEST_TIME 11 5756
TEST_TIME 12 5926
TEST_TIME 13 6493
TEST_TIME 14 6449
TEST_TIME 15 6850
TEST_TIME 16 7370
TEST_TIME 17 7443
TEST_TIME 18 7596
TEST_TIME 19 7534
TEST_TIME 20 7735
TEST_TIME 21 8039
TEST_TIME 22 8026
TEST_TIME 23 8106
TEST_TIME 24 8069
TEST_TIME 25 8230
TEST_TIME 26 8726
TEST_TIME 27 8840
TEST_TIME 28 9191
TEST_TIME 29 9816
TEST_TIME 30 10348
TEST_TIME 31 10921
TEST_TIME 32 10960
TEST_TIME 33 11117
TEST_TIME 34 11092
TEST_TIME 35 10884
TEST_TIME 36 10688
TEST_TIME 37 10449
TEST_TIME 38 10343
TEST_TIME 39 10390
TEST_TIME 40 10225
TEST_TIME 41 10110
TEST_TIME 42 9974
TEST_TIME 43 9951
TEST_TIME 44 9903
TEST_TIME 45 9892
TEST_TIME 46 9805
TEST_TIME 47 9624
TEST_TIME 48 9631
TEST_TIME 49 9489
TEST_TIME 50 9412
TEST_TIME 51 9393
TEST_TIME 52 9383
TEST_TIME 53 9298
TEST_TIME 54 9252
TEST_TIME 55 9169
TEST_TIME 56 9117
TEST_TIME 57 9106
TEST_TIME 58 9047
TEST_TIME 59 9059
TEST_TIME 60 9047
TEST_TIME 61 9051
TEST_TIME 62 9089
TEST_TIME 63 9152
TEST_TIME 64 9132
TEST_TIME 65 9228
TEST_TIME 66 9275
TEST_TIME 67 9248
TEST_TIME 68 9232
TEST_TIME 69 9360
TEST_TIME 70 9333
TEST_TIME 71 9272
TEST_TIME 72 9197
TEST_TIME 73 9223
TEST_TIME 74 9258
TEST_TIME 75 9161
TEST_TIME 76 9115
TEST_TIME 77 9027
TEST_TIME 78 8972
TEST_TIME 79 9032
TEST_TIME 80 9029
TEST_TIME 81 8988
TEST_TIME 82 8985
TEST_TIME 83 8938
TEST_TIME 84 8946
TEST_TIME 85 8974
TEST_TIME 86 8965
TEST_TIME 87 8962
TEST_TIME 88 9062
TEST_TIME 89 9195
TEST_TIME 90 9179
TEST_TIME 91 9183
TEST_TIME 92 9097
TEST_TIME 93 9086
TEST_TIME 94 9039
TEST_TIME 95 9014
TEST_TIME 96 8982
TEST_TIME 97 9004
TEST_TIME 98 8970
TEST_TIME 99 8980
TEST_TIME 100 8975
TEST_TIME 101 8935
TEST_TIME 102 8901
TEST_TIME 103 8927
TEST_TIME 104 8995
TEST_TIME 105 9006
TEST_TIME 106 9090
TEST_TIME 107 9120
TEST_TIME 108 9093
TEST_TIME 109 9102
TEST_TIME 110 9054
TEST_TIME 111 9022
TEST_TIME 112 8973
TEST_TIME 113 8956
TEST_TIME 114 8929
TEST_TIME 115 8903
TEST_TIME 116 8961
TEST_TIME 117 8918
TEST_TIME 118 8889
TEST_TIME 119 8857
TEST_TIME 120 8796

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2022 07:38:45 to 22-06-2022 07:38:48.

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 1471517 1471517 0 0.00 0 28839 13 0 0
doc_list_biud 1471509 1471509 0 0.00 0 33844 38 0 0
sp_add_invoice_to_stock 4999 4887 112 2.24 55 174903 24407 0 112
sp_cancel_adding_invoice 5266 5266 0 0.00 5 69836 1131 0 0
sp_cancel_client_order 1662 1662 0 0.00 5 8471 378 0 0
sp_cancel_customer_reserve 516327 516327 0 0.00 1 33872 93 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 4 7824 122 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 1858 108 0 0
sp_cancel_supplier_invoice 5933 5920 13 0.21 3 16114 902 0 13
sp_cancel_supplier_order 391 391 0 0.00 10 1456503 154754 0 0
sp_cancel_write_off 9766 9766 0 0.00 2 23803 151 0 0
sp_client_order 20342 20342 0 0.00 6 24130 484 0 0
sp_customer_reserve 452986 452986 0 0.00 1 43804 233 0 0
sp_fill_shopping_cart 30947 30947 0 0.00 1 21935 82 0 0
sp_fill_shopping_cart_clo_res 452946 452946 0 0.00 0 13856 20 0 0
sp_get_clo_for_invoice 4843 4843 0 0.00 0 26545 351 0 0
sp_kill_cost_storno 16098 16098 0 0.00 0 28829 70 0 0
sp_kill_qstorno_ret_qs2qd 1048731 1048731 0 0.00 0 33040 40 0 0
sp_kill_qty_storno 553950 553950 0 0.00 0 33842 93 0 0
sp_lock_selected_doc 20154 20154 0 0.00 0 6346 7 0 0
sp_make_cost_storno 27240 27240 0 0.00 0 13945 87 0 0
sp_multiply_rows_for_pdistr 27240 27240 0 0.00 0 8331 39 0 0
sp_multiply_rows_for_qdistr 20341 20341 0 0.00 0 10913 188 0 0
sp_pay_from_customer 6887 6887 0 0.00 5 18477 172 0 0
sp_pay_to_supplier 5735 5735 0 0.00 7 18116 219 0 0
sp_payment_common 12622 12622 0 0.00 2 6016 45 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 8458 307 0 0
sp_qd_handle_on_invoice_upd_sts 10107 10107 0 0.00 0 31602 504 0 0
sp_qd_handle_on_reserve_upd_sts 9777 9777 0 0.00 0 26694 66 0 0
sp_reserve_write_off 9778 9778 0 0.00 8 26738 286 0 0
sp_supplier_invoice 5355 5355 0 0.00 8 78300 3236 0 0
sp_supplier_order 5271 5271 0 0.00 8 94869 1402 0 0
srv_aggregate_perf_data 95 95 0 0.00 2375 120246 17103 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 866 136289 37221 0 0
srv_find_qd_qs_mism 1005909 1005909 0 0.00 0 14725 9 0 0
srv_make_invnt_saldo 514 514 0 0.00 44 91449 3512 0 0
srv_make_money_saldo 854 854 0 0.00 1 12777 360 0 0
srv_recalc_idx_stat 5 5 0 0.00 56136 405682 238775 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 1 16084 834 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 2 5957 398 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 4727 29 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3628 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 507 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1671 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1158 32 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 88778 6569 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1710 17 0 0
t$perf-norm:sp_client_order 20342 20342 0 0.00 0 11724 32 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 5618 20 0 0
t$perf-norm:sp_pay_from_customer 6887 6887 0 0.00 0 15314 21 0 0
t$perf-norm:sp_pay_to_supplier 5735 5735 0 0.00 0 5346 20 0 0
t$perf-norm:sp_reserve_write_off 9778 9778 0 0.00 0 15318 24 0 0
t$perf-norm:sp_supplier_invoice 5355 5355 0 0.00 0 6854 33 0 0
t$perf-norm:sp_supplier_order 5271 5271 0 0.00 0 11074 30 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 374 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 283 9 0 0
t$perf-norm:srv_make_invnt_saldo 514 514 0 0.00 0 1966 17 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 765 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 178 76 0 0
v_add_invoice_to_stock 9904 9680 224 2.26 0 13482 23 0 224
v_all_customers 36856 36856 0 0.00 0 224 3 0 0
v_all_suppliers 29836 29836 0 0.00 0 226 4 0 0
v_all_wares 40672 40672 0 0.00 0 226 4 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 524 12 0 0
v_cancel_client_order 3324 3324 0 0.00 0 4564 10 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1764 10 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 2207 11 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1721 15 0 0
v_cancel_supplier_invoice 894 868 26 2.90 0 598 20 0 26
v_cancel_supplier_order 782 782 0 0.00 0 616 10 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3820 23 0 0
v_max_id_clo_ord 5267 5267 0 0.00 0 1240 5 0 0
v_max_id_clo_res 964 964 0 0.00 0 1423 11 0 0
v_max_id_ord_sup 5343 5343 0 0.00 0 4951 7 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 66 7 0 0
v_max_non_paid_realizn 3653 3653 0 0.00 0 4523 10 0 0
v_min_id_clo_ord 5267 5267 0 0.00 0 2922 9 0 0
v_min_id_clo_res 964 964 0 0.00 0 5324 18 0 0
v_min_id_ord_sup 5343 5343 0 0.00 0 2235 6 0 0
v_min_non_paid_invoice 5735 5735 0 0.00 0 6013 19 0 0
v_min_non_paid_realizn 6887 6887 0 0.00 0 4947 16 0 0
v_our_firm 10282 10282 0 0.00 0 50 4 0 0
v_reserve_write_off 19552 19552 0 0.00 0 9941 12 0 0
x_lock_depdocs_on_canc_invoice 10752 10752 0 0.00 0 18605 119 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 9757 354 0 0
x_make_qstorno_1000_1200 5271 5271 0 0.00 3 91900 1237 0 0
x_make_qstorno_1200_2000 5355 5355 0 0.00 4 78289 3008 0 0
x_make_qstorno_2100_3300 452986 452986 0 0.00 0 40189 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 07:38:49 to 22-06-2022 07:38:53.

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:22 3 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:23 25238 8192000000 9716178592 10016743424 791850832 8.814 100 85 170 0 820350528 7800528 20699168
05:09:55 26862 8192000000 9769802912 10082996224 837447968 9.274 100 83 166 0 870298016 12663328 20186720
05:11:28 19776 8192000000 9818543744 10143604736 877160448 9.671 100 85 170 0 926788384 30007984 19619952
05:12:50 12381 8192000000 9897326656 10233389056 936122016 10.255 100 89 178 0 999966320 44825120 19019184
05:14:09 5004 8192000000 9935663968 10280546304 967435296 10.562 100 94 185 1 1048513424 61587840 19490288
05:15:17 2852 8192000000 9987551568 10340327424 994361440 10.824 100 92 184 0 1101819520 88852912 18605168
05:16:22 1644 8192000000 10001089344 10362814464 1018424592 11.057 100 90 170 3 1117841760 82181040 17236128
05:17:26 1427 8192000000 10016061888 10384986112 1040826256 11.273 100 89 174 1 1129810432 70478128 18506048
05:18:29 912 8192000000 10020581936 10394066944 1055840864 11.417 100 91 177 0 1137103776 63760336 17502576
05:19:32 1075 8192000000 10049668752 10429517824 1061589296 11.472 100 98 188 2 1166614160 85341760 19683104
05:20:34 1234 8192000000 10047841232 10426523648 1068772384 11.540 100 93 180 1 1169322192 82340304 18209504
05:21:36 1092 8192000000 10022157504 10405212160 1077494448 11.624 100 83 155 2 1141764720 48477952 15792320
05:22:38 1608 8192000000 10053544384 10443902976 1078748512 11.636 100 81 156 2 1181544576 86875808 15920256
05:23:41 1201 8192000000 10048923936 10433720320 1085868528 11.703 100 95 188 0 1171149216 65667024 19613664
05:24:43 1306 8192000000 10050862768 10438709248 1089055920 11.734 100 99 182 1 1171867760 64535408 18276432
05:25:46 1361 8192000000 10042570464 10431512576 1092067360 11.762 100 96 181 3 1165665472 54761296 18836816
05:26:48 1310 8192000000 10046892784 10435186688 1076827248 11.617 100 95 183 3 1163900608 68593824 18479536
05:27:50 1283 8192000000 10034712352 10420649984 1080836592 11.655 100 94 184 2 1154193568 54316720 19040256
05:28:53 3761 8192000000 10052084064 10445352960 1086564016 11.710 100 80 152 0 1169815424 67893392 15358016
05:29:59 1220 8192000000 10043684800 10432692224 1069270816 11.545 100 82 159 4 1163803680 78878304 15654560
05:31:01 1149 8192000000 9994654896 10375319552 1060467008 11.461 99 96 187 3 1122229840 40486192 21276640
05:32:03 1321 8192000000 10019766848 10398760960 1062426272 11.480 100 98 194 0 1137329968 56059472 18844224
05:33:05 5033 8192000000 10001767136 10387435520 1053996336 11.399 99 69 130 2 1120522768 53215152 13311280
05:34:11 989 8192000000 9987785728 10366959616 1029782336 11.166 99 93 168 1 1112056384 64565712 17708336
05:35:13 1296 8192000000 9974974368 10348605440 1032286912 11.190 100 81 150 1 1095246256 46279216 16680128
05:36:15 8057 8192000000 9961197440 10341289984 1004095088 10.918 96 61 111 0 1070583824 53609888 12878848
05:37:24 14598 8192000000 9993777968 10369150976 1050065216 11.361 100 68 120 1 1111725232 49479888 12180128
05:38:49 19317 8192000000 10007657920 10399805440 1065007920 11.504 100 63 120 3 1126100864 49599392 11493552
05:40:15 2692 8192000000 10028234352 10412150784 1084473552 11.690 100 83 155 3 1154586080 54614352 15498176
05:41:22 2540 8192000000 10105822704 10488844288 1096662944 11.806 100 88 166 0 1220970864 107574032 16733888
05:42:26 7194 8192000000 10144683024 10540326912 1112141200 11.953 100 79 156 0 1256010720 125559696 18309824
05:43:38 7152 8192000000 10147268976 10552344576 1123426096 12.059 100 56 109 0 1258418944 123370000 11622848
05:44:52 861 8192000000 10195535920 10585722880 1134132592 12.160 100 89 163 1 1302516336 152031568 16352176
05:45:55 5953 8192000000 10194832304 10596311040 1128202000 12.104 99 68 128 1 1309933360 168202704 13528656
05:47:07 2438 8192000000 10241701872 10648522752 1142955744 12.243 100 88 169 0 1352838368 191857328 18025296
05:48:13 4249 8192000000 10274158800 10683867136 1147603568 12.287 100 63 120 1 1382353696 221067616 13682512
05:49:19 870 8192000000 10315781040 10727596032 1160938800 12.412 100 91 178 0 1426578016 244799952 20839264
05:50:23 1076 8192000000 10333096432 10737475584 1162273648 12.425 100 94 181 0 1445650944 262325248 21052048
05:51:27 1825 8192000000 10373658784 10775597056 1166023296 12.460 100 93 183 3 1479756784 291471776 22261712
05:52:35 1123 8192000000 10358913536 10805276672 1171614256 12.512 100 98 191 0 1465250784 270455072 23181456
05:53:38 1075 8192000000 10355699776 10786525184 1164795008 12.448 100 91 181 0 1463882672 279004128 20083536
05:54:41 1952 8192000000 10396327792 10799157248 1179270272 12.583 100 97 189 0 1498135776 298247968 20617536
05:55:45 1057 8192000000 10424759216 10840100864 1172460576 12.520 99 95 184 0 1534127696 340285488 21381632
05:56:49 1016 8192000000 10461356624 10896588800 1188379424 12.668 100 97 194 1 1566830576 354632928 23818224
05:57:52 1101 8192000000 10490340160 10925756416 1187140288 12.657 100 97 192 0 1595989648 386393296 22456064
05:58:55 4396 8192000000 10492394640 10912251904 1179958416 12.590 99 69 136 0 1592702160 396445648 16298096
06:00:03 1659 8192000000 10505110000 10941124608 1173894288 12.533 100 72 142 0 1618634704 426844448 17895968
06:01:06 909 8192000000 10557785040 10969186304 1175679776 12.550 100 81 156 2 1664904880 469800784 19424320
06:02:09 6154 8192000000 10556841984 10982166528 1159479824 12.398 98 63 125 0 1653631216 477834368 16317024
06:03:27 15485 8192000000 10493725536 10991820800 1166024704 12.460 100 44 88 1 1629288048 451963872 11299472
06:04:52 21500 8192000000 10470336496 10961424384 1140987616 12.225 99 29 56 1 1579178800 430352336 7838848
06:06:35 40938 8192000000 10322068048 10817310720 1125165360 12.076 99 18 36 0 1435711520 305927184 4618976
06:08:16 33038 8192000000 10134212176 10637131776 1133778464 12.157 100 14 28 0 1270087248 132781568 3527216
06:10:18 77450 8192000000 10088115328 10540703744 1109648768 11.929 98 14 27 0 1168598992 56333792 2616432
06:12:36 58408 8192000000 10091962800 10537406464 1135986272 12.178 100 39 67 1 1190083280 46761216 7335792
06:14:36 37732 8192000000 10117331840 10565914624 1137930272 12.196 100 39 76 1 1207129344 61700384 7498688
06:16:22 47214 8192000000 10067436464 10566868992 1125617776 12.080 99 12 24 0 1195757488 67286736 2852976
06:18:12 88856 8192000000 10109013184 10570670080 1132543936 12.145 99 18 33 2 1196791136 60556768 3690432
06:21:58 37574 8192000000 10109942048 10594816000 1120473376 12.031 98 19 37 1 1199982224 75328816 4180032
06:23:41 92689 8192000000 10063764336 10534891520 1093543472 11.776 96 21 42 0 1140358048 42257168 4557408
06:26:35 28626 8192000000 10049260320 10503557120 1111956128 11.951 99 14 28 1 1148258224 33517856 2784240
06:28:04 37298 8192000000 10081283888 10560671744 1129870544 12.120 100 45 90 3 1202031168 62662016 9498608
06:30:14 35725 8192000000 10062098448 10505650176 1108916064 11.922 98 13 26 0 1152805168 40876448 3012656
06:31:50 127096 8192000000 10062198320 10544103424 1096443856 11.804 97 12 24 0 1169328400 70208784 2675760
06:35:00 41242 8192000000 10058941232 10531700736 1134732944 12.166 100 15 24 0 1172471936 35665616 2073376
06:36:41 60649 8192000000 10086821264 10545291264 1114952592 11.979 98 29 58 0 1155105376 34206464 5946320
06:38:42 47635 8192000000 10139198912 10600325120 1150352016 12.313 100 34 68 2 1254996208 97613072 7031120
06:40:31 61395 8192000000 10109835136 10553028608 1117170832 12.000 97 33 66 0 1183047376 59391648 6484896
06:42:35 73376 8192000000 10087462960 10570588160 1080185552 11.649 94 28 56 1 1141491824 54764880 6541392
06:44:51 15160 8192000000 10104623840 10540933120 1116082576 11.990 99 41 82 0 1192282432 68206496 7993360
06:46:06 46061 8192000000 10083420256 10553241600 1111055936 11.942 99 36 72 2 1215816960 97568720 7192304
06:48:16 5158 8192000000 10114983888 10525495296 1129041952 12.112 100 88 164 2 1205178416 59191360 16945104
06:49:22 89893 8192000000 10082169376 10572464128 1115065696 11.980 99 17 34 0 1239347984 120386720 3895568
06:51:58 51238 8192000000 10060768704 10590466048 1118958176 12.017 100 3 6 0 1172299232 52625472 715584
06:53:51 59684 8192000000 10051634528 10517209088 1104127696 11.877 98 8 16 0 1148691440 42976800 1586944
06:55:56 110129 8192000000 10079522384 10528497664 1066484896 11.519 95 19 36 1 1119106240 48786672 3834672
06:58:47 34430 8192000000 10081882640 10539118592 1110724256 11.939 98 28 54 0 1184745120 67164608 6856256
07:00:26 20522 8192000000 10121216176 10560077824 1126810496 12.091 100 37 73 0 1217559024 83080160 7668368
07:01:53 17829 8192000000 10138247712 10564722688 1120875696 12.035 99 40 80 1 1240760208 110484224 9400288
07:03:13 19394 8192000000 10140549712 10583896064 1108701584 11.920 98 45 88 1 1227271104 108576992 9992528
07:04:37 45353 8192000000 10177028032 10711367680 1117150016 12.000 99 30 60 0 1294299360 169974960 7174384
07:06:42 53942 8192000000 10100822304 10557149184 1119676480 12.024 99 17 34 1 1172715072 49830384 3208208
07:08:39 118710 8192000000 10053863696 10529886208 1130190368 12.123 99 23 46 0 1166012880 31630320 4192192
07:11:43 50177 8192000000 10083739392 10530189312 1111331328 11.945 97 8 17 0 1141110704 28177776 1601600
07:13:50 25192 8192000000 10118269008 10553561088 1146138752 12.273 100 25 49 1 1200651840 49011264 5501824
07:15:18 40510 8192000000 10106194208 10555084800 1146481984 12.276 100 40 78 0 1202750336 48627856 7640496
07:17:00 47992 8192000000 10139721104 10582249472 1131710592 12.137 98 24 48 1 1186944880 50589328 4644960
07:19:03 43498 8192000000 10172662768 10607919104 1139754080 12.213 99 33 62 0 1230291040 84405280 6131680
07:20:47 60577 8192000000 10121967648 10564468736 1148804080 12.298 100 19 38 0 1263390464 110161728 4424656
07:22:58 136289 8192000000 10083382032 10585595904 1108136112 11.915 96 13 26 1 1159065488 48193872 2735504
07:26:16 126420 8192000000 10059093008 10523344896 1105260976 11.888 97 14 27 1 1134798544 26073216 3464352
07:29:31 14593 8192000000 10078478928 10511450112 1146050576 12.272 100 46 87 0 1179519872 25541856 7927440
07:30:46 62379 8192000000 10107711856 10578538496 1099841760 11.836 96 15 30 0 1188962208 85546384 3574064
07:32:49 80549 8192000000 10063287728 10584326144 1114424848 11.974 99 22 44 0 1227799872 108166912 5208112
07:35:14 54246 8192000000 10047418976 10502119424 1112977984 11.961 98 14 28 1 1141328448 25791200 2559264
07:37:08 20121 8192000000 8922683184 9138958336 87275328 1.054 8 3 6 0 106585984 18804912 505744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-06-2022 07:38:55 to 22-06-2022 07:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 07:39:12 to 22-06-2022 07:39:14.

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          219911
MON$OLDEST_ACTIVE               219912
MON$OLDEST_SNAPSHOT             219912
MON$NEXT_TRANSACTION            219912
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                       2047712
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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'

22.06.22 07:39:15. 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
2022-06-22 07:39:17 No severe PSQL-related problems occured
22.06.22 07:39:19. End of report.