Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.06.2022 13:39
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 16106872 kB
MemAvailable 31374592 kB
Buffers 0 kB
Cached 15629740 kB
SwapCached 2444 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-02 13:37:19
DTS_END                         2022-06-02 13:37:19.4680
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/49968
TRN_ID                          208556
ATT_ID                          647
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: 8807.32 23488 1056878
customer order: creation 162.07 491 19448
customer order: refuse 13.93 484 1672
order to supplier: creation 42.81 1351 5137
order to supplier: removal 3.33 186293 400
invoice (draft): creation 41.83 3513 5019
invoice (draft): removal 49.98 1062 5997
invoice accept: apply 39.78 29783 4774
invoice accept: cancel 45.36 1411 5443
customer reserve: creation 3679.70 285 441564
customer reserve: removal 4450.62 109 534074
realization accept: apply 77.34 291 9281
realization accept: cancel 80.21 178 9625
payment from customer: creation 54.61 169 6553
payment from customer: removal 5.47 123 656
payment to supplier: creation 44.94 213 5393
payment to supplier: removal 3.28 122 393
service: total inventory turnovers 3.56 4902 427
service: total monetary turnovers 7.59 377 911
service: aggregate perf. data 0.88 18580 106
service: refresh index statistics 0.04 220021 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-06-2022 13:39:28 to 02-06-2022 13:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10585
TEST_TIME 2 6641
TEST_TIME 3 6458
TEST_TIME 4 6732
TEST_TIME 5 7431
TEST_TIME 6 6809
TEST_TIME 7 6804
TEST_TIME 8 7006
TEST_TIME 9 6916
TEST_TIME 10 6930
TEST_TIME 11 6885
TEST_TIME 12 6870
TEST_TIME 13 6884
TEST_TIME 14 6807
TEST_TIME 15 6800
TEST_TIME 16 6802
TEST_TIME 17 6829
TEST_TIME 18 6930
TEST_TIME 19 7280
TEST_TIME 20 7395
TEST_TIME 21 7491
TEST_TIME 22 7822
TEST_TIME 23 7905
TEST_TIME 24 8107
TEST_TIME 25 8235
TEST_TIME 26 8351
TEST_TIME 27 8409
TEST_TIME 28 8479
TEST_TIME 29 8746
TEST_TIME 30 8882
TEST_TIME 31 9155
TEST_TIME 32 9470
TEST_TIME 33 9657
TEST_TIME 34 9986
TEST_TIME 35 10083
TEST_TIME 36 10614
TEST_TIME 37 10847
TEST_TIME 38 10768
TEST_TIME 39 10503
TEST_TIME 40 10459
TEST_TIME 41 10368
TEST_TIME 42 10266
TEST_TIME 43 10286
TEST_TIME 44 10206
TEST_TIME 45 10077
TEST_TIME 46 9950
TEST_TIME 47 9832
TEST_TIME 48 9807
TEST_TIME 49 9762
TEST_TIME 50 9622
TEST_TIME 51 9557
TEST_TIME 52 9423
TEST_TIME 53 9261
TEST_TIME 54 9455
TEST_TIME 55 9376
TEST_TIME 56 9350
TEST_TIME 57 9276
TEST_TIME 58 9214
TEST_TIME 59 9163
TEST_TIME 60 9041
TEST_TIME 61 8991
TEST_TIME 62 8941
TEST_TIME 63 9048
TEST_TIME 64 9050
TEST_TIME 65 9042
TEST_TIME 66 9124
TEST_TIME 67 9124
TEST_TIME 68 9098
TEST_TIME 69 9174
TEST_TIME 70 9109
TEST_TIME 71 9103
TEST_TIME 72 9185
TEST_TIME 73 9182
TEST_TIME 74 9183
TEST_TIME 75 9153
TEST_TIME 76 9177
TEST_TIME 77 9225
TEST_TIME 78 9169
TEST_TIME 79 9072
TEST_TIME 80 9093
TEST_TIME 81 9029
TEST_TIME 82 8990
TEST_TIME 83 8941
TEST_TIME 84 8934
TEST_TIME 85 8882
TEST_TIME 86 8919
TEST_TIME 87 8838
TEST_TIME 88 8871
TEST_TIME 89 8838
TEST_TIME 90 8827
TEST_TIME 91 8789
TEST_TIME 92 8786
TEST_TIME 93 8797
TEST_TIME 94 8792
TEST_TIME 95 8784
TEST_TIME 96 8787
TEST_TIME 97 8953
TEST_TIME 98 8957
TEST_TIME 99 8922
TEST_TIME 100 8934
TEST_TIME 101 8882
TEST_TIME 102 8868
TEST_TIME 103 8842
TEST_TIME 104 8829
TEST_TIME 105 8777
TEST_TIME 106 8753
TEST_TIME 107 8718
TEST_TIME 108 8672
TEST_TIME 109 8701
TEST_TIME 110 8716
TEST_TIME 111 8688
TEST_TIME 112 8662
TEST_TIME 113 8634
TEST_TIME 114 8621
TEST_TIME 115 8587
TEST_TIME 116 8690
TEST_TIME 117 8820
TEST_TIME 118 8834
TEST_TIME 119 8876
TEST_TIME 120 8839

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-06-2022 13:39:32 to 02-06-2022 13:39:36.

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 1461800 1461800 0 0.00 0 27880 15 0 0
doc_list_biud 1461788 1461788 0 0.00 0 36175 47 0 0
sp_add_invoice_to_stock 4873 4774 99 2.03 48 277461 29783 0 99
sp_cancel_adding_invoice 5443 5443 0 0.00 7 84545 1411 0 0
sp_cancel_client_order 1672 1672 0 0.00 7 11906 485 0 0
sp_cancel_customer_reserve 534074 534074 0 0.00 1 32655 110 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 2018 124 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 4 1487 122 0 0
sp_cancel_supplier_invoice 6004 5997 7 0.11 4 36295 1063 0 7
sp_cancel_supplier_order 400 400 0 0.00 21 1684580 186294 0 0
sp_cancel_write_off 9625 9625 0 0.00 2 25318 179 0 0
sp_client_order 19448 19448 0 0.00 6 31827 491 0 0
sp_customer_reserve 441564 441564 0 0.00 0 49236 285 0 0
sp_fill_shopping_cart 29597 29597 0 0.00 2 45310 96 0 0
sp_fill_shopping_cart_clo_res 441536 441536 0 0.00 0 12677 21 0 0
sp_get_clo_for_invoice 4730 4730 0 0.00 0 24751 347 0 0
sp_kill_cost_storno 16117 16117 0 0.00 0 27864 84 0 0
sp_kill_qstorno_ret_qs2qd 1084170 1084170 0 0.00 0 36170 48 0 0
sp_kill_qty_storno 571216 571216 0 0.00 0 36174 110 0 0
sp_lock_selected_doc 19424 19424 0 0.00 0 27892 9 0 0
sp_make_cost_storno 25957 25957 0 0.00 0 9569 79 0 0
sp_multiply_rows_for_pdistr 25953 25953 0 0.00 0 8397 37 0 0
sp_multiply_rows_for_qdistr 19448 19448 0 0.00 0 15675 191 0 0
sp_pay_from_customer 6553 6553 0 0.00 5 19702 170 0 0
sp_pay_to_supplier 5393 5393 0 0.00 6 25883 214 0 0
sp_payment_common 11943 11943 0 0.00 2 5508 45 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 1 11394 395 0 0
sp_qd_handle_on_invoice_upd_sts 10170 10170 0 0.00 0 33296 619 0 0
sp_qd_handle_on_reserve_upd_sts 9278 9278 0 0.00 0 8893 61 0 0
sp_reserve_write_off 9281 9281 0 0.00 8 20490 291 0 0
sp_supplier_invoice 5019 5019 0 0.00 11 91287 3514 0 0
sp_supplier_order 5137 5137 0 0.00 7 41003 1352 0 0
srv_aggregate_perf_data 106 106 0 0.00 2018 175441 18580 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 847 120512 33312 0 0
srv_find_qd_qs_mism 1010018 1010018 0 0.00 0 10906 12 0 0
srv_make_invnt_saldo 427 427 0 0.00 100 90108 4902 0 0
srv_make_money_saldo 911 911 0 0.00 2 10356 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 51719 412209 220022 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4779 4779 0 0.00 1 17497 1093 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 15830 630 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 919 28 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 1725 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 307 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 477 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 909 42 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 114741 8195 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 1427 21 0 0
t$perf-norm:sp_client_order 19448 19448 0 0.00 0 13900 42 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 597 14 0 0
t$perf-norm:sp_pay_from_customer 6553 6553 0 0.00 0 8654 21 0 0
t$perf-norm:sp_pay_to_supplier 5394 5394 0 0.00 0 7960 22 0 0
t$perf-norm:sp_reserve_write_off 9281 9281 0 0.00 0 15473 25 0 0
t$perf-norm:sp_supplier_invoice 5019 5019 0 0.00 0 3684 34 0 0
t$perf-norm:sp_supplier_order 5138 5138 0 0.00 0 3606 35 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 485 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 34 3 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 652 16 0 0
t$perf-norm:srv_make_money_saldo 911 911 0 0.00 0 983 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 213 57 0 0
v_add_invoice_to_stock 9640 9442 198 2.05 0 25568 32 0 198
v_all_customers 35568 35568 0 0.00 0 78 4 0 0
v_all_suppliers 28126 28126 0 0.00 0 75 5 0 0
v_all_wares 38892 38892 0 0.00 0 152 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 25195 55 0 0
v_cancel_client_order 3344 3344 0 0.00 0 9107 15 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 608 11 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1641 13 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1271 13 0 0
v_cancel_supplier_invoice 730 716 14 1.91 0 1094 29 0 14
v_cancel_supplier_order 800 800 0 0.00 0 25492 61 0 0
v_cancel_write_off 1868 1868 0 0.00 0 4634 28 0 0
v_max_id_clo_ord 5137 5137 0 0.00 0 2393 6 0 0
v_max_id_clo_res 978 978 0 0.00 0 3736 24 0 0
v_max_id_ord_sup 5014 5014 0 0.00 0 2922 6 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 56 8 0 0
v_max_non_paid_realizn 3336 3336 0 0.00 0 376 7 0 0
v_min_id_clo_ord 5137 5137 0 0.00 0 3013 9 0 0
v_min_id_clo_res 978 978 0 0.00 0 1972 11 0 0
v_min_id_ord_sup 5014 5014 0 0.00 0 7034 12 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 5348 18 0 0
v_min_non_paid_realizn 6550 6550 0 0.00 0 5502 12 0 0
v_our_firm 9752 9752 0 0.00 0 63 5 0 0
v_reserve_write_off 18554 18554 0 0.00 0 19657 15 0 0
x_lock_depdocs_on_canc_invoice 11082 11082 0 0.00 0 9874 128 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 16474 395 0 0
x_make_qstorno_1000_1200 5137 5137 0 0.00 3 40985 1167 0 0
x_make_qstorno_1200_2000 5019 5019 0 0.00 3 88278 3238 0 0
x_make_qstorno_2100_3300 441564 441564 0 0.00 0 49205 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 13:39:37 to 02-06-2022 13:39:41.

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
11:07:23 918 8192000000 8922919632 8978763776 7225024 0.088 3 2 4 0 7389056 91600 72432
11:08:25 35689 8192000000 9733866992 10034409472 807427552 8.971 100 89 178 0 837338176 7922176 21988448
11:10:06 32315 8192000000 9772674400 10082058240 835689232 9.256 100 87 172 0 869219856 13172992 20357632
11:11:40 19180 8192000000 9806831248 10129620992 860942208 9.510 100 74 148 0 904126640 25950944 17233488
11:13:06 14406 8192000000 9862314496 10198294528 904260496 9.941 100 77 152 0 965591088 44202016 17128576
11:14:23 3667 8192000000 9923586896 10268356608 956700576 10.457 100 86 168 0 1035099456 60531216 17867664
11:15:31 2239 8192000000 9976825472 10329223168 995405472 10.834 100 89 172 0 1081459712 68850416 17203824
11:16:36 945 8192000000 10020339632 10385080320 1015514096 11.029 100 95 187 0 1131906400 97376288 19016016
11:17:39 1141 8192000000 10024291344 10395070464 1036015168 11.226 100 93 185 3 1132898976 78127424 18756384
11:18:44 1335 8192000000 10043361200 10416955392 1048396416 11.345 100 98 186 1 1155727984 88559968 18771600
11:19:46 713 8192000000 10040985984 10415484928 1059557904 11.452 100 91 176 2 1150021680 72040912 18422864
11:20:48 1265 8192000000 10053816160 10435264512 1068441872 11.537 100 93 177 1 1167897984 80589840 18866272
11:21:53 3227 8192000000 10041964848 10421272576 1076182496 11.611 100 84 158 0 1151533936 59308720 16042720
11:22:58 1244 8192000000 10083316448 10469265408 1084815968 11.693 100 95 184 1 1191453216 88255312 18381936
11:24:01 1236 8192000000 10066359984 10451587072 1093352320 11.775 100 91 175 0 1182811872 71385072 18074480
11:25:04 6313 8192000000 10074937536 10469683200 1092817328 11.769 100 70 136 0 1195177776 88892784 13467664
11:26:11 4841 8192000000 10057104944 10454872064 1094431488 11.785 100 66 125 0 1183235680 76354560 12449632
11:27:18 1614 8192000000 10053718128 10439794688 1091948128 11.761 100 80 148 2 1164333792 58187360 14198304
11:28:21 1459 8192000000 10046652736 10434658304 1098248096 11.821 100 86 163 6 1162581776 48663152 15670528
11:29:23 1036 8192000000 10047100400 10438881280 1087422704 11.718 100 90 170 1 1158714032 52548560 18742768
11:30:26 1295 8192000000 10041189264 10426650624 1082337520 11.670 100 93 180 3 1155453136 54818864 18296752
11:31:28 1210 8192000000 10031726848 10416197632 1060754144 11.464 100 85 160 1 1150169136 71553168 17861824
11:32:32 1401 8192000000 10020929808 10397294592 1040511552 11.270 99 97 188 2 1132979056 74316048 18151456
11:33:34 1158 8192000000 10006715568 10381864960 1044482992 11.308 100 95 184 0 1120531552 57797728 18250832
11:34:36 932 8192000000 10035339040 10416689152 1047706288 11.339 99 90 174 1 1149612672 83433296 18473088
11:35:37 1457 8192000000 9987753712 10371809280 1045448944 11.317 100 73 144 3 1091853536 31885648 14518944
11:36:40 1494 8192000000 9985777648 10365767680 1035304576 11.220 100 78 149 0 1111033216 59185904 16542736
11:37:43 5823 8192000000 10000291776 10380136448 1052047232 11.380 100 72 130 1 1122836944 58669440 12120272
11:38:58 16899 8192000000 10005996688 10387320832 1055975952 11.418 100 65 117 0 1122157264 55474480 10706832
11:40:21 1142 8192000000 10031052176 10419920896 1067910320 11.532 100 56 108 1 1154491280 74829872 11751088
11:41:28 2515 8192000000 10075837488 10467270656 1092707440 11.768 100 73 138 0 1197920304 91777312 13435552
11:42:33 1130 8192000000 10110977936 10504404992 1113274048 11.963 100 95 184 1 1223848832 91845120 18729664
11:43:38 2874 8192000000 10119094352 10510397440 1121646320 12.043 100 84 166 1 1236722208 97610960 17464928
11:44:54 2341 8192000000 10202744336 10593644544 1137425024 12.191 100 88 175 0 1315022240 159176352 18420864
11:46:02 3093 8192000000 10170517776 10570502144 1137637280 12.193 100 85 164 2 1288999776 133821008 17541488
11:47:09 8686 8192000000 10233501856 10636546048 1148744320 12.298 100 70 138 1 1350580256 186204016 15631920
11:48:22 2026 8192000000 10230283776 10633297920 1160753712 12.410 100 80 152 0 1347956176 169813408 17389056
11:49:28 2140 8192000000 10288221728 10690052096 1170766176 12.504 100 82 155 1 1400751216 212683296 17301744
11:50:33 1810 8192000000 10322629712 10720858112 1178717792 12.578 100 88 169 2 1430941152 233387504 18835856
11:51:40 970 8192000000 10357951456 10762731520 1182865168 12.617 100 97 182 1 1467262336 264732720 19664448
11:52:46 2160 8192000000 10388442688 10792562688 1188691152 12.671 100 90 176 1 1500462336 290656432 21114752
11:53:51 833 8192000000 10427893040 10827862016 1178736496 12.578 100 95 187 1 1537573600 335599744 23237360
11:54:56 1370 8192000000 10474628976 10892369920 1188304352 12.668 100 97 190 0 1581775440 371531856 21939232
11:56:01 1378 8192000000 10486554656 10927976448 1182151616 12.610 100 96 189 1 1594600768 390967392 21481760
11:57:08 1258 8192000000 10484674160 10892439552 1184813520 12.635 100 92 175 1 1594722624 388967472 20941632
11:58:14 1251 8192000000 10515017648 10931331072 1189690096 12.680 100 88 168 0 1624501680 414622528 20189056
11:59:18 2529 8192000000 10513709712 10960113664 1194604992 12.726 100 90 180 0 1629241584 413562064 21074528
12:00:23 906 8192000000 10524072992 10931548160 1170882112 12.505 100 95 187 0 1629544400 436759632 21902656
12:01:26 1112 8192000000 10479149792 10919579648 1155870448 12.365 100 89 175 0 1587005904 409848240 21287216
12:02:31 2750 8192000000 10506990576 10955792384 1171159120 12.508 100 85 159 2 1613040864 422172656 19709088
12:03:37 4656 8192000000 10518955216 10951106560 1170320288 12.500 100 66 132 1 1623546128 436795616 16430224
12:04:48 6676 8192000000 10506191280 10988544000 1165531568 12.455 100 52 104 1 1622168848 442836240 13801040
12:06:04 915 8192000000 10506260576 10972049408 1177956176 12.571 100 91 175 0 1607377136 407961056 21459904
12:07:08 3290 8192000000 10513297424 10950660096 1165893760 12.458 100 84 159 1 1615604256 430270160 19440336
12:08:15 4918 8192000000 10443187008 10932400128 1172862752 12.524 100 64 124 0 1550603200 362024464 15715984
12:09:27 16900 8192000000 10430006496 10922782720 1173884192 12.533 100 43 86 0 1550201568 365279952 11037424
12:11:33 37973 8192000000 10327136240 10812571648 1165325584 12.453 100 26 52 1 1448573456 276600160 6647712
12:13:13 43298 8192000000 10191830080 10667597824 1148584480 12.296 100 17 34 1 1290699616 137694080 4421056
12:14:58 70025 8192000000 10132999344 10580422656 1137080208 12.188 99 23 44 1 1206022560 63587904 5354448
12:17:48 25593 8192000000 10115496544 10559270912 1148816400 12.298 99 46 90 0 1209897360 51916976 9163984
12:19:18 65216 8192000000 10119782800 10607386624 1157371888 12.379 100 19 38 0 1245868832 84342608 4154336
12:21:23 85936 8192000000 10078167760 10534739968 1118220192 12.010 97 14 28 0 1148546880 27845872 2480816
12:23:50 56724 8192000000 10104596016 10558373888 1130273008 12.124 98 20 40 1 1178305536 43114848 4917680
12:25:54 55769 8192000000 10097371024 10571546624 1126334944 12.087 98 11 22 0 1184739104 55979312 2424848
12:28:12 110844 8192000000 10123485824 10602262528 1136639232 12.184 99 22 44 1 1225582176 83586512 5356432
12:31:27 54090 8192000000 10078370688 10621853696 1129683392 12.118 99 11 22 1 1192504384 60004928 2816064
12:33:23 28937 8192000000 10098851120 10564460544 1138640560 12.203 100 51 100 1 1190630224 41534272 10455392
12:34:52 45445 8192000000 10097057600 10556534784 1122140080 12.047 98 22 42 2 1173594448 47039728 4414640
12:36:44 92515 8192000000 10128174944 10616647680 1116823728 11.997 98 14 28 1 1211467104 91342688 3300688
12:39:17 14076 8192000000 10119251584 10612060160 1133261664 12.152 100 16 33 0 1231784976 94455712 4067600
12:40:32 59077 8192000000 10089003328 10587672576 1129836528 12.120 99 13 24 1 1177709536 44825056 3047952
12:42:34 56253 8192000000 10148919696 10614808576 1141955376 12.234 99 17 34 0 1235960640 90533632 3471632
12:44:31 3731 8192000000 10077488784 10515705856 1152952944 12.337 100 88 158 4 1174943632 6536032 15454656
12:45:35 23819 8192000000 10135468096 10580676608 1133631712 12.156 99 48 96 0 1204819456 61977408 9210336
12:47:02 14457 8192000000 10072167376 10505670656 1098046256 11.819 97 38 73 0 1142295232 36959872 7289104
12:48:19 21306 8192000000 10158442048 10597695488 1112480368 11.956 99 62 124 0 1241322592 116486032 12356192
12:49:40 49921 8192000000 10077370352 10539151360 1105729712 11.892 99 24 48 1 1172535264 62000672 4804880
12:51:36 14219 8192000000 10070481472 10511785984 1113345920 11.964 100 38 76 0 1160465808 39460464 7659424
12:52:53 58159 8192000000 10100384560 10546823168 1107215120 11.906 98 37 73 0 1193246960 78570624 7461216
12:55:00 120512 8192000000 10075777232 10520215552 1097893120 11.818 98 28 56 0 1171562384 66779776 6889488
12:58:07 35552 8192000000 10053354192 10524909568 1092657808 11.768 98 25 50 1 1159951072 61369056 5924208
12:59:43 68264 8192000000 10082900416 10535202816 1104628144 11.882 98 19 38 0 1185263936 76666656 3969136
13:01:52 76792 8192000000 10110585552 10574323712 1104874736 11.884 98 12 24 0 1199065632 92009472 2181424
13:04:09 41704 8192000000 10067974816 10533408768 1111756624 11.949 99 20 40 1 1163592528 47795664 4040240
13:05:51 86933 8192000000 10044464400 10500550656 1079178704 11.640 96 7 14 1 1103096768 22378992 1539072
13:08:21 41761 8192000000 10125858720 10562060288 1097829744 11.817 97 30 60 1 1185749360 81506496 6413120
13:10:12 65429 8192000000 10182141072 10622504960 1133222976 12.152 99 29 58 1 1261200496 121666192 6311328
13:12:21 43704 8192000000 10192954672 10629197824 1126476048 12.088 99 40 80 3 1291851984 156048224 9327712
13:14:10 29579 8192000000 10162430928 10667880448 1131493712 12.135 100 43 86 2 1308434400 168225280 8715408
13:15:43 34162 8192000000 10112668128 10544058368 1134839760 12.167 99 52 104 1 1206619488 61489456 10290272
13:17:18 14325 8192000000 10179033136 10632335360 1153557600 12.343 100 53 106 0 1259092896 94608320 10926976
13:18:37 86896 8192000000 10108535760 10565173248 1139613568 12.212 99 61 118 2 1213588672 61349280 12625824
13:21:57 84634 8192000000 10073009152 10554273792 1143485888 12.248 100 8 16 0 1195000432 49862528 1652016
13:24:26 70352 8192000000 10105675152 10552950784 1097804224 11.817 96 11 22 0 1159203376 59281184 2117968
13:26:37 68658 8192000000 10119132128 10577760256 1131131104 12.132 99 24 46 1 1217210064 80353408 5725552
13:28:46 57020 8192000000 10140431408 10584367104 1140324192 12.219 99 32 63 1 1230759536 83122368 7312976
13:30:44 22576 8192000000 10207560768 10645819392 1145231488 12.265 99 48 94 1 1280368224 124245568 10891168
13:32:06 32074 8192000000 10259744720 10703011840 1131793600 12.138 98 35 70 1 1312692032 172595696 8302736
13:34:06 108037 8192000000 10101534256 10577870848 1125911984 12.083 99 10 20 0 1200008320 72243008 1853328
13:36:56 50823 8192000000 9002512352 9234722816 158049136 1.892 14 2 4 0 168790480 10512656 228688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-06-2022 13:39:43 to 02-06-2022 13:39:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-06-2022 13:40:00 to 02-06-2022 13:40:02.

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          208457
MON$OLDEST_ACTIVE               209345
MON$OLDEST_SNAPSHOT             209345
MON$NEXT_TRANSACTION            209345
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                       2072992
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             752
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

02.06.22 13:40:03. 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-02 13:40:05 No severe PSQL-related problems occured
02.06.22 13:40:07. End of report.