Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.2022 10: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 16416684 kB
MemAvailable 31463276 kB
Buffers 0 kB
Cached 15324644 kB
SwapCached 2648 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-05-17 10:37:13
DTS_END                         2022-05-17 10:37:13.4140
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/56808
TRN_ID                          216147
ATT_ID                          645
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: 8689.03 28780 1042683
customer order: creation 164.15 484 19698
customer order: refuse 14.23 380 1708
order to supplier: creation 43.48 1385 5218
order to supplier: removal 3.23 160474 387
invoice (draft): creation 43.31 3338 5197
invoice (draft): removal 46.25 991 5550
invoice accept: apply 40.73 27943 4887
invoice accept: cancel 42.63 1186 5115
customer reserve: creation 3807.87 263 456944
customer reserve: removal 4199.81 97 503977
realization accept: apply 80.43 287 9651
realization accept: cancel 77.56 153 9307
payment from customer: creation 56.97 190 6836
payment from customer: removal 5.65 139 678
payment to supplier: creation 47.21 204 5665
payment to supplier: removal 3.39 115 407
service: total inventory turnovers 3.66 4061 439
service: total monetary turnovers 7.59 333 911
service: aggregate perf. data 0.86 14258 103
service: refresh index statistics 0.04 359328 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-05-2022 10:39:13 to 17-05-2022 10:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10632
TEST_TIME 2 6886
TEST_TIME 3 6882
TEST_TIME 4 6268
TEST_TIME 5 6192
TEST_TIME 6 6502
TEST_TIME 7 6557
TEST_TIME 8 6959
TEST_TIME 9 7283
TEST_TIME 10 7299
TEST_TIME 11 7701
TEST_TIME 12 7766
TEST_TIME 13 8266
TEST_TIME 14 8225
TEST_TIME 15 8240
TEST_TIME 16 8189
TEST_TIME 17 8209
TEST_TIME 18 8316
TEST_TIME 19 8401
TEST_TIME 20 8393
TEST_TIME 21 8473
TEST_TIME 22 8431
TEST_TIME 23 8656
TEST_TIME 24 8931
TEST_TIME 25 9076
TEST_TIME 26 9050
TEST_TIME 27 9382
TEST_TIME 28 10024
TEST_TIME 29 10505
TEST_TIME 30 10889
TEST_TIME 31 11026
TEST_TIME 32 11332
TEST_TIME 33 11262
TEST_TIME 34 10951
TEST_TIME 35 10828
TEST_TIME 36 10969
TEST_TIME 37 10794
TEST_TIME 38 10643
TEST_TIME 39 10520
TEST_TIME 40 10355
TEST_TIME 41 10225
TEST_TIME 42 10135
TEST_TIME 43 9974
TEST_TIME 44 9947
TEST_TIME 45 9951
TEST_TIME 46 9845
TEST_TIME 47 9708
TEST_TIME 48 9677
TEST_TIME 49 9620
TEST_TIME 50 9578
TEST_TIME 51 9447
TEST_TIME 52 9347
TEST_TIME 53 9295
TEST_TIME 54 9188
TEST_TIME 55 9093
TEST_TIME 56 9114
TEST_TIME 57 9074
TEST_TIME 58 8999
TEST_TIME 59 8951
TEST_TIME 60 8960
TEST_TIME 61 9020
TEST_TIME 62 8999
TEST_TIME 63 8924
TEST_TIME 64 8995
TEST_TIME 65 9040
TEST_TIME 66 8991
TEST_TIME 67 9027
TEST_TIME 68 9064
TEST_TIME 69 9124
TEST_TIME 70 9134
TEST_TIME 71 9152
TEST_TIME 72 9275
TEST_TIME 73 9246
TEST_TIME 74 9189
TEST_TIME 75 9085
TEST_TIME 76 9043
TEST_TIME 77 9032
TEST_TIME 78 9023
TEST_TIME 79 8996
TEST_TIME 80 8945
TEST_TIME 81 8893
TEST_TIME 82 8821
TEST_TIME 83 8822
TEST_TIME 84 8802
TEST_TIME 85 8799
TEST_TIME 86 8854
TEST_TIME 87 8909
TEST_TIME 88 8931
TEST_TIME 89 9026
TEST_TIME 90 9090
TEST_TIME 91 9081
TEST_TIME 92 9100
TEST_TIME 93 9102
TEST_TIME 94 9090
TEST_TIME 95 9044
TEST_TIME 96 9013
TEST_TIME 97 8988
TEST_TIME 98 8941
TEST_TIME 99 8897
TEST_TIME 100 8876
TEST_TIME 101 8863
TEST_TIME 102 8841
TEST_TIME 103 8824
TEST_TIME 104 8832
TEST_TIME 105 8856
TEST_TIME 106 8852
TEST_TIME 107 8868
TEST_TIME 108 8856
TEST_TIME 109 8836
TEST_TIME 110 8909
TEST_TIME 111 8893
TEST_TIME 112 8903
TEST_TIME 113 8896
TEST_TIME 114 8910
TEST_TIME 115 8853
TEST_TIME 116 8823
TEST_TIME 117 8864
TEST_TIME 118 8835
TEST_TIME 119 8790
TEST_TIME 120 8722

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-05-2022 10:39:17 to 17-05-2022 10:39:21.

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 1462148 1462148 0 0.00 0 24217 14 0 0
doc_list_biud 1462135 1462135 0 0.00 0 54138 39 0 0
sp_add_invoice_to_stock 5042 4887 155 3.07 77 269258 27944 0 155
sp_cancel_adding_invoice 5115 5115 0 0.00 6 106852 1186 0 0
sp_cancel_client_order 1708 1708 0 0.00 7 10978 381 0 0
sp_cancel_customer_reserve 503977 503977 0 0.00 1 54141 98 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 5549 139 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 3253 116 0 0
sp_cancel_supplier_invoice 5562 5550 12 0.21 3 29723 992 0 12
sp_cancel_supplier_order 387 387 0 0.00 10 1318605 160474 0 0
sp_cancel_write_off 9307 9307 0 0.00 2 13626 154 0 0
sp_client_order 19698 19698 0 0.00 6 41643 484 0 0
sp_customer_reserve 456944 456944 0 0.00 1 47081 263 0 0
sp_fill_shopping_cart 30099 30099 0 0.00 2 13794 87 0 0
sp_fill_shopping_cart_clo_res 456904 456904 0 0.00 0 16613 22 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 26492 398 0 0
sp_kill_cost_storno 15507 15507 0 0.00 0 24209 71 0 0
sp_kill_qstorno_ret_qs2qd 1023198 1023198 0 0.00 0 54129 42 0 0
sp_kill_qty_storno 540525 540525 0 0.00 0 54137 97 0 0
sp_lock_selected_doc 20060 20060 0 0.00 0 6422 8 0 0
sp_make_cost_storno 26983 26983 0 0.00 0 20862 85 0 0
sp_multiply_rows_for_pdistr 26982 26982 0 0.00 0 20810 47 0 0
sp_multiply_rows_for_qdistr 19698 19698 0 0.00 0 30841 194 0 0
sp_pay_from_customer 6836 6836 0 0.00 5 16243 191 0 0
sp_pay_to_supplier 5665 5665 0 0.00 6 16745 205 0 0
sp_payment_common 12499 12499 0 0.00 2 8765 48 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 1 10948 309 0 0
sp_qd_handle_on_invoice_upd_sts 9945 9945 0 0.00 0 21684 504 0 0
sp_qd_handle_on_reserve_upd_sts 9651 9651 0 0.00 0 8189 64 0 0
sp_reserve_write_off 9651 9651 0 0.00 9 18545 287 0 0
sp_supplier_invoice 5197 5197 0 0.00 10 76327 3338 0 0
sp_supplier_order 5218 5218 0 0.00 8 33335 1385 0 0
srv_aggregate_perf_data 103 103 0 0.00 2434 137913 14259 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 688 113112 38393 0 0
srv_find_qd_qs_mism 994559 994559 0 0.00 0 20213 10 0 0
srv_make_invnt_saldo 439 439 0 0.00 97 73960 4061 0 0
srv_make_money_saldo 911 911 0 0.00 1 13838 333 0 0
srv_recalc_idx_stat 5 5 0 0.00 51642 522827 359329 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 1 11015 997 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 8491 402 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 1797 26 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 2861 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 798 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 183 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 7797 49 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 68131 6117 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1705 20 0 0
t$perf-norm:sp_client_order 19700 19700 0 0.00 0 6377 35 0 0
t$perf-norm:sp_customer_reserve 923 923 0 0.00 0 5839 23 0 0
t$perf-norm:sp_pay_from_customer 6837 6837 0 0.00 0 4931 20 0 0
t$perf-norm:sp_pay_to_supplier 5665 5665 0 0.00 0 6558 18 0 0
t$perf-norm:sp_reserve_write_off 9651 9651 0 0.00 0 9432 22 0 0
t$perf-norm:sp_supplier_invoice 5197 5197 0 0.00 0 6099 39 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 5188 32 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 255 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 2913 51 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 1266 14 0 0
t$perf-norm:srv_make_money_saldo 911 911 0 0.00 0 778 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 202 63 0 0
v_add_invoice_to_stock 9966 9656 310 3.11 0 10575 31 0 310
v_all_customers 36006 36006 0 0.00 0 60 4 0 0
v_all_suppliers 29042 29042 0 0.00 0 54 4 0 0
v_all_wares 39388 39388 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 980 15 0 0
v_cancel_client_order 3416 3416 0 0.00 0 2558 10 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 3250 16 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1513 12 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 3154 14 0 0
v_cancel_supplier_invoice 790 766 24 3.03 0 1015 23 0 24
v_cancel_supplier_order 774 774 0 0.00 0 710 13 0 0
v_cancel_write_off 1896 1896 0 0.00 0 648 21 0 0
v_max_id_clo_ord 5211 5211 0 0.00 0 391 5 0 0
v_max_id_clo_res 923 923 0 0.00 0 1908 13 0 0
v_max_id_ord_sup 5193 5193 0 0.00 0 8117 10 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 402 8 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 1125 7 0 0
v_min_id_clo_ord 5211 5211 0 0.00 0 10537 12 0 0
v_min_id_clo_res 923 923 0 0.00 0 1315 12 0 0
v_min_id_ord_sup 5193 5193 0 0.00 0 3153 8 0 0
v_min_non_paid_invoice 5663 5663 0 0.00 0 8688 21 0 0
v_min_non_paid_realizn 6835 6835 0 0.00 0 7810 18 0 0
v_our_firm 9866 9866 0 0.00 0 140 5 0 0
v_reserve_write_off 19300 19300 0 0.00 0 15878 11 0 0
x_lock_depdocs_on_canc_invoice 10282 10282 0 0.00 0 11001 135 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 29404 437 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 2 33311 1209 0 0
x_make_qstorno_1200_2000 5197 5197 0 0.00 3 71171 3110 0 0
x_make_qstorno_2100_3300 456943 456943 0 0.00 0 42608 229 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-05-2022 10:39:21 to 17-05-2022 10:39:26.

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
08:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:17 33228 8192000000 9731749008 10032689152 811057760 9.008 100 89 178 0 840541568 7578800 21905008
08:10:01 20003 8192000000 9772093056 10078560256 842249744 9.322 100 96 192 0 880131824 15065600 22816480
08:11:35 11218 8192000000 9814247136 10136158208 869427680 9.594 100 81 162 2 918328048 30279632 18620736
08:12:58 11638 8192000000 9889507648 10223763456 923054144 10.126 100 84 167 1 997824240 56694080 18076016
08:14:12 3000 8192000000 9926527088 10270093312 959788432 10.487 100 93 177 3 1047740352 69920560 18031360
08:15:18 1082 8192000000 9979414400 10334998528 991776384 10.799 100 89 178 0 1091048208 81473968 17797856
08:16:21 1044 8192000000 9994502048 10358652928 1022452416 11.096 100 92 183 1 1119060912 78219328 18389168
08:17:24 1167 8192000000 10026516704 10399645696 1037927376 11.245 100 96 182 2 1144182064 88492752 17761936
08:18:28 990 8192000000 10027809760 10404917248 1049986256 11.361 100 95 176 1 1148338784 80062080 18290448
08:19:31 931 8192000000 10054632128 10432069632 1058193056 11.439 100 99 192 0 1171876768 94995296 18688416
08:20:33 1168 8192000000 10040267072 10416652288 1070489056 11.557 100 95 190 0 1160041776 71209616 18343104
08:21:35 1290 8192000000 10035336816 10416300032 1077316240 11.622 100 95 183 0 1158849360 63073248 18459872
08:22:37 1165 8192000000 10049346848 10431234048 1082260432 11.669 100 99 185 0 1169057552 69404144 17392976
08:23:40 1251 8192000000 10047287920 10433101824 1088028368 11.724 100 94 185 1 1169027776 62005664 18993744
08:24:41 923 8192000000 10022800208 10411397120 1082385008 11.670 100 95 175 1 1148698912 47810592 18503312
08:25:43 2166 8192000000 10065124320 10465566720 1090317712 11.746 100 74 146 1 1189303616 83189936 15795968
08:26:46 1151 8192000000 10065458592 10459873280 1092985568 11.771 100 78 146 0 1190094288 81867808 15240912
08:27:48 1178 8192000000 10063966144 10456354816 1092740576 11.769 100 95 182 2 1186405088 73669232 19995280
08:28:51 1174 8192000000 10032907568 10416779264 1069244816 11.545 99 93 176 0 1152166032 64843632 18077584
08:29:53 1276 8192000000 10043555760 10431180800 1080030528 11.648 100 96 176 0 1164395840 66037488 18327824
08:30:55 999 8192000000 10030862736 10414546944 1069398656 11.546 100 94 180 1 1150985760 62647616 18939488
08:31:57 2017 8192000000 9976156128 10357055488 1035966848 11.226 99 78 147 4 1090481120 39786128 14728144
08:33:00 1767 8192000000 9992113904 10369732608 1025218704 11.122 99 68 131 1 1109933088 70825904 13888480
08:34:03 841 8192000000 9974561536 10348589056 1025357168 11.124 100 79 145 0 1098863312 59379184 14126960
08:35:04 1214 8192000000 9984353024 10351824896 1024807376 11.118 100 95 185 1 1105920112 62261664 18851072
08:36:07 1115 8192000000 9989942656 10360963072 1027357600 11.143 100 90 172 2 1109812944 64628336 17827008
08:37:10 1295 8192000000 10009246656 10382073856 1050560960 11.366 100 94 178 2 1135686640 67139920 17985760
08:38:20 13062 8192000000 10019020704 10403291136 1071035888 11.562 100 68 125 0 1146897808 63560016 12301904
08:39:37 6660 8192000000 10055702048 10444025856 1087148800 11.716 100 67 128 1 1174904336 75172128 12583408
08:40:50 1493 8192000000 10060397440 10451525632 1091086032 11.753 100 67 126 0 1182183840 78333120 12764688
08:41:55 8018 8192000000 10112747664 10514857984 1104962064 11.885 100 65 129 0 1222786608 104842608 12981936
08:43:06 1383 8192000000 10116360896 10516115456 1111155968 11.943 100 91 171 0 1237050608 108227536 17667104
08:44:15 1970 8192000000 10148719376 10541350912 1118141472 12.009 100 79 154 0 1265327936 130341936 16844528
08:45:20 1322 8192000000 10171132976 10583814144 1123865328 12.063 100 94 184 0 1286638704 142601616 20171760
08:46:26 1402 8192000000 10196961712 10597695488 1137065984 12.188 100 86 167 1 1314639328 158760064 18813280
08:47:35 1337 8192000000 10251466784 10650165248 1152848304 12.336 100 86 160 2 1362390672 192300560 17241808
08:48:39 1170 8192000000 10258790336 10666053632 1156043280 12.366 100 89 169 0 1368854560 193659920 19151360
08:49:42 1242 8192000000 10263483584 10675621888 1160666720 12.410 100 95 188 0 1373785888 192368256 20750912
08:50:47 969 8192000000 10267098560 10679160832 1163096976 12.432 100 96 182 0 1375909856 190660816 22152064
08:51:51 1883 8192000000 10277438576 10688208896 1167474624 12.473 100 83 165 1 1384465664 198323632 18667408
08:52:58 2103 8192000000 10317160784 10725318656 1174835024 12.542 100 87 164 0 1420957296 227801536 18320736
08:54:02 1473 8192000000 10356516848 10765512704 1182540688 12.614 100 95 190 3 1467874320 262512624 22821008
08:55:06 1048 8192000000 10392592416 10806575104 1185237536 12.639 100 99 194 1 1496820544 288125776 23457232
08:56:09 785 8192000000 10428397392 10834444288 1177742672 12.569 100 99 193 0 1534192192 333806640 22642880
08:57:11 989 8192000000 10469259696 10871353344 1166893056 12.468 100 95 189 1 1574722576 386206976 21622544
08:58:14 1064 8192000000 10506768944 10915553280 1169105248 12.488 100 98 192 1 1611160016 419363872 22690896
08:59:17 1333 8192000000 10544629648 10957434880 1168249456 12.480 100 94 186 1 1651639104 460304304 23085344
09:00:24 681 8192000000 10537845152 10961743872 1160255552 12.406 100 99 197 0 1640999696 457359664 23384480
09:01:27 3742 8192000000 10484715264 10954604544 1148714368 12.297 99 62 117 1 1594172080 430005040 15452672
09:02:36 14702 8192000000 10513816240 10951340032 1133745776 12.157 99 50 98 0 1607560336 460934592 12879968
09:04:00 19284 8192000000 10504873104 10972811264 1139071472 12.207 100 33 64 2 1625535920 477250848 9213600
09:05:45 38939 8192000000 10258530688 10722758656 1108308752 11.916 98 25 50 2 1377055568 261997088 6749728
09:07:24 40705 8192000000 10204677376 10651959296 1134606816 12.165 100 27 52 2 1297769056 157135376 6026864
09:09:47 77169 8192000000 10119143616 10555498496 1134028368 12.159 99 24 47 0 1239442704 100760112 4654224
09:12:42 72059 8192000000 10088787344 10586681344 1122678752 12.052 98 20 40 2 1194725952 68197504 3849696
09:14:55 88462 8192000000 10050470880 10494615552 1060143808 11.458 93 16 33 1 1090350832 27111520 3095504
09:17:28 44213 8192000000 10072570880 10504888320 1116897568 11.998 98 50 99 1 1153424448 25276304 11250576
09:19:12 45339 8192000000 10097889696 10558058496 1127053152 12.094 99 28 56 0 1201413424 68574912 5785360
09:21:40 52663 8192000000 10071169520 10531786752 1120874816 12.035 99 23 42 1 1166516432 41673072 3968544
09:23:45 49487 8192000000 10077013824 10506715136 1119689872 12.024 99 38 76 2 1170059808 42244288 8125648
09:25:37 32032 8192000000 10074261504 10526654464 1125043872 12.075 100 26 52 1 1175865152 44906144 5915136
09:27:20 76771 8192000000 10063245136 10518765568 1119987168 12.027 99 10 20 0 1167664576 45552208 2125200
09:29:37 92672 8192000000 10109031696 10584485888 1141758016 12.232 100 17 32 0 1208442944 63476672 3208256
09:32:37 68395 8192000000 10070796592 10532728832 1114614944 11.976 98 34 66 3 1202784384 80900784 7268656
09:34:53 13604 8192000000 10096946256 10576310272 1133693520 12.156 100 44 87 2 1193513584 49458896 10361168
09:36:14 69090 8192000000 10138387008 10629410816 1125593904 12.080 99 18 36 0 1207850224 78124496 4131824
09:38:23 42871 8192000000 10071978944 10513608704 1116326704 11.992 98 18 34 2 1160640784 41011552 3302528
09:40:15 96903 8192000000 10110646896 10592366592 1102355024 11.860 97 19 38 1 1197088560 90312352 4421184
09:42:55 56810 8192000000 10146840736 10592722944 1136310752 12.181 99 31 62 0 1224928928 81426304 7191872
09:44:52 66076 8192000000 10124279232 10588557312 1128273136 12.105 99 19 38 0 1231609856 99374992 3961728
09:47:08 36414 8192000000 10128983840 10600251392 1153994848 12.347 100 37 74 1 1235190112 73854720 7340544
09:48:45 110636 8192000000 10059817648 10519998464 1096256080 11.802 96 20 38 0 1162090160 61840928 3993152
09:51:36 30846 8192000000 10090407536 10522054656 1126074208 12.084 100 58 116 0 1179950528 42482400 11393920
09:53:08 110309 8192000000 10084097696 10553683968 1113601024 11.966 98 22 44 1 1177354208 59305792 4447392
09:56:01 34902 8192000000 10079045616 10515955712 1094241984 11.783 96 26 50 0 1156944704 57539168 5163552
09:58:02 50843 8192000000 10147796384 10592022528 1120318288 12.030 98 27 54 2 1215445280 89056544 6070448
09:59:55 87018 8192000000 10197026720 10675535872 1092916112 11.770 96 23 44 0 1274482960 175619056 5947792
10:02:25 39226 8192000000 10212082480 10647912448 1125960128 12.083 99 32 64 0 1287436032 153804016 7671888
10:04:06 59780 8192000000 10144719440 10608955392 1110466832 11.937 99 21 42 1 1257045328 141933408 4645088
10:06:06 113112 8192000000 10106022560 10606645248 1090655296 11.749 97 11 22 1 1194977440 101498160 2823984
10:09:03 5439 8192000000 10101307664 10527600640 1129438544 12.116 100 55 101 1 1192809616 52779088 10591984
10:10:09 29737 8192000000 10124176128 10553077760 1126263984 12.086 99 48 96 0 1184699552 48937472 9498096
10:11:42 6789 8192000000 10096515104 10506391552 1126835200 12.092 100 76 144 2 1189326144 48463600 14027344
10:12:58 60821 8192000000 10063028368 10517622784 1108638144 11.920 98 44 86 1 1167156224 48900416 9617664
10:15:02 56349 8192000000 10066553856 10522165248 1125923776 12.083 99 14 28 0 1157842480 28863904 3054800
10:16:59 83513 8192000000 10073383184 10553909248 1129884096 12.120 99 7 14 0 1169910688 38319760 1706832
10:19:23 62329 8192000000 10090777584 10527449088 1060540896 11.462 93 29 58 0 1114303856 47415376 6347584
10:21:28 30737 8192000000 10119583616 10583789568 1126374784 12.087 100 27 54 1 1205005088 72488176 6142128
10:23:07 33013 8192000000 10064440912 10492014592 1107856496 11.912 98 36 72 0 1144559280 29670192 7032592
10:24:43 90221 8192000000 10097121120 10533150720 1115977504 11.989 99 30 60 2 1196357584 74111584 6268496
10:27:14 51560 8192000000 10092823776 10597339136 1133041296 12.150 100 36 72 0 1236828688 96402192 7385200
10:29:06 39091 8192000000 10093890160 10543276032 1124200128 12.067 99 42 84 1 1203182304 70557232 8424944
10:30:46 10895 8192000000 10117082480 10546515968 1119112016 12.019 99 46 92 0 1188342400 60248784 8981600
10:31:59 97423 8192000000 10127932960 10561888256 1108525360 11.918 98 47 92 0 1226927488 108683776 9718352
10:34:38 64786 8192000000 10041848560 10542682112 1109047024 11.923 99 16 32 1 1150785504 38366736 3371744
10:36:43 39565 8192000000 9366868176 9698258944 484555264 5.584 44 6 12 0 523706784 37577840 1573680

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-05-2022 10:39:27 to 17-05-2022 10:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 10:39:44 to 17-05-2022 10:39:46.

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          216146
MON$OLDEST_ACTIVE               217593
MON$OLDEST_SNAPSHOT             217593
MON$NEXT_TRANSACTION            217593
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                       2048288
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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.05.22 10:39:47. 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-05-17 10:39:50 No severe PSQL-related problems occured
17.05.22 10:39:51. End of report.