Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2022 04:37
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 15032080 kB
MemAvailable 30443360 kB
Buffers 20 kB
Cached 16594744 kB
SwapCached 2140 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.3
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-10-17 04:37:22
DTS_END                         2022-10-17 04:37:22.1710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37486
TRN_ID                          207430
ATT_ID                          526
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: 8481.98 30785 1017837
customer order: creation 154.18 508 18502
customer order: refuse 13.49 456 1619
order to supplier: creation 41.83 1520 5020
order to supplier: removal 3.45 166792 414
invoice (draft): creation 41.21 3836 4945
invoice (draft): removal 48.23 1072 5787
invoice accept: apply 37.17 28415 4460
invoice accept: cancel 43.36 1306 5203
customer reserve: creation 3531.43 263 423771
customer reserve: removal 4295.96 104 515515
realization accept: apply 78.09 318 9371
realization accept: cancel 74.65 172 8958
payment from customer: creation 54.07 189 6488
payment from customer: removal 5.22 131 626
payment to supplier: creation 44.79 218 5375
payment to supplier: removal 3.19 156 383
service: total inventory turnovers 3.72 4368 446
service: total monetary turnovers 7.12 419 854
service: aggregate perf. data 0.80 18822 96
service: refresh index statistics 0.03 386643 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 04:38:18 to 17-10-2022 04:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6555
TEST_TIME 2 5108
TEST_TIME 3 5437
TEST_TIME 4 5472
TEST_TIME 5 5305
TEST_TIME 6 5116
TEST_TIME 7 5078
TEST_TIME 8 5989
TEST_TIME 9 6136
TEST_TIME 10 6149
TEST_TIME 11 6181
TEST_TIME 12 6210
TEST_TIME 13 6497
TEST_TIME 14 6388
TEST_TIME 15 6518
TEST_TIME 16 6869
TEST_TIME 17 7243
TEST_TIME 18 7231
TEST_TIME 19 7269
TEST_TIME 20 7433
TEST_TIME 21 7561
TEST_TIME 22 7358
TEST_TIME 23 7521
TEST_TIME 24 7574
TEST_TIME 25 8012
TEST_TIME 26 8442
TEST_TIME 27 8594
TEST_TIME 28 8800
TEST_TIME 29 8866
TEST_TIME 30 9378
TEST_TIME 31 9557
TEST_TIME 32 9563
TEST_TIME 33 10017
TEST_TIME 34 10054
TEST_TIME 35 10023
TEST_TIME 36 10453
TEST_TIME 37 10416
TEST_TIME 38 10512
TEST_TIME 39 10537
TEST_TIME 40 10303
TEST_TIME 41 10210
TEST_TIME 42 10207
TEST_TIME 43 10131
TEST_TIME 44 10066
TEST_TIME 45 9921
TEST_TIME 46 9944
TEST_TIME 47 9883
TEST_TIME 48 9889
TEST_TIME 49 9778
TEST_TIME 50 9716
TEST_TIME 51 9567
TEST_TIME 52 9539
TEST_TIME 53 9388
TEST_TIME 54 9363
TEST_TIME 55 9217
TEST_TIME 56 9142
TEST_TIME 57 9077
TEST_TIME 58 9071
TEST_TIME 59 9025
TEST_TIME 60 8997
TEST_TIME 61 8895
TEST_TIME 62 8900
TEST_TIME 63 8875
TEST_TIME 64 8761
TEST_TIME 65 8700
TEST_TIME 66 8758
TEST_TIME 67 8763
TEST_TIME 68 8726
TEST_TIME 69 8701
TEST_TIME 70 8644
TEST_TIME 71 8621
TEST_TIME 72 8556
TEST_TIME 73 8576
TEST_TIME 74 8652
TEST_TIME 75 8809
TEST_TIME 76 8835
TEST_TIME 77 8881
TEST_TIME 78 8819
TEST_TIME 79 8869
TEST_TIME 80 8921
TEST_TIME 81 8950
TEST_TIME 82 8895
TEST_TIME 83 8804
TEST_TIME 84 8779
TEST_TIME 85 8785
TEST_TIME 86 8749
TEST_TIME 87 8733
TEST_TIME 88 8675
TEST_TIME 89 8670
TEST_TIME 90 8614
TEST_TIME 91 8614
TEST_TIME 92 8554
TEST_TIME 93 8546
TEST_TIME 94 8516
TEST_TIME 95 8528
TEST_TIME 96 8539
TEST_TIME 97 8542
TEST_TIME 98 8508
TEST_TIME 99 8497
TEST_TIME 100 8556
TEST_TIME 101 8545
TEST_TIME 102 8546
TEST_TIME 103 8606
TEST_TIME 104 8637
TEST_TIME 105 8634
TEST_TIME 106 8625
TEST_TIME 107 8656
TEST_TIME 108 8645
TEST_TIME 109 8671
TEST_TIME 110 8682
TEST_TIME 111 8623
TEST_TIME 112 8637
TEST_TIME 113 8590
TEST_TIME 114 8577
TEST_TIME 115 8559
TEST_TIME 116 8522
TEST_TIME 117 8544
TEST_TIME 118 8538
TEST_TIME 119 8552
TEST_TIME 120 8504

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2022 04:38:22 to 17-10-2022 04:38:25.

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 1408573 1408573 0 0.00 0 38815 14 0 0
doc_list_biud 1408571 1408571 0 0.00 0 35785 44 0 0
sp_add_invoice_to_stock 4605 4460 145 3.14 70 203288 28415 0 145
sp_cancel_adding_invoice 5203 5203 0 0.00 5 83618 1306 0 0
sp_cancel_client_order 1619 1619 0 0.00 6 8691 457 0 0
sp_cancel_customer_reserve 515515 515515 0 0.00 1 35792 104 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 4 2589 132 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 4 3795 157 0 0
sp_cancel_supplier_invoice 5798 5787 11 0.18 2 35651 1073 0 11
sp_cancel_supplier_order 414 414 0 0.00 16 1540556 166793 0 0
sp_cancel_write_off 8958 8958 0 0.00 2 10555 173 0 0
sp_client_order 18502 18502 0 0.00 5 42795 508 0 0
sp_customer_reserve 423771 423771 0 0.00 1 57390 264 0 0
sp_fill_shopping_cart 28459 28459 0 0.00 1 28558 91 0 0
sp_fill_shopping_cart_clo_res 423744 423744 0 0.00 0 17999 20 0 0
sp_get_clo_for_invoice 4418 4418 0 0.00 0 21535 358 0 0
sp_kill_cost_storno 15170 15170 0 0.00 0 32922 91 0 0
sp_kill_qstorno_ret_qs2qd 1046189 1046189 0 0.00 0 35751 46 0 0
sp_kill_qty_storno 551278 551278 0 0.00 0 35784 105 0 0
sp_lock_selected_doc 19087 19087 0 0.00 0 7224 7 0 0
sp_make_cost_storno 25647 25647 0 0.00 0 11678 83 0 0
sp_multiply_rows_for_pdistr 25645 25645 0 0.00 0 8703 41 0 0
sp_multiply_rows_for_qdistr 18502 18502 0 0.00 0 11169 175 0 0
sp_pay_from_customer 6488 6488 0 0.00 5 11018 189 0 0
sp_pay_to_supplier 5375 5375 0 0.00 7 11009 218 0 0
sp_payment_common 11863 11863 0 0.00 2 9185 49 0 0
sp_qd_handle_on_cancel_clo 1619 1619 0 0.00 0 8682 378 0 0
sp_qd_handle_on_invoice_upd_sts 9614 9614 0 0.00 0 14418 578 0 0
sp_qd_handle_on_reserve_upd_sts 9371 9371 0 0.00 0 35782 86 0 0
sp_reserve_write_off 9371 9371 0 0.00 7 35861 319 0 0
sp_supplier_invoice 4945 4945 0 0.00 7 101958 3836 0 0
sp_supplier_order 5020 5020 0 0.00 7 73498 1521 0 0
srv_aggregate_perf_data 96 96 0 0.00 2216 163385 18823 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 769 160365 35480 0 0
srv_find_qd_qs_mism 973504 973504 0 0.00 0 38813 10 0 0
srv_make_invnt_saldo 446 446 0 0.00 69 78160 4368 0 0
srv_make_money_saldo 854 854 0 0.00 1 17455 420 0 0
srv_recalc_idx_stat 4 4 0 0.00 216118 645727 386643 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4463 4463 0 0.00 1 12117 1008 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 2 7262 549 0 0
t$perf-norm:sp_cancel_client_order 1619 1619 0 0.00 0 1943 30 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 901 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 2838 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 2511 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 331 331 0 0.00 0 1198 41 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 106941 7547 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 8093 28 0 0
t$perf-norm:sp_client_order 18502 18502 0 0.00 0 8089 39 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 4335 22 0 0
t$perf-norm:sp_pay_from_customer 6488 6488 0 0.00 0 4518 19 0 0
t$perf-norm:sp_pay_to_supplier 5375 5375 0 0.00 0 14236 27 0 0
t$perf-norm:sp_reserve_write_off 9371 9371 0 0.00 0 4502 23 0 0
t$perf-norm:sp_supplier_invoice 4945 4945 0 0.00 0 3969 35 0 0
t$perf-norm:sp_supplier_order 5020 5020 0 0.00 0 10007 38 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 543 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 15 3 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1332 22 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 316 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 190 51 0 0
v_add_invoice_to_stock 9112 8822 290 3.18 0 10931 32 0 290
v_all_customers 34130 34130 0 0.00 0 78 4 0 0
v_all_suppliers 28206 28206 0 0.00 0 135 4 0 0
v_all_wares 37002 37002 0 0.00 0 113 4 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 1834 18 0 0
v_cancel_client_order 3238 3238 0 0.00 0 4575 15 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 2573 17 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 1 1602 17 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1138 14 0 0
v_cancel_supplier_invoice 684 662 22 3.21 0 943 28 0 22
v_cancel_supplier_order 828 828 0 0.00 0 2374 19 0 0
v_cancel_write_off 1818 1818 0 0.00 0 6375 29 0 0
v_max_id_clo_ord 5020 5020 0 0.00 0 3157 6 0 0
v_max_id_clo_res 975 975 0 0.00 0 1653 13 0 0
v_max_id_ord_sup 4938 4938 0 0.00 0 7819 9 0 0
v_max_non_paid_invoice 1230 1230 0 0.00 0 142 8 0 0
v_max_non_paid_realizn 3282 3282 0 0.00 0 278 7 0 0
v_min_id_clo_ord 5020 5020 0 0.00 0 1731 8 0 0
v_min_id_clo_res 975 975 0 0.00 0 2736 15 0 0
v_min_id_ord_sup 4938 4938 0 0.00 0 5847 9 0 0
v_min_non_paid_invoice 5375 5375 0 0.00 0 9155 23 0 0
v_min_non_paid_realizn 6488 6488 0 0.00 0 4318 16 0 0
v_our_firm 9284 9284 0 0.00 0 61 5 0 0
v_reserve_write_off 18742 18742 0 0.00 0 10944 14 0 0
x_lock_depdocs_on_canc_invoice 10659 10659 0 0.00 0 13150 127 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 7532 399 0 0
x_make_qstorno_1000_1200 5020 5020 0 0.00 3 73490 1348 0 0
x_make_qstorno_1200_2000 4945 4945 0 0.00 3 101899 3564 0 0
x_make_qstorno_2100_3300 423771 423771 0 0.00 0 57385 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 04:38:26 to 17-10-2022 04:38:30.

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:26 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 18492 8192000000 9721162048 10019422208 799001056 8.886 100 88 176 0 828825696 7803056 22021584
02:09:51 10661 8192000000 9759712000 10070937600 841473536 9.315 100 84 168 0 872726784 11276624 19976624
02:11:17 11260 8192000000 9801743312 10118672384 875284256 9.653 100 86 172 0 916988928 21790688 19913984
02:12:39 9187 8192000000 9867466880 10204483584 923148832 10.127 100 82 164 0 977604960 36269472 18186656
02:13:53 6838 8192000000 9927077376 10272522240 968324816 10.570 100 85 170 0 1037581248 51358704 17897728
02:15:02 1882 8192000000 9959281696 10313457664 995554800 10.835 100 86 170 0 1081717488 68140544 18022144
02:16:07 2454 8192000000 9976163472 10339168256 1010082976 10.976 100 86 169 0 1098564032 70612496 17868560
02:17:12 989 8192000000 9994470816 10362847232 1035618288 11.223 100 92 183 0 1114932576 60469184 18845104
02:18:15 1392 8192000000 10018181664 10397356032 1056587936 11.424 100 87 159 0 1137404416 64634032 16182448
02:19:20 1126 8192000000 10041343472 10420191232 1067155792 11.525 100 93 178 0 1164011472 78639696 18215984
02:20:23 1416 8192000000 10049389792 10425815040 1072238512 11.573 100 95 186 3 1172595280 82523440 17833328
02:21:26 1308 8192000000 10062000128 10441678848 1075509568 11.605 100 97 189 2 1181776480 88132032 18134880
02:22:29 981 8192000000 10054875168 10437828608 1082771472 11.674 100 97 178 0 1178777392 77944720 18061200
02:23:33 1044 8192000000 10061834192 10450284544 1090036352 11.743 100 94 176 1 1188507808 79851904 18619552
02:24:36 3725 8192000000 10050313888 10444148736 1090093904 11.744 100 91 170 1 1178184576 72211264 15879408
02:25:41 1179 8192000000 10072020144 10456788992 1101447680 11.851 100 95 182 1 1186190288 66558272 18184336
02:26:44 1171 8192000000 10094377792 10484523008 1102182352 11.858 100 96 185 0 1215223504 94210176 18830976
02:27:45 1029 8192000000 10062053328 10452910080 1101994560 11.857 100 94 186 2 1183791504 62357392 19439552
02:28:48 1184 8192000000 10049591680 10443284480 1100051760 11.838 100 63 122 2 1168585808 55407776 13126272
02:29:50 821 8192000000 10026845488 10414436352 1086450720 11.709 99 88 170 0 1150606048 46772848 17382480
02:30:51 1007 8192000000 10018630064 10406617088 1089929136 11.742 100 90 173 1 1139307200 31954464 17423600
02:31:53 7298 8192000000 10032578480 10423005184 1064025920 11.495 99 68 129 1 1158684416 81540384 13118112
02:33:02 1227 8192000000 10036522256 10419060736 1056426864 11.422 99 96 178 2 1158049888 84219024 17404000
02:34:05 1121 8192000000 10011680640 10382442496 1044241344 11.305 100 96 188 0 1133630000 69944544 19444112
02:35:07 1253 8192000000 9961044752 10323795968 1012728400 11.002 100 96 173 2 1082348112 52234240 17385472
02:36:12 2867 8192000000 9994112880 10378371072 1043302304 11.296 100 68 130 3 1118427104 62616448 12508352
02:37:15 1045 8192000000 9986650080 10364989440 1040760384 11.272 100 86 172 0 1107548016 49250704 17536928
02:38:22 15297 8192000000 10002077008 10401861632 1057706656 11.435 100 60 107 1 1124851312 56814416 10330240
02:39:46 6064 8192000000 10037745264 10418270208 1076281296 11.612 100 82 154 2 1158058352 65392288 16384768
02:41:01 3206 8192000000 10067169840 10453073920 1082607152 11.672 100 74 146 0 1186891328 88364352 15919824
02:42:12 8915 8192000000 10100207392 10494967808 1087754496 11.721 100 65 117 0 1210593584 110616032 12223056
02:43:25 5278 8192000000 10141530016 10534633472 1099574368 11.834 100 67 121 1 1254848432 142326912 12947152
02:44:35 2336 8192000000 10166666240 10555465728 1092857280 11.770 99 77 147 0 1279313168 170358416 16097472
02:45:42 1178 8192000000 10181873088 10599821312 1109610176 11.929 100 81 154 0 1306204112 179994800 16599136
02:46:47 1033 8192000000 10212188368 10601336832 1121916736 12.045 100 91 171 2 1322630848 183394960 17319152
02:47:53 923 8192000000 10236167072 10630733824 1143697792 12.250 100 95 183 0 1346703632 183326944 19678896
02:48:57 2013 8192000000 10267135856 10663133184 1147423168 12.285 100 86 166 1 1380264896 214914336 17927392
02:50:01 2004 8192000000 10315769504 10726748160 1169407184 12.491 100 87 168 1 1422158608 232578848 20172576
02:51:09 1001 8192000000 10315223328 10712039424 1181800512 12.607 100 92 184 0 1424578560 221000064 21777984
02:52:14 3603 8192000000 10364042192 10777841664 1185286224 12.639 100 86 164 1 1470051984 265506976 19258784
02:53:20 758 8192000000 10409588224 10840252416 1189067632 12.675 100 98 187 1 1516355552 305771232 21516688
02:54:24 776 8192000000 10403128544 10824454144 1196754720 12.746 100 94 182 2 1518357040 300287488 21314832
02:55:29 1932 8192000000 10465278672 10874507264 1207506448 12.846 100 95 186 1 1572081184 343801600 20773136
02:56:33 875 8192000000 10515564272 10946125824 1215683552 12.922 100 97 195 1 1617785456 379041616 23060288
02:57:38 1093 8192000000 10517096944 10951192576 1205183984 12.824 100 98 190 1 1618551200 389875776 23491440
02:58:44 3819 8192000000 10528599472 10973995008 1209408640 12.864 100 60 118 2 1637951920 413427184 15116096
03:00:02 1288 8192000000 10571454288 11008262144 1206327872 12.835 100 95 187 1 1672258384 443616720 22313792
03:01:06 1006 8192000000 10569556784 11023671296 1201977344 12.795 100 98 188 2 1673040672 448099744 22963584
03:02:11 2915 8192000000 10574268304 11030347776 1191801408 12.700 100 75 149 0 1681678384 472102464 17774512
03:03:23 18524 8192000000 10469997120 10963378176 1158627184 12.390 99 37 74 1 1577621904 408428688 10566032
03:04:46 12412 8192000000 10444183488 10900516864 1177059296 12.563 100 47 92 0 1546359008 357644208 11655504
03:06:02 15547 8192000000 10420776704 10874327040 1140757728 12.223 98 43 84 0 1502198256 351232288 10208240
03:07:20 16921 8192000000 10353288272 10834325504 1134314736 12.162 98 45 88 0 1450275344 305145216 10815392
03:08:37 19163 8192000000 10352696960 10812895232 1143875232 12.252 99 37 74 2 1456739888 303927296 8937360
03:09:59 14539 8192000000 10308536048 10786754560 1137043232 12.188 100 33 64 1 1424158528 277943584 9171712
03:11:18 20420 8192000000 10238263344 10691629056 1133304320 12.153 99 46 85 3 1326296400 182931520 10060560
03:12:39 18931 8192000000 10256763152 10722172928 1139617296 12.212 100 31 60 0 1365052112 217832560 7602256
03:14:00 13859 8192000000 10165784656 10585985024 1116242208 11.991 98 36 67 0 1247801456 123288528 8270720
03:15:24 23787 8192000000 10121309760 10564571136 1131045776 12.131 100 45 86 0 1223291344 81677232 10568336
03:16:49 72668 8192000000 10142519760 10576224256 1138732112 12.204 100 53 102 1 1251682096 101036528 11913456
03:19:14 43830 8192000000 10137106176 10612260864 1140487904 12.220 100 23 47 0 1243195264 97399296 5308064
03:21:06 55492 8192000000 10116934048 10580201472 1135872576 12.177 99 24 48 0 1209377936 68547456 4957904
03:23:02 95232 8192000000 10105073408 10574618624 1123571536 12.061 98 14 28 0 1206101568 80071952 2458080
03:25:38 78687 8192000000 10105163968 10559062016 1102828352 11.864 96 13 26 0 1161168384 55604400 2735632
03:28:00 160364 8192000000 10068903008 10551844864 1122426112 12.050 98 25 49 0 1209821312 82090928 5304272
03:31:52 51413 8192000000 10082407104 10523582464 1111343536 11.945 98 36 70 1 1173497664 54875968 7278160
03:33:46 56571 8192000000 10119769216 10551234560 1121368016 12.040 99 32 64 0 1206668832 78164928 7135888
03:35:44 28050 8192000000 10092821888 10569797632 1127601824 12.099 99 32 64 1 1194989488 59985792 7401872
03:37:13 73446 8192000000 10093586112 10561011712 1121133936 12.038 98 9 18 0 1199012720 75794928 2083856
03:39:28 70757 8192000000 10105263632 10626822144 1128980448 12.112 99 15 30 0 1235952736 103313856 3658432
03:42:02 89059 8192000000 10105939040 10644574208 1142750496 12.241 100 21 42 0 1252447712 105058464 4638752
03:44:31 113494 8192000000 10078219008 10547142656 1121218624 12.039 98 13 24 1 1159584928 36307040 2059264
03:47:25 45060 8192000000 10127342128 10574389248 1120226080 12.029 97 14 28 0 1181102320 57889712 2986528
03:49:37 20570 8192000000 10202280112 10642063360 1152227296 12.330 99 47 92 1 1285002912 122962640 9812976
03:51:04 108455 8192000000 10111740064 10564235264 1117215632 12.001 97 8 15 1 1218021056 98841152 1964272
03:53:59 47876 8192000000 10099106480 10581643264 1151694480 12.325 100 20 40 0 1233435920 77553920 4187520
03:55:48 52438 8192000000 10130913136 10614853632 1142148128 12.236 99 37 74 0 1215290912 65301872 7840912
03:57:46 58253 8192000000 10099865872 10561789952 1119213936 12.020 97 16 32 0 1172187872 49776400 3197536
04:00:15 79737 8192000000 10141978720 10594598912 1151330960 12.322 100 52 98 0 1225762400 62844528 11586912
04:03:14 36256 8192000000 10095034752 10581245952 1131835440 12.139 99 35 69 1 1194093808 55075392 7182976
04:04:53 69775 8192000000 10041747888 10489413632 1093914864 11.780 96 10 16 0 1133303600 37708320 1680416
04:07:03 68732 8192000000 10039576416 10517020672 1080667408 11.654 96 13 27 0 1123960096 40537104 2755584
04:09:13 71007 8192000000 10045334848 10494455808 1071779152 11.569 95 24 46 0 1112150384 35067312 5303920
04:11:36 34940 8192000000 10090561792 10560217088 1126593056 12.089 100 31 58 2 1206000592 72811152 6596384
04:13:21 101793 8192000000 10049089728 10543665152 1080701008 11.654 96 9 19 0 1109966016 27496512 1768496
04:16:04 29394 8192000000 10093197888 10535870464 1128639808 12.109 100 26 52 0 1191463136 57363856 5459472
04:17:42 34360 8192000000 10095885024 10524766208 1129325632 12.115 100 33 64 0 1203692800 68150976 6216192
04:19:18 23447 8192000000 10119907920 10568572928 1128567968 12.108 99 48 96 0 1207143104 68568448 10006688
04:20:48 36755 8192000000 10129999360 10567626752 1131231856 12.133 99 48 92 1 1223588384 82312592 10043936
04:22:29 16402 8192000000 10108812592 10525229056 1137634880 12.193 100 73 141 1 1215100912 63364576 14101456
04:23:46 9560 8192000000 10128161184 10558902272 1137821456 12.195 100 61 120 0 1218812464 68960880 12030128
04:24:57 20160 8192000000 10110452240 10535452672 1120491840 12.032 99 59 118 0 1196518688 64233392 11793456
04:26:19 19736 8192000000 10138760640 10580545536 1126560976 12.089 99 57 105 4 1213505296 76062640 10881680
04:28:09 111940 8192000000 10074365584 10563203072 1127392896 12.097 99 21 42 0 1184992752 52720336 4879520
04:31:01 41799 8192000000 10079142400 10583986176 1134454176 12.163 100 2 4 0 1183996800 49231744 310880
04:32:45 47157 8192000000 10087571968 10534465536 1142796848 12.242 100 17 34 0 1183933408 37658112 3478448
04:34:42 72783 8192000000 10075895472 10542972928 1097480128 11.814 96 20 40 0 1158271504 56342784 4448592
04:36:55 29893 8192000000 9263080464 9552723968 401669632 4.674 36 14 28 0 417457792 13161600 2626560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-10-2022 04:38:32 to 17-10-2022 04:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 04:38:49 to 17-10-2022 04:38:51.

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          207844
MON$OLDEST_ACTIVE               207845
MON$OLDEST_SNAPSHOT             207845
MON$NEXT_TRANSACTION            207845
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                       2078112
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

17.10.22 04:38:52. 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-10-17 04:38:55 No severe PSQL-related problems occured
17.10.22 04:38:56. End of report.