Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.07.2022 04: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 15277848 kB
MemAvailable 31052588 kB
Buffers 20 kB
Cached 16387792 kB
SwapCached 2500 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-07-21 04:37:19
DTS_END                         2022-07-21 04:37:19.2460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(751:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57868
TRN_ID                          215956
ATT_ID                          603
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: 8763.52 23804 1051622
customer order: creation 163.53 500 19623
customer order: refuse 13.42 523 1610
order to supplier: creation 43.49 1487 5219
order to supplier: removal 3.25 196574 390
invoice (draft): creation 43.88 3493 5266
invoice (draft): removal 49.66 1241 5959
invoice accept: apply 40.03 26136 4804
invoice accept: cancel 45.16 1472 5419
customer reserve: creation 3670.44 247 440453
customer reserve: removal 4405.93 112 528711
realization accept: apply 79.14 341 9497
realization accept: cancel 81.92 187 9830
payment from customer: creation 56.57 213 6788
payment from customer: removal 5.35 155 642
payment to supplier: creation 46.24 265 5549
payment to supplier: removal 3.34 163 401
service: total inventory turnovers 3.70 4860 444
service: total monetary turnovers 7.55 443 906
service: aggregate perf. data 0.88 18682 106
service: refresh index statistics 0.04 218990 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-07-2022 04:39:49 to 21-07-2022 04:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5770
TEST_TIME 2 5630
TEST_TIME 3 5475
TEST_TIME 4 5873
TEST_TIME 5 6156
TEST_TIME 6 6781
TEST_TIME 7 6665
TEST_TIME 8 6667
TEST_TIME 9 6456
TEST_TIME 10 6592
TEST_TIME 11 6442
TEST_TIME 12 6532
TEST_TIME 13 6560
TEST_TIME 14 6800
TEST_TIME 15 6768
TEST_TIME 16 6769
TEST_TIME 17 6895
TEST_TIME 18 6977
TEST_TIME 19 7230
TEST_TIME 20 7631
TEST_TIME 21 7654
TEST_TIME 22 7723
TEST_TIME 23 7912
TEST_TIME 24 7972
TEST_TIME 25 8026
TEST_TIME 26 8349
TEST_TIME 27 8460
TEST_TIME 28 8355
TEST_TIME 29 8482
TEST_TIME 30 8928
TEST_TIME 31 9373
TEST_TIME 32 9917
TEST_TIME 33 9903
TEST_TIME 34 9844
TEST_TIME 35 9800
TEST_TIME 36 9944
TEST_TIME 37 10243
TEST_TIME 38 10415
TEST_TIME 39 10242
TEST_TIME 40 10077
TEST_TIME 41 10126
TEST_TIME 42 10121
TEST_TIME 43 10650
TEST_TIME 44 10582
TEST_TIME 45 10408
TEST_TIME 46 10309
TEST_TIME 47 10220
TEST_TIME 48 10145
TEST_TIME 49 10093
TEST_TIME 50 10076
TEST_TIME 51 10134
TEST_TIME 52 10181
TEST_TIME 53 10201
TEST_TIME 54 10101
TEST_TIME 55 10048
TEST_TIME 56 9987
TEST_TIME 57 9843
TEST_TIME 58 9746
TEST_TIME 59 9777
TEST_TIME 60 9686
TEST_TIME 61 9627
TEST_TIME 62 9647
TEST_TIME 63 9590
TEST_TIME 64 9527
TEST_TIME 65 9497
TEST_TIME 66 9496
TEST_TIME 67 9450
TEST_TIME 68 9411
TEST_TIME 69 9419
TEST_TIME 70 9419
TEST_TIME 71 9367
TEST_TIME 72 9333
TEST_TIME 73 9263
TEST_TIME 74 9191
TEST_TIME 75 9113
TEST_TIME 76 9115
TEST_TIME 77 9071
TEST_TIME 78 9069
TEST_TIME 79 9100
TEST_TIME 80 9062
TEST_TIME 81 9059
TEST_TIME 82 9060
TEST_TIME 83 9039
TEST_TIME 84 9058
TEST_TIME 85 9110
TEST_TIME 86 9072
TEST_TIME 87 9252
TEST_TIME 88 9242
TEST_TIME 89 9225
TEST_TIME 90 9282
TEST_TIME 91 9261
TEST_TIME 92 9167
TEST_TIME 93 9178
TEST_TIME 94 9104
TEST_TIME 95 9102
TEST_TIME 96 9049
TEST_TIME 97 9043
TEST_TIME 98 9020
TEST_TIME 99 8997
TEST_TIME 100 8982
TEST_TIME 101 8947
TEST_TIME 102 8980
TEST_TIME 103 8949
TEST_TIME 104 8933
TEST_TIME 105 8981
TEST_TIME 106 9039
TEST_TIME 107 9020
TEST_TIME 108 8993
TEST_TIME 109 9045
TEST_TIME 110 9120
TEST_TIME 111 9066
TEST_TIME 112 9007
TEST_TIME 113 8972
TEST_TIME 114 8959
TEST_TIME 115 8920
TEST_TIME 116 8905
TEST_TIME 117 8910
TEST_TIME 118 8883
TEST_TIME 119 8857
TEST_TIME 120 8787

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-07-2022 04:39:53 to 21-07-2022 04:39:56.

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 1458273 1458273 0 0.00 0 32999 14 0 0
doc_list_biud 1458260 1458260 0 0.00 0 36999 48 0 0
sp_add_invoice_to_stock 4882 4804 78 1.59 58 173546 26136 0 78
sp_cancel_adding_invoice 5419 5419 0 0.00 7 102549 1473 0 0
sp_cancel_client_order 1610 1610 0 0.00 6 25205 523 0 0
sp_cancel_customer_reserve 528711 528711 0 0.00 1 32633 112 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 4 6078 155 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4937 164 0 0
sp_cancel_supplier_invoice 5964 5959 5 0.08 3 32397 1242 0 5
sp_cancel_supplier_order 390 390 0 0.00 8 1455096 196574 0 0
sp_cancel_write_off 9830 9830 0 0.00 2 9461 188 0 0
sp_client_order 19623 19623 0 0.00 6 37858 501 0 0
sp_customer_reserve 440453 440453 0 0.00 1 114688 247 0 0
sp_fill_shopping_cart 30097 30097 0 0.00 1 18688 95 0 0
sp_fill_shopping_cart_clo_res 440405 440405 0 0.00 0 19153 20 0 0
sp_get_clo_for_invoice 4754 4754 0 0.00 0 22780 313 0 0
sp_kill_cost_storno 16292 16292 0 0.00 0 32984 97 0 0
sp_kill_qstorno_ret_qs2qd 1073601 1073601 0 0.00 0 32540 49 0 0
sp_kill_qty_storno 566161 566161 0 0.00 0 36997 114 0 0
sp_lock_selected_doc 19738 19738 0 0.00 0 8990 8 0 0
sp_make_cost_storno 26588 26588 0 0.00 0 17462 104 0 0
sp_multiply_rows_for_pdistr 26578 26578 0 0.00 0 31564 47 0 0
sp_multiply_rows_for_qdistr 19623 19623 0 0.00 0 37775 178 0 0
sp_pay_from_customer 6788 6788 0 0.00 5 21668 213 0 0
sp_pay_to_supplier 5549 5549 0 0.00 6 31588 266 0 0
sp_payment_common 12333 12333 0 0.00 2 14052 63 0 0
sp_qd_handle_on_cancel_clo 1610 1610 0 0.00 0 25192 429 0 0
sp_qd_handle_on_invoice_upd_sts 10166 10166 0 0.00 0 36996 633 0 0
sp_qd_handle_on_reserve_upd_sts 9495 9495 0 0.00 0 12330 79 0 0
sp_reserve_write_off 9497 9497 0 0.00 9 17580 342 0 0
sp_supplier_invoice 5266 5266 0 0.00 7 84385 3494 0 0
sp_supplier_order 5219 5219 0 0.00 6 33888 1487 0 0
srv_aggregate_perf_data 106 106 0 0.00 2515 340568 18682 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 916 160377 32666 0 0
srv_find_qd_qs_mism 1005546 1005546 0 0.00 0 13689 9 0 0
srv_make_invnt_saldo 444 444 0 0.00 71 113562 4861 0 0
srv_make_money_saldo 906 906 0 0.00 1 17184 444 0 0
srv_recalc_idx_stat 5 5 0 0.00 61278 381072 218991 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4807 4807 0 0.00 1 10878 1051 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 2 8187 549 0 0
t$perf-norm:sp_cancel_client_order 1610 1610 0 0.00 0 2547 28 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 957 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 325 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1028 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 831 42 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 1 97382 8183 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 967 20 0 0
t$perf-norm:sp_client_order 19624 19624 0 0.00 0 14370 41 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 819 17 0 0
t$perf-norm:sp_pay_from_customer 6788 6788 0 0.00 0 3621 20 0 0
t$perf-norm:sp_pay_to_supplier 5549 5549 0 0.00 0 5043 23 0 0
t$perf-norm:sp_reserve_write_off 9497 9497 0 0.00 0 3899 23 0 0
t$perf-norm:sp_supplier_invoice 5266 5266 0 0.00 0 7555 35 0 0
t$perf-norm:sp_supplier_order 5219 5219 0 0.00 0 8193 37 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1541 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 361 12 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 792 28 0 0
t$perf-norm:srv_make_money_saldo 906 906 0 0.00 0 765 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 196 87 0 0
v_add_invoice_to_stock 9650 9494 156 1.61 0 6003 29 0 156
v_all_customers 36046 36046 0 0.00 0 74 4 0 0
v_all_suppliers 29164 29164 0 0.00 0 67 5 0 0
v_all_wares 39244 39244 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 2807 20 0 0
v_cancel_client_order 3220 3220 0 0.00 0 3153 15 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 6007 21 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 4834 18 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1851 20 0 0
v_cancel_supplier_invoice 752 742 10 1.32 0 6931 38 0 10
v_cancel_supplier_order 780 780 0 0.00 0 3636 16 0 0
v_cancel_write_off 1996 1996 0 0.00 0 5068 33 0 0
v_max_id_clo_ord 5219 5219 0 0.00 0 2995 6 0 0
v_max_id_clo_res 985 985 0 0.00 0 1631 16 0 0
v_max_id_ord_sup 5256 5256 0 0.00 0 4443 8 0 0
v_max_non_paid_invoice 1440 1440 0 0.00 0 494 9 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 1530 9 0 0
v_min_id_clo_ord 5219 5219 0 0.00 0 2756 10 0 0
v_min_id_clo_res 985 985 0 0.00 0 3035 16 0 0
v_min_id_ord_sup 5256 5256 0 0.00 0 4300 9 0 0
v_min_non_paid_invoice 5547 5547 0 0.00 0 13746 29 0 0
v_min_non_paid_realizn 6786 6786 0 0.00 0 13372 31 0 0
v_our_firm 9682 9682 0 0.00 0 106 5 0 0
v_reserve_write_off 18990 18990 0 0.00 0 7452 15 0 0
x_lock_depdocs_on_canc_invoice 11007 11007 0 0.00 0 13837 149 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 6871 455 0 0
x_make_qstorno_1000_1200 5219 5219 0 0.00 2 33840 1290 0 0
x_make_qstorno_1200_2000 5266 5266 0 0.00 3 82485 3236 0 0
x_make_qstorno_2100_3300 440453 440453 0 0.00 0 114683 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-07-2022 04:39:57 to 21-07-2022 04:40:01.

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:23 31271 8192000000 9712587648 10003824640 788799712 8.783 100 90 178 0 818203136 7485472 21917952
02:09:59 23021 8192000000 9761169840 10065682432 832450608 9.224 100 93 184 0 866711584 12269472 21991504
02:11:30 13390 8192000000 9792561632 10114682880 867270160 9.573 100 80 157 0 903743664 18340944 18132560
02:12:51 6147 8192000000 9887816448 10220097536 922949552 10.125 100 85 170 0 993359120 51925664 18483904
02:14:03 3535 8192000000 9937108112 10281938944 964628384 10.534 100 90 176 0 1048901024 65584928 18687712
02:15:12 2534 8192000000 9969707936 10324099072 1000931152 10.888 100 93 178 0 1077418608 58341536 18145920
02:16:17 947 8192000000 9995255200 10357088256 1031250256 11.180 100 97 193 0 1116299008 65945488 19103264
02:17:20 1384 8192000000 10026824528 10391916544 1041036400 11.275 100 88 167 2 1141720560 84200096 16484064
02:18:23 864 8192000000 10020832624 10398453760 1057485040 11.432 100 91 175 1 1139835168 65091568 17258560
02:19:25 878 8192000000 10030763264 10411368448 1067030304 11.524 100 95 181 1 1153435472 68255344 18149824
02:20:27 1183 8192000000 10056334640 10439204864 1072486464 11.576 100 96 181 2 1180490144 89093120 18910560
02:21:29 1302 8192000000 10040124288 10425315328 1081650016 11.663 100 82 154 2 1160696528 63054928 15991584
02:22:31 1082 8192000000 10033861840 10423365632 1085564576 11.700 100 87 159 1 1160169472 57477920 17126976
02:23:34 1230 8192000000 10080675824 10470113280 1088598016 11.729 100 96 187 0 1204267024 95727200 19941808
02:24:36 1111 8192000000 10066157536 10450485248 1095234240 11.792 100 97 192 2 1188643984 74446864 18962880
02:25:38 1249 8192000000 10069503840 10458882048 1087009568 11.714 100 96 189 1 1188539456 82622240 18907648
02:26:41 1175 8192000000 10053477504 10442944512 1080685808 11.654 99 92 179 1 1179409424 80275376 18448240
02:27:43 1709 8192000000 10039197184 10434887680 1088491968 11.728 100 77 142 1 1164017392 60949936 14575488
02:28:46 1908 8192000000 10039211744 10431307776 1091227280 11.754 100 80 156 0 1163572992 57185408 15160304
02:29:49 1012 8192000000 10021791952 10407100416 1069179776 11.544 99 94 181 0 1148863408 58813024 20870608
02:30:51 1471 8192000000 10014443120 10400829440 1067058864 11.524 100 77 152 3 1135447824 53533408 14855552
02:31:54 4288 8192000000 10011134608 10396995584 1066618976 11.520 100 71 135 0 1137876400 57276624 13980800
02:33:00 1742 8192000000 10016009376 10405912576 1057567344 11.433 100 74 130 1 1136673232 65603632 13502256
02:34:02 1533 8192000000 10012052576 10392395776 1045566208 11.318 100 86 164 0 1128218128 66300400 16351520
02:35:05 1368 8192000000 9990430944 10369601536 1035509088 11.221 100 84 166 0 1117194720 64806112 16879520
02:36:07 966 8192000000 10003097152 10382667776 1046502432 11.327 100 89 156 0 1123272672 60450064 16320176
02:37:09 1172 8192000000 9972354400 10346504192 1045078960 11.313 100 83 154 3 1096092288 35762832 15250496
02:38:14 3532 8192000000 10042856752 10424852480 1064850656 11.503 100 85 152 1 1152881984 73144384 14886944
02:39:32 1767 8192000000 10047549408 10431848448 1082555312 11.672 100 83 159 1 1173110128 75090896 15463920
02:40:51 3551 8192000000 10090827328 10477088768 1093293648 11.774 100 78 133 1 1210999584 103277200 14428736
02:42:01 6266 8192000000 10108279856 10495950848 1093653968 11.777 99 73 143 1 1209794624 101644704 14495952
02:43:11 1118 8192000000 10139964608 10533265408 1121722784 12.043 100 92 178 2 1255525616 115794512 18008320
02:44:15 1267 8192000000 10173848864 10562875392 1133733312 12.157 100 93 176 0 1280960528 127890720 19336496
02:45:19 1561 8192000000 10182570416 10577719296 1142985328 12.244 100 94 179 0 1295356832 132089056 20282448
02:46:24 5916 8192000000 10212523264 10611970048 1155232592 12.359 100 75 150 0 1310835760 139138000 16465168
02:47:40 1588 8192000000 10266257056 10668294144 1161701024 12.419 100 83 161 1 1384336880 204756192 17879664
02:48:49 1838 8192000000 10295972960 10699149312 1173630992 12.531 100 84 161 2 1406815360 213809504 19374864
02:49:54 1762 8192000000 10330689648 10733289472 1181864480 12.608 100 89 171 1 1441344688 240599616 18880592
02:50:59 949 8192000000 10377600704 10794971136 1197566880 12.754 100 92 178 2 1484742896 265937136 21238880
02:52:06 3638 8192000000 10392610464 10804527104 1196041680 12.740 100 73 146 0 1498533360 284909024 17582656
02:53:13 3065 8192000000 10419769072 10834530304 1208244416 12.853 100 90 175 1 1529185712 300145664 20795632
02:54:22 1475 8192000000 10454466352 10875367424 1199299856 12.770 100 92 180 0 1561589136 340123968 22165312
02:55:25 1704 8192000000 10479603456 10913341440 1197396896 12.752 100 94 188 0 1580252176 360569456 22285824
02:56:30 917 8192000000 10488432160 10944167936 1194640416 12.727 100 98 192 1 1596876272 378071120 24164736
02:57:34 1314 8192000000 10505515856 10940968960 1191695648 12.699 100 96 186 2 1611933136 398488368 21749120
02:58:40 907 8192000000 10516917360 10944057344 1192334992 12.705 100 95 186 1 1620606432 405126240 23145200
02:59:46 2447 8192000000 10513785712 10959380480 1192420432 12.706 100 87 160 2 1618097296 405914352 19762512
03:00:51 1341 8192000000 10531848336 10948665344 1200946720 12.785 100 96 185 2 1637149552 414143952 22058880
03:01:55 1456 8192000000 10557087008 10976727040 1189066448 12.675 100 88 171 0 1657625984 447311008 21248528
03:03:00 1304 8192000000 10546499616 11022262272 1184568480 12.633 100 70 140 0 1649723024 448019040 17135504
03:04:06 16000 8192000000 10519566288 10970923008 1180648272 12.596 100 37 73 0 1624874672 434505744 9720656
03:05:35 5033 8192000000 10540207456 10968227840 1166575760 12.465 100 48 96 0 1643760240 464288256 12896224
03:06:46 15770 8192000000 10479609952 10969899008 1154988608 12.356 100 38 74 2 1591513392 425846400 10678384
03:08:05 18846 8192000000 10385268240 10899374080 1155464640 12.361 100 35 69 0 1496540224 332536368 8539216
03:09:28 47317 8192000000 10298106976 10739527680 1123485536 12.060 96 37 70 0 1350063088 218576592 8000960
03:11:20 1102 8192000000 10301329168 10720915456 1160199216 12.405 100 92 175 0 1396134944 216645424 19290304
03:12:23 1233 8192000000 10387889504 10799845376 1166695744 12.466 100 91 176 0 1481915744 296434800 18785200
03:13:25 11006 8192000000 10315232288 10815897600 1174843184 12.542 100 50 95 0 1435312480 248648144 11821152
03:14:45 11177 8192000000 10301634224 10775670784 1174306400 12.537 100 40 80 0 1407483104 222943568 10233136
03:16:05 81631 8192000000 10313951952 10753519616 1173382208 12.528 100 40 76 0 1415281376 232815680 9083488
03:18:30 36986 8192000000 10361025504 10863775744 1165117136 12.451 100 19 38 0 1442213568 272321664 4774768
03:20:07 36821 8192000000 10181130544 10806800384 1148672448 12.297 100 7 14 0 1298788768 148101344 2014976
03:22:21 17326 8192000000 10121691824 10559160320 1156600016 12.371 100 37 75 0 1208042720 42647360 8795344
03:23:38 45605 8192000000 10131937504 10596810752 1158687568 12.391 100 27 55 0 1242967392 78700800 5579024
03:25:25 95772 8192000000 10169208240 10622361600 1156327792 12.369 100 20 40 0 1271266160 110914080 4024288
03:28:40 75445 8192000000 10098039392 10629079040 1138622160 12.203 98 14 26 0 1244239504 102862608 2754736
03:31:02 31974 8192000000 10069464112 10515656704 1144425504 12.257 100 22 41 0 1165512064 16193888 4892672
03:32:42 160377 8192000000 10114623744 10571354112 1147338656 12.285 99 29 58 0 1208366976 54504672 6523648
03:36:25 35931 8192000000 10096425376 10526220288 1140511472 12.220 99 62 101 3 1186995392 34590528 11893392
03:38:02 121606 8192000000 10046204208 10482159616 1067568432 11.529 93 32 59 3 1105322176 31022176 6731568
03:41:05 6638 8192000000 10077008304 10502930432 1101266464 11.850 99 50 97 0 1163670928 52540400 9864064
03:42:17 11300 8192000000 10152792400 10577272832 1137040768 12.188 100 60 117 0 1247600912 97943504 12616640
03:43:38 87976 8192000000 10136016512 10613514240 1125399776 12.078 99 17 34 0 1236710240 107427248 3883216
03:46:21 19127 8192000000 10075853232 10546544640 1121486784 12.041 99 14 28 0 1185651216 61151136 3013296
03:47:41 54258 8192000000 10070824208 10543067136 1123913824 12.064 99 13 26 0 1167409312 40735904 2759584
03:49:40 52661 8192000000 10057124976 10537848832 1137754816 12.194 100 7 14 0 1185703936 46544448 1404672
03:51:50 39159 8192000000 10087858576 10562248704 1147545184 12.286 100 11 20 0 1196640256 47202448 1892624
03:53:30 84605 8192000000 10108947120 10599501824 1140599440 12.221 99 8 16 1 1216002576 73536608 1866528
03:55:56 55902 8192000000 10073166336 10564571136 1137828304 12.195 99 16 30 0 1195756992 54895424 3033264
03:57:53 24721 8192000000 10079277632 10527682560 1129559616 12.117 100 37 74 0 1174699632 37341488 7798528
03:59:18 18200 8192000000 10130323520 10569797632 1129911760 12.121 100 53 105 0 1218343824 77965344 10466720
04:00:37 31767 8192000000 10118129024 10588884992 1110513520 11.937 98 28 52 1 1208078624 91689120 5875984
04:02:29 67000 8192000000 10183451184 10628390912 1141495856 12.230 100 16 30 0 1243876416 99234624 3145936
04:04:50 76996 8192000000 10088203056 10521866240 1088107616 11.725 95 16 32 0 1155399008 64108640 3182752
04:07:12 54157 8192000000 10095613952 10565152768 1126509264 12.088 99 39 78 0 1208372112 74327040 7535808
04:09:09 74503 8192000000 10083173792 10541457408 1129663184 12.118 100 23 44 0 1195458000 61147488 4647328
04:11:32 16897 8192000000 10077030112 10506854400 1132636992 12.146 100 53 98 0 1170166112 28058832 9470288
04:12:50 65749 8192000000 10087704240 10543439872 1131458272 12.135 99 23 46 0 1205666272 69098544 5109456
04:15:00 55364 8192000000 10068805952 10538012672 1128966816 12.112 99 14 28 0 1188379072 56395776 3016480
04:16:58 46466 8192000000 10102778864 10563895296 1153011376 12.338 100 15 29 0 1201223072 45241472 2970224
04:18:46 49045 8192000000 10099406288 10549579776 1128315760 12.105 98 19 36 0 1193043216 60555008 4172448
04:20:44 32061 8192000000 10160092928 10610368512 1164041856 12.441 100 31 59 1 1248579360 77543984 6993520
04:22:18 63778 8192000000 10115711392 10620190720 1149684528 12.307 99 25 50 0 1266432000 111321232 5426240
04:24:27 40197 8192000000 10079857184 10519789568 1081502400 11.662 95 47 94 0 1120491376 29643664 9345312
04:26:08 51507 8192000000 10122888640 10614505472 1135323536 12.172 100 28 56 0 1228969072 87631296 6014240
04:28:00 107610 8192000000 10069305664 10507599872 1092144080 11.763 96 34 67 0 1155863504 56815888 6903536
04:30:51 20603 8192000000 10086935088 10549071872 1123652912 12.061 99 32 61 0 1182123824 52265248 6205664
04:32:21 69150 8192000000 10078708304 10591113216 1132958720 12.149 100 9 18 0 1219838544 84852912 2026912
04:35:16 56618 8192000000 10043297520 10494214144 1122781952 12.053 99 23 46 0 1155890496 28207808 4900736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-07-2022 04:40:03 to 21-07-2022 04:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-07-2022 04:40:20 to 21-07-2022 04:40:22.

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          215815
MON$OLDEST_ACTIVE               217117
MON$OLDEST_SNAPSHOT             217117
MON$NEXT_TRANSACTION            217117
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                       2122816
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

21.07.22 04:40:23. 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-07-21 04:40:26 No severe PSQL-related problems occured
21.07.22 04:40:27. End of report.