Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2022 04: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 15988572 kB
MemAvailable 30981560 kB
Buffers 20 kB
Cached 15701112 kB
SwapCached 2512 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-08-03 04:37:18
DTS_END                         2022-08-03 04:37:18.7910
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/60932
TRN_ID                          207863
ATT_ID                          646
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: 8642.31 19946 1037077
customer order: creation 156.43 502 18772
customer order: refuse 14.14 383 1697
order to supplier: creation 42.21 1436 5065
order to supplier: removal 3.38 150375 405
invoice (draft): creation 41.99 3379 5039
invoice (draft): removal 46.63 1010 5595
invoice accept: apply 40.18 27692 4822
invoice accept: cancel 42.38 1087 5086
customer reserve: creation 3777.18 261 453262
customer reserve: removal 4216.34 93 505961
realization accept: apply 76.78 278 9213
realization accept: cancel 65.69 178 7883
payment from customer: creation 53.66 182 6439
payment from customer: removal 5.26 109 631
payment to supplier: creation 45.29 228 5435
payment to supplier: removal 3.14 147 377
service: total inventory turnovers 3.84 3718 461
service: total monetary turnovers 6.86 344 823
service: aggregate perf. data 0.89 14656 107
service: refresh index statistics 0.03 192870 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2022 04:39:12 to 03-08-2022 04:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6727
TEST_TIME 2 5453
TEST_TIME 3 5150
TEST_TIME 4 5116
TEST_TIME 5 5285
TEST_TIME 6 5614
TEST_TIME 7 5705
TEST_TIME 8 5941
TEST_TIME 9 6286
TEST_TIME 10 6187
TEST_TIME 11 6182
TEST_TIME 12 6326
TEST_TIME 13 6968
TEST_TIME 14 7129
TEST_TIME 15 7254
TEST_TIME 16 7325
TEST_TIME 17 7333
TEST_TIME 18 7435
TEST_TIME 19 7513
TEST_TIME 20 7729
TEST_TIME 21 8057
TEST_TIME 22 8639
TEST_TIME 23 8790
TEST_TIME 24 8922
TEST_TIME 25 9087
TEST_TIME 26 9190
TEST_TIME 27 9651
TEST_TIME 28 10255
TEST_TIME 29 11192
TEST_TIME 30 10941
TEST_TIME 31 10717
TEST_TIME 32 10603
TEST_TIME 33 10513
TEST_TIME 34 10461
TEST_TIME 35 10329
TEST_TIME 36 10237
TEST_TIME 37 10066
TEST_TIME 38 10067
TEST_TIME 39 9966
TEST_TIME 40 9782
TEST_TIME 41 9769
TEST_TIME 42 9706
TEST_TIME 43 9650
TEST_TIME 44 9540
TEST_TIME 45 9474
TEST_TIME 46 9366
TEST_TIME 47 9281
TEST_TIME 48 9183
TEST_TIME 49 9120
TEST_TIME 50 9074
TEST_TIME 51 8996
TEST_TIME 52 8926
TEST_TIME 53 8794
TEST_TIME 54 8745
TEST_TIME 55 8798
TEST_TIME 56 8781
TEST_TIME 57 8821
TEST_TIME 58 8782
TEST_TIME 59 8793
TEST_TIME 60 8938
TEST_TIME 61 8931
TEST_TIME 62 8983
TEST_TIME 63 8959
TEST_TIME 64 9008
TEST_TIME 65 9073
TEST_TIME 66 9076
TEST_TIME 67 9073
TEST_TIME 68 9118
TEST_TIME 69 9189
TEST_TIME 70 9140
TEST_TIME 71 9137
TEST_TIME 72 9098
TEST_TIME 73 9036
TEST_TIME 74 8957
TEST_TIME 75 8950
TEST_TIME 76 8930
TEST_TIME 77 8863
TEST_TIME 78 8826
TEST_TIME 79 8774
TEST_TIME 80 8717
TEST_TIME 81 8704
TEST_TIME 82 8821
TEST_TIME 83 8830
TEST_TIME 84 8793
TEST_TIME 85 8774
TEST_TIME 86 8838
TEST_TIME 87 8813
TEST_TIME 88 8930
TEST_TIME 89 8925
TEST_TIME 90 8901
TEST_TIME 91 8952
TEST_TIME 92 8987
TEST_TIME 93 8957
TEST_TIME 94 8978
TEST_TIME 95 8908
TEST_TIME 96 8846
TEST_TIME 97 8878
TEST_TIME 98 8871
TEST_TIME 99 8816
TEST_TIME 100 8780
TEST_TIME 101 8775
TEST_TIME 102 8767
TEST_TIME 103 8741
TEST_TIME 104 8747
TEST_TIME 105 8714
TEST_TIME 106 8755
TEST_TIME 107 8769
TEST_TIME 108 8723
TEST_TIME 109 8746
TEST_TIME 110 8754
TEST_TIME 111 8787
TEST_TIME 112 8808
TEST_TIME 113 8856
TEST_TIME 114 8839
TEST_TIME 115 8784
TEST_TIME 116 8740
TEST_TIME 117 8755
TEST_TIME 118 8725
TEST_TIME 119 8714
TEST_TIME 120 8665

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-08-2022 04:39:16 to 03-08-2022 04:39:19.

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 1452917 1452917 0 0.00 0 21924 13 0 0
doc_list_biud 1452907 1452907 0 0.00 0 56632 38 0 0
sp_add_invoice_to_stock 4951 4822 129 2.60 61 203456 27692 0 129
sp_cancel_adding_invoice 5086 5086 0 0.00 4 69897 1088 0 0
sp_cancel_client_order 1697 1697 0 0.00 5 7461 384 0 0
sp_cancel_customer_reserve 505961 505961 0 0.00 1 24505 94 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 5616 110 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 5462 147 0 0
sp_cancel_supplier_invoice 5605 5595 10 0.17 3 57003 1010 0 10
sp_cancel_supplier_order 405 405 0 0.00 18 1543187 150375 0 0
sp_cancel_write_off 7883 7883 0 0.00 2 10275 179 0 0
sp_client_order 18772 18772 0 0.00 5 20574 502 0 0
sp_customer_reserve 453262 453262 0 0.00 0 80995 261 0 0
sp_fill_shopping_cart 28866 28866 0 0.00 1 63702 88 0 0
sp_fill_shopping_cart_clo_res 453227 453227 0 0.00 0 22182 23 0 0
sp_get_clo_for_invoice 4775 4775 0 0.00 0 27748 357 0 0
sp_kill_cost_storno 13977 13977 0 0.00 0 6450 73 0 0
sp_kill_qstorno_ret_qs2qd 1025805 1025805 0 0.00 0 56629 42 0 0
sp_kill_qty_storno 540613 540613 0 0.00 0 56631 95 0 0
sp_lock_selected_doc 19435 19435 0 0.00 0 6505 8 0 0
sp_make_cost_storno 25862 25862 0 0.00 0 21868 87 0 0
sp_multiply_rows_for_pdistr 25861 25861 0 0.00 0 15181 38 0 0
sp_multiply_rows_for_qdistr 18772 18772 0 0.00 0 11037 192 0 0
sp_pay_from_customer 6439 6439 0 0.00 5 16785 182 0 0
sp_pay_to_supplier 5435 5435 0 0.00 6 20094 229 0 0
sp_payment_common 11874 11874 0 0.00 2 10873 46 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 7453 313 0 0
sp_qd_handle_on_invoice_upd_sts 9860 9860 0 0.00 0 18966 491 0 0
sp_qd_handle_on_reserve_upd_sts 9212 9212 0 0.00 0 9343 62 0 0
sp_reserve_write_off 9213 9213 0 0.00 8 19259 278 0 0
sp_supplier_invoice 5039 5039 0 0.00 11 96916 3380 0 0
sp_supplier_order 5065 5065 0 0.00 6 42100 1436 0 0
srv_aggregate_perf_data 107 107 0 0.00 2522 193157 14657 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 945 124203 37168 0 0
srv_find_qd_qs_mism 990335 990335 0 0.00 0 10355 10 0 0
srv_make_invnt_saldo 461 461 0 0.00 45 65699 3718 0 0
srv_make_money_saldo 823 823 0 0.00 1 8510 345 0 0
srv_recalc_idx_stat 4 4 0 0.00 77749 396977 192871 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4825 4825 0 0.00 1 12711 850 0 0
t$perf-norm:sp_cancel_adding_invoice 367 367 0 0.00 1 6848 377 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 7077 33 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 594 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 513 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2099 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 3956 43 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 108468 5795 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 5359 25 0 0
t$perf-norm:sp_client_order 18772 18772 0 0.00 0 10958 35 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 621 13 0 0
t$perf-norm:sp_pay_from_customer 6439 6439 0 0.00 0 9874 21 0 0
t$perf-norm:sp_pay_to_supplier 5436 5436 0 0.00 0 9419 20 0 0
t$perf-norm:sp_reserve_write_off 9213 9213 0 0.00 0 4628 22 0 0
t$perf-norm:sp_supplier_invoice 5039 5039 0 0.00 0 6564 32 0 0
t$perf-norm:sp_supplier_order 5066 5066 0 0.00 0 9141 31 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 623 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1669 30 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 365 12 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 339 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 207 63 0 0
v_add_invoice_to_stock 9804 9546 258 2.63 0 11005 30 0 258
v_all_customers 34012 34012 0 0.00 0 129 4 0 0
v_all_suppliers 28030 28030 0 0.00 0 67 4 0 0
v_all_wares 37538 37538 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 734 734 0 0.00 0 1354 15 0 0
v_cancel_client_order 3394 3394 0 0.00 0 6249 11 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 520 9 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2243 14 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3569 16 0 0
v_cancel_supplier_invoice 760 740 20 2.63 0 1591 27 0 20
v_cancel_supplier_order 810 810 0 0.00 0 937 10 0 0
v_cancel_write_off 1888 1888 0 0.00 0 867 22 0 0
v_max_id_clo_ord 5062 5062 0 0.00 0 826 5 0 0
v_max_id_clo_res 920 920 0 0.00 0 1109 12 0 0
v_max_id_ord_sup 5035 5035 0 0.00 0 6902 8 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 75 7 0 0
v_max_non_paid_realizn 3399 3399 0 0.00 0 1601 7 0 0
v_min_id_clo_ord 5062 5062 0 0.00 0 2519 8 0 0
v_min_id_clo_res 920 920 0 0.00 0 4074 11 0 0
v_min_id_ord_sup 5035 5035 0 0.00 0 9368 19 0 0
v_min_non_paid_invoice 5435 5435 0 0.00 0 8620 22 0 0
v_min_non_paid_realizn 6439 6439 0 0.00 0 10854 18 0 0
v_our_firm 9606 9606 0 0.00 0 139 4 0 0
v_reserve_write_off 18424 18424 0 0.00 0 19041 11 0 0
x_lock_depdocs_on_canc_invoice 10311 10311 0 0.00 0 12141 130 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 31379 433 0 0
x_make_qstorno_1000_1200 5065 5065 0 0.00 2 42093 1278 0 0
x_make_qstorno_1200_2000 5039 5039 0 0.00 5 86971 3111 0 0
x_make_qstorno_2100_3300 453262 453262 0 0.00 0 80965 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2022 04:39:20 to 03-08-2022 04:39:24.

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
02:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 36599 8192000000 9753347760 10058760192 818165552 9.080 100 86 173 0 847078336 7817072 21095712
02:10:05 10656 8192000000 9785624640 10100703232 862729792 9.527 100 88 176 0 895476784 12619440 20127552
02:11:23 9098 8192000000 9828252128 10150248448 895660512 9.855 100 91 182 0 938573024 22632176 20280336
02:12:44 5988 8192000000 9877177728 10213707776 924102144 10.137 100 80 160 0 990029680 48470208 17457328
02:13:54 5985 8192000000 9917221184 10263732224 963678608 10.525 100 86 170 0 1032215168 49847760 18688800
02:15:04 2969 8192000000 9979902128 10333356032 1001902048 10.897 100 89 177 0 1097540848 77724832 17913968
02:16:10 1415 8192000000 10001718720 10365394944 1023352000 11.104 100 94 185 0 1125316816 83475360 18489456
02:17:13 1000 8192000000 10008699648 10380537856 1047227504 11.334 100 98 189 0 1129224400 63587360 18409536
02:18:15 1025 8192000000 10050126432 10429042688 1069931632 11.551 100 98 187 1 1172452736 83366592 19154512
02:19:17 11294 8192000000 10042901232 10422218752 1079387088 11.642 100 81 152 3 1162836656 68322304 15127264
02:20:30 794 8192000000 10065280864 10448957440 1087354528 11.717 100 90 174 0 1187632176 82964496 17313152
02:21:31 5726 8192000000 10040887904 10433957888 1086504112 11.709 100 74 135 3 1162923232 63151920 13267200
02:22:41 905 8192000000 10065330256 10451849216 1094613952 11.787 100 95 180 2 1185605056 73256240 17734864
02:23:43 1123 8192000000 10073224928 10464980992 1098828944 11.827 100 90 170 2 1198041760 81549200 17663616
02:24:46 1225 8192000000 10081592208 10468794368 1102325312 11.860 100 96 188 1 1200501344 79886176 18289856
02:25:48 1170 8192000000 10085550992 10474733568 1099133696 11.829 100 95 185 1 1208433440 90539600 18760144
02:26:50 1795 8192000000 10056143504 10448203776 1102796480 11.864 100 82 155 0 1182472080 64639376 15036224
02:27:52 1961 8192000000 10055099296 10447159296 1100348480 11.841 100 73 129 4 1172110048 57609680 14151888
02:28:55 996 8192000000 10067610976 10458955776 1101513792 11.852 100 93 179 3 1186508688 66536224 18458672
02:29:57 3166 8192000000 10045761840 10431721472 1087941024 11.723 100 76 149 1 1167625104 64604912 15079168
02:31:01 2915 8192000000 10013274688 10404208640 1077670032 11.625 100 74 146 1 1142083472 49268912 15144528
02:32:06 1228 8192000000 10023991696 10401112064 1050123472 11.362 100 94 180 1 1144188000 74777024 19287504
02:33:07 1109 8192000000 10032203904 10412285952 1070676256 11.559 100 95 179 1 1155098192 64923136 19498800
02:34:09 3944 8192000000 10022447072 10408452096 1049125280 11.352 99 57 107 0 1124153440 64694064 10334096
02:35:13 5024 8192000000 9992877632 10376294400 1040043840 11.265 100 65 129 1 1113234128 60597168 12593120
02:36:19 965 8192000000 10020003456 10398703616 1048896160 11.350 100 96 187 2 1137204112 69707680 18600272
02:37:21 2647 8192000000 10004612224 10378358784 1045890064 11.321 100 95 169 3 1117142960 54817200 16435696
02:38:29 8805 8192000000 10023806592 10403246080 1062355120 11.479 100 68 131 0 1142059440 66596384 13107936
02:39:43 15965 8192000000 10033211344 10418847744 1086037920 11.705 100 81 153 0 1162085552 60289472 15758160
02:41:13 1923 8192000000 10109090032 10495098880 1097898288 11.818 100 78 143 4 1225167616 112102912 15166416
02:42:21 10714 8192000000 10125368784 10515714048 1108376080 11.917 100 73 144 0 1240495328 116872304 15246944
02:43:37 6815 8192000000 10164645056 10559242240 1119809120 12.025 100 71 136 0 1282389056 148077680 14502256
02:44:48 8814 8192000000 10213150288 10606686208 1120544912 12.032 99 70 130 0 1314295056 179214528 14535616
02:46:01 1816 8192000000 10227245104 10639515648 1141046336 12.225 100 84 153 2 1341571776 183366032 17159408
02:47:07 4396 8192000000 10271718080 10673549312 1157758560 12.382 100 72 142 2 1379741360 206493600 15489200
02:48:19 1640 8192000000 10304692480 10700099584 1168695344 12.485 100 88 171 0 1417621904 229401712 19524848
02:49:23 1515 8192000000 10355957568 10759168000 1166552544 12.465 100 96 179 1 1466256864 278491984 21212336
02:50:26 2514 8192000000 10363933728 10772791296 1171851584 12.514 100 94 175 0 1471238768 278461184 20926000
02:51:31 1013 8192000000 10377965712 10801291264 1186225168 12.648 100 95 185 0 1486879280 279901376 20752736
02:52:35 1006 8192000000 10417117504 10823151616 1181235632 12.602 100 92 181 2 1522840848 320003536 21601680
02:53:40 1033 8192000000 10441377696 10847416320 1189354000 12.677 100 91 178 0 1548956672 339547440 20055232
02:54:44 1257 8192000000 10478660240 10894036992 1188900448 12.673 100 81 151 1 1586314000 379480608 17932944
02:55:49 925 8192000000 10475081440 10917072896 1194934864 12.729 100 96 183 0 1583843840 365372608 23536368
02:56:52 2050 8192000000 10487741760 10938306560 1186942640 12.655 100 77 154 0 1595226992 389357952 18926400
02:57:56 1898 8192000000 10521880864 10969219072 1184561344 12.633 100 82 157 0 1630463984 426257104 19645536
02:59:01 11117 8192000000 10518419648 10972717056 1180173168 12.592 100 55 108 2 1626410976 431790976 14446832
03:00:15 12443 8192000000 10425696944 10868469760 1177009920 12.562 100 34 66 1 1539858112 353853424 8994768
03:01:28 11775 8192000000 10419618912 10862571520 1160613312 12.409 100 35 70 0 1530394416 360713136 9067968
03:02:44 28217 8192000000 10383551136 10848919552 1119904096 12.026 97 34 68 0 1502254752 372734816 9615840
03:04:18 12543 8192000000 10340462704 10838003712 1150072544 12.310 100 31 61 1 1460950208 302866096 8011568
03:05:39 21536 8192000000 10233497760 10757664768 1110624144 11.938 98 26 52 0 1366366768 249441680 6300944
03:07:15 20116 8192000000 10061056448 10475696128 1128152288 12.104 100 72 124 3 1149657920 7658752 13846880
03:08:38 32460 8192000000 10120412336 10548596736 1132391616 12.144 100 60 114 2 1214394624 70119872 11883136
03:10:12 40107 8192000000 10066500624 10528555008 1116865296 11.997 99 10 18 0 1156248704 37422400 1961008
03:11:52 84050 8192000000 10076845184 10520223744 1109806096 11.931 98 12 23 0 1156634928 44554064 2274768
03:14:17 62920 8192000000 10091939600 10529390592 1124364320 12.068 100 27 52 0 1196358512 66263024 5731168
03:16:49 32409 8192000000 10059341360 10476486656 1091579888 11.758 98 46 81 1 1127758416 26716192 9462336
03:18:40 38988 8192000000 10051975472 10502402048 1098828464 11.827 99 25 48 0 1147168160 43265728 5073968
03:20:22 124203 8192000000 10031806976 10472251392 1117797920 12.006 100 34 69 1 1148728608 22837952 8092736
03:23:31 47084 8192000000 10066631616 10518216704 1127215040 12.095 100 39 76 0 1162768720 27992176 7561504
03:25:39 8404 8192000000 10074305904 10504601600 1130160256 12.123 100 47 85 0 1175473056 35903200 9409600
03:26:49 54305 8192000000 10094775072 10544791552 1089812288 11.741 96 37 70 0 1160036528 63220992 7003248
03:28:57 71807 8192000000 10124032944 10588090368 1127285184 12.096 100 12 24 0 1230289744 100249616 2754944
03:31:15 50128 8192000000 10131529664 10608394240 1125650576 12.080 100 9 18 0 1240039168 112309936 2078656
03:33:06 77305 8192000000 10108614288 10606678016 1132264912 12.143 100 14 28 0 1243504640 108106016 3133712
03:35:27 72938 8192000000 10159457568 10615558144 1133272848 12.152 99 17 34 0 1241112304 104051632 3787824
03:37:40 69592 8192000000 10125094016 10586161152 1125259936 12.077 98 21 41 0 1194399344 65131696 4007712
03:40:56 31216 8192000000 10130746064 10587533312 1147505936 12.286 100 21 42 0 1208768000 57104096 4157968
03:42:35 58617 8192000000 10118350976 10596777984 1155165744 12.358 100 16 32 0 1196665152 37722672 3776736
03:44:34 57791 8192000000 10112101152 10581385216 1146467888 12.276 99 27 54 2 1223763680 70946480 6349312
03:46:32 56236 8192000000 10118151936 10579648512 1153156464 12.339 99 18 37 0 1188568912 31752928 3659520
03:48:35 21565 8192000000 10134699392 10578784256 1145463856 12.267 98 45 88 0 1227645200 73522656 8658688
03:50:00 97067 8192000000 10076571600 10516058112 1126116768 12.085 97 41 81 0 1190028192 55199552 8711872
03:52:46 78923 8192000000 10084137312 10562314240 1111557392 11.947 97 12 24 0 1167847776 53885808 2404576
03:55:05 47178 8192000000 10098474304 10562187264 1132448896 12.144 99 19 38 0 1205757712 68410128 4898688
03:56:56 35488 8192000000 10119472688 10592755712 1143309600 12.247 100 15 30 0 1241592848 94621552 3661696
03:58:32 62821 8192000000 10074529136 10593075200 1121388112 12.040 98 15 31 0 1232991056 108081776 3521168
04:00:40 63465 8192000000 10106533328 10535133184 1129217168 12.114 99 35 65 1 1185067536 48808896 7041472
04:02:44 53917 8192000000 10087181040 10571264000 1128387360 12.106 99 27 53 0 1223535648 89356336 5791952
04:04:55 70413 8192000000 10077667280 10539089920 1078387344 11.632 95 22 44 0 1167795136 84665648 4742144
04:07:10 7537 8192000000 10068194160 10492190720 1112090288 11.952 100 44 88 1 1156642352 35852288 8699776
04:08:24 44112 8192000000 10126809664 10601512960 1132544416 12.145 99 42 84 0 1227811056 87155088 8111552
04:10:59 83217 8192000000 10083521952 10544979968 1086423472 11.709 96 39 78 0 1189181568 95060880 7697216
04:14:06 74040 8192000000 10104023552 10595717120 1116093984 11.990 98 16 32 0 1203200608 83541712 3564912
04:16:23 62342 8192000000 10104730320 10587070464 1126484912 12.088 99 10 20 0 1198760960 70084320 2191728
04:18:26 54467 8192000000 10056149136 10575761408 1113421184 11.965 98 11 22 0 1152660672 36560832 2678656
04:20:21 8411 8192000000 10055384832 10488479744 1106854032 11.903 98 63 118 2 1142372080 21084752 14433296
04:21:43 51941 8192000000 10087739504 10521415680 1114680256 11.977 98 35 68 0 1184834128 62289696 7864176
04:23:35 60843 8192000000 10067132064 10549731328 1105348640 11.888 98 27 54 0 1165272800 54473392 5450768
04:25:42 32904 8192000000 10150497856 10594455552 1139647360 12.212 100 53 107 0 1222783088 71883584 11252144
04:27:23 51901 8192000000 10095210336 10545881088 1128409952 12.106 99 28 56 0 1193939888 60125680 5404256
04:29:24 65541 8192000000 10104007984 10612408320 1141468352 12.229 100 38 76 0 1250066320 101042304 7555664
04:31:34 25309 8192000000 10123727312 10569949184 1148565984 12.296 100 48 95 0 1219454208 61378272 9509952
04:33:03 58046 8192000000 10135918448 10620129280 1150580496 12.315 100 46 89 1 1246862736 87490064 8792176
04:35:11 87820 8192000000 10101362464 10568175616 1139752864 12.213 99 14 26 1 1202366720 59403664 3210192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-08-2022 04:39:26 to 03-08-2022 04:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2022 04:39:42 to 03-08-2022 04:39:44.

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          207339
MON$OLDEST_ACTIVE               208416
MON$OLDEST_SNAPSHOT             208416
MON$NEXT_TRANSACTION            208416
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                       2030496
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             753
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2812 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2812 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2812 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2812 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 ---
:65: 
:66: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:30 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:37 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:37 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:37 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:38 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Wed Aug  3 04:37:40 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

03.08.22 04:39:45. 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-08-03 04:39:48 No severe PSQL-related problems occured
03.08.22 04:39:49. End of report.