Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.03.2022 07:50
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 16270588 kB
MemAvailable 31663996 kB
Buffers 0 kB
Cached 15459284 kB
SwapCached 2152 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-03-26 07:48:14
DTS_END                         2022-03-26 07:48:14.7920
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/37914
TRN_ID                          205276
ATT_ID                          560
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: 8811.78 26308 1057413
customer order: creation 157.23 547 18868
customer order: refuse 13.39 514 1607
order to supplier: creation 41.99 1525 5039
order to supplier: removal 3.35 176325 402
invoice (draft): creation 41.08 3662 4929
invoice (draft): removal 51.64 1070 6197
invoice accept: apply 38.86 26785 4663
invoice accept: cancel 46.84 1374 5621
customer reserve: creation 3570.38 251 428445
customer reserve: removal 4574.68 101 548962
realization accept: apply 76.53 348 9184
realization accept: cancel 76.78 177 9213
payment from customer: creation 54.23 229 6507
payment from customer: removal 5.34 188 641
payment to supplier: creation 44.73 235 5367
payment to supplier: removal 3.33 158 399
service: total inventory turnovers 3.13 5815 376
service: total monetary turnovers 7.39 375 887
service: aggregate perf. data 0.85 20385 102
service: refresh index statistics 0.03 286100 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-03-2022 07:50:55 to 26-03-2022 07:50:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6669
TEST_TIME 2 7354
TEST_TIME 3 6145
TEST_TIME 4 6720
TEST_TIME 5 6014
TEST_TIME 6 5711
TEST_TIME 7 5899
TEST_TIME 8 5820
TEST_TIME 9 6080
TEST_TIME 10 6442
TEST_TIME 11 6633
TEST_TIME 12 6689
TEST_TIME 13 7125
TEST_TIME 14 7261
TEST_TIME 15 7349
TEST_TIME 16 7641
TEST_TIME 17 7934
TEST_TIME 18 7883
TEST_TIME 19 7859
TEST_TIME 20 7903
TEST_TIME 21 8276
TEST_TIME 22 8449
TEST_TIME 23 8841
TEST_TIME 24 8757
TEST_TIME 25 9082
TEST_TIME 26 8974
TEST_TIME 27 8958
TEST_TIME 28 8901
TEST_TIME 29 9119
TEST_TIME 30 9268
TEST_TIME 31 9515
TEST_TIME 32 9465
TEST_TIME 33 9384
TEST_TIME 34 9927
TEST_TIME 35 9884
TEST_TIME 36 10168
TEST_TIME 37 9904
TEST_TIME 38 9842
TEST_TIME 39 9884
TEST_TIME 40 10044
TEST_TIME 41 10355
TEST_TIME 42 10443
TEST_TIME 43 10293
TEST_TIME 44 10214
TEST_TIME 45 10214
TEST_TIME 46 10084
TEST_TIME 47 9977
TEST_TIME 48 9858
TEST_TIME 49 9838
TEST_TIME 50 9892
TEST_TIME 51 9931
TEST_TIME 52 9885
TEST_TIME 53 9835
TEST_TIME 54 9713
TEST_TIME 55 9630
TEST_TIME 56 9546
TEST_TIME 57 9527
TEST_TIME 58 9479
TEST_TIME 59 9354
TEST_TIME 60 9332
TEST_TIME 61 9284
TEST_TIME 62 9349
TEST_TIME 63 9346
TEST_TIME 64 9270
TEST_TIME 65 9184
TEST_TIME 66 9120
TEST_TIME 67 9222
TEST_TIME 68 9172
TEST_TIME 69 9135
TEST_TIME 70 9090
TEST_TIME 71 9163
TEST_TIME 72 9153
TEST_TIME 73 9088
TEST_TIME 74 9023
TEST_TIME 75 9190
TEST_TIME 76 9119
TEST_TIME 77 9243
TEST_TIME 78 9272
TEST_TIME 79 9307
TEST_TIME 80 9246
TEST_TIME 81 9373
TEST_TIME 82 9427
TEST_TIME 83 9468
TEST_TIME 84 9378
TEST_TIME 85 9360
TEST_TIME 86 9356
TEST_TIME 87 9408
TEST_TIME 88 9380
TEST_TIME 89 9321
TEST_TIME 90 9256
TEST_TIME 91 9205
TEST_TIME 92 9244
TEST_TIME 93 9226
TEST_TIME 94 9168
TEST_TIME 95 9139
TEST_TIME 96 9135
TEST_TIME 97 9081
TEST_TIME 98 9073
TEST_TIME 99 9043
TEST_TIME 100 8992
TEST_TIME 101 8923
TEST_TIME 102 8916
TEST_TIME 103 8931
TEST_TIME 104 8908
TEST_TIME 105 8905
TEST_TIME 106 8889
TEST_TIME 107 8904
TEST_TIME 108 8941
TEST_TIME 109 8937
TEST_TIME 110 8939
TEST_TIME 111 8955
TEST_TIME 112 9004
TEST_TIME 113 9011
TEST_TIME 114 9041
TEST_TIME 115 9034
TEST_TIME 116 8960
TEST_TIME 117 8937
TEST_TIME 118 8941
TEST_TIME 119 8903
TEST_TIME 120 8840

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-03-2022 07:50:59 to 26-03-2022 07:51:02.

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 1452592 1452592 0 0.00 0 33725 14 0 0
doc_list_biud 1452573 1452573 0 0.00 0 51633 45 0 0
sp_add_invoice_to_stock 4798 4663 135 2.81 55 176378 26785 0 135
sp_cancel_adding_invoice 5621 5621 0 0.00 5 114532 1375 0 0
sp_cancel_client_order 1607 1607 0 0.00 6 10253 514 0 0
sp_cancel_customer_reserve 548962 548962 0 0.00 1 51636 101 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 4 12109 188 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 2387 158 0 0
sp_cancel_supplier_invoice 6208 6197 11 0.17 3 18916 1071 0 11
sp_cancel_supplier_order 402 402 0 0.00 8 1256462 176326 0 0
sp_cancel_write_off 9213 9213 0 0.00 2 16332 177 0 0
sp_client_order 18868 18868 0 0.00 6 34775 547 0 0
sp_customer_reserve 428445 428445 0 0.00 0 71024 251 0 0
sp_fill_shopping_cart 28824 28824 0 0.00 1 11538 101 0 0
sp_fill_shopping_cart_clo_res 428397 428397 0 0.00 0 23589 20 0 0
sp_get_clo_for_invoice 4613 4613 0 0.00 0 40936 357 0 0
sp_kill_cost_storno 15874 15874 0 0.00 0 33713 91 0 0
sp_kill_qstorno_ret_qs2qd 1113736 1113736 0 0.00 0 51630 44 0 0
sp_kill_qty_storno 585799 585799 0 0.00 0 51633 103 0 0
sp_lock_selected_doc 19059 19059 0 0.00 0 9257 8 0 0
sp_make_cost_storno 25671 25671 0 0.00 0 13398 101 0 0
sp_multiply_rows_for_pdistr 25671 25671 0 0.00 0 11042 46 0 0
sp_multiply_rows_for_qdistr 18868 18868 0 0.00 0 11858 181 0 0
sp_pay_from_customer 6507 6507 0 0.00 5 21074 230 0 0
sp_pay_to_supplier 5367 5367 0 0.00 6 21503 235 0 0
sp_payment_common 11874 11874 0 0.00 2 13316 57 0 0
sp_qd_handle_on_cancel_clo 1607 1607 0 0.00 0 10246 428 0 0
sp_qd_handle_on_invoice_upd_sts 10234 10234 0 0.00 0 19684 609 0 0
sp_qd_handle_on_reserve_upd_sts 9184 9184 0 0.00 0 10063 75 0 0
sp_reserve_write_off 9184 9184 0 0.00 9 20028 349 0 0
sp_supplier_invoice 4929 4929 0 0.00 9 84275 3663 0 0
sp_supplier_order 5039 5039 0 0.00 7 34247 1526 0 0
srv_aggregate_perf_data 102 102 0 0.00 2832 149982 20385 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 831 195169 33628 0 0
srv_find_qd_qs_mism 1012763 1012763 0 0.00 0 23079 9 0 0
srv_make_invnt_saldo 376 376 0 0.00 84 108474 5816 0 0
srv_make_money_saldo 887 887 0 0.00 1 10274 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 93560 374301 286100 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4668 4668 0 0.00 0 21513 1184 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 2 9389 705 0 0
t$perf-norm:sp_cancel_client_order 1607 1607 0 0.00 0 6103 42 0 0
t$perf-norm:sp_cancel_customer_reserve 578 578 0 0.00 0 2995 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 2225 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 2444 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2492 46 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 113096 8592 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 6516 39 0 0
t$perf-norm:sp_client_order 18868 18868 0 0.00 0 6617 45 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 2007 20 0 0
t$perf-norm:sp_pay_from_customer 6507 6507 0 0.00 0 6856 29 0 0
t$perf-norm:sp_pay_to_supplier 5367 5367 0 0.00 0 3994 24 0 0
t$perf-norm:sp_reserve_write_off 9184 9184 0 0.00 0 6000 29 0 0
t$perf-norm:sp_supplier_invoice 4929 4929 0 0.00 0 9189 40 0 0
t$perf-norm:sp_supplier_order 5039 5039 0 0.00 0 6033 45 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 263 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 28 4 0 0
t$perf-norm:srv_make_invnt_saldo 376 376 0 0.00 0 723 15 0 0
t$perf-norm:srv_make_money_saldo 887 887 0 0.00 0 460 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 76 40 0 0
v_add_invoice_to_stock 9496 9226 270 2.84 0 11232 31 0 270
v_all_customers 34876 34876 0 0.00 0 95 4 0 0
v_all_suppliers 27786 27786 0 0.00 0 60 5 0 0
v_all_wares 37736 37736 0 0.00 0 63 5 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 13211 32 0 0
v_cancel_client_order 3214 3214 0 0.00 0 916 12 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 11619 29 0 0
v_cancel_customer_reserve 1156 1156 0 0.00 0 2943 18 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 902 14 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 1376 25 0 22
v_cancel_supplier_order 804 804 0 0.00 0 4006 17 0 0
v_cancel_write_off 1760 1760 0 0.00 0 2989 24 0 0
v_max_id_clo_ord 5034 5034 0 0.00 0 5315 8 0 0
v_max_id_clo_res 919 919 0 0.00 0 4518 20 0 0
v_max_id_ord_sup 4922 4922 0 0.00 0 2659 7 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 6996 14 0 0
v_max_non_paid_realizn 3262 3262 0 0.00 0 5774 13 0 0
v_min_id_clo_ord 5034 5034 0 0.00 0 1906 10 0 0
v_min_id_clo_res 919 919 0 0.00 0 7573 24 0 0
v_min_id_ord_sup 4922 4922 0 0.00 0 5228 10 0 0
v_min_non_paid_invoice 5367 5367 0 0.00 0 8468 20 0 0
v_min_non_paid_realizn 6507 6507 0 0.00 0 9515 23 0 0
v_our_firm 9350 9350 0 0.00 0 81 5 0 0
v_reserve_write_off 18368 18368 0 0.00 0 19228 14 0 0
x_lock_depdocs_on_canc_invoice 11447 11447 0 0.00 0 10327 125 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7792 390 0 0
x_make_qstorno_1000_1200 5039 5039 0 0.00 2 34195 1305 0 0
x_make_qstorno_1200_2000 4929 4929 0 0.00 5 84255 3391 0 0
x_make_qstorno_2100_3300 428445 428445 0 0.00 0 71015 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-03-2022 07:51:03 to 26-03-2022 07:51:07.

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
05:18:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:19 29821 8192000000 9717213040 10015080448 793189696 8.827 100 91 182 0 824197120 8429744 22577680
05:20:55 24300 8192000000 9765682336 10075815936 840497920 9.305 100 89 177 1 873265248 12221920 20545408
05:22:27 19314 8192000000 9798770080 10121023488 870997840 9.610 100 82 164 2 907823408 18146352 18679216
05:23:56 8716 8192000000 9871433088 10205237248 919900816 10.095 100 85 167 1 983054400 45316736 17836848
05:25:08 5292 8192000000 9923847152 10268123136 954094736 10.431 100 87 169 1 1044587360 72858368 17634256
05:26:18 3560 8192000000 9959778112 10308710400 991681696 10.798 100 95 186 0 1075321632 64326880 19313056
05:27:24 1531 8192000000 10016893648 10377654272 1019146944 11.064 100 93 178 1 1133774672 96531392 18096336
05:28:27 1106 8192000000 10014394880 10380705792 1041386208 11.278 100 95 189 0 1132538784 71743792 19408784
05:29:29 1234 8192000000 10027134384 10399416320 1057264016 11.430 100 95 184 2 1151473168 74310848 19898304
05:30:31 1153 8192000000 10052151776 10431438848 1067527200 11.528 100 95 189 0 1173025776 86195072 19303504
05:31:34 5935 8192000000 10023426064 10403921920 1072518752 11.576 100 88 162 3 1143951056 55600672 15831632
05:32:41 7521 8192000000 10042980624 10429853696 1076145488 11.611 100 70 138 0 1172457584 82323936 13988160
05:33:54 923 8192000000 10054991184 10441060352 1083990976 11.685 100 88 175 0 1174917728 73315104 17611648
05:34:57 1281 8192000000 10070026672 10456645632 1090100448 11.744 100 95 183 1 1188717856 79652976 18964432
05:36:00 1287 8192000000 10068794320 10455805952 1094047904 11.781 100 97 185 0 1189006512 75055088 19903520
05:37:02 2522 8192000000 10056668128 10452619264 1096108688 11.801 100 67 130 2 1181737488 72118944 13509856
05:38:06 1145 8192000000 10063068560 10448470016 1080739984 11.655 99 95 182 0 1186001072 86498752 18762336
05:39:08 1158 8192000000 10029995232 10416857088 1082136512 11.668 100 87 169 1 1152218064 52981616 17099936
05:40:10 1087 8192000000 10057591952 10444591104 1092596720 11.767 100 91 173 1 1177351072 67342656 17411696
05:41:13 2190 8192000000 10009680704 10398728192 1073375360 11.584 100 74 135 1 1132314800 45271696 13667744
05:42:16 3522 8192000000 10018294688 10409672704 1074609648 11.596 100 76 145 1 1139076816 49618112 14849056
05:43:20 3554 8192000000 9993682544 10376667136 1050905440 11.369 99 67 128 0 1132092480 67898880 13288160
05:44:24 2527 8192000000 9993012192 10382200832 1048209296 11.343 100 70 139 1 1116003040 53134576 14659168
05:45:28 1228 8192000000 9983964960 10361458688 1025047168 11.121 98 76 149 5 1109142096 68716128 15378800
05:46:30 1193 8192000000 9941440832 10312708096 1017975680 11.052 99 79 152 2 1065446640 30606896 16864064
05:47:32 1243 8192000000 9983490992 10353111040 1030992592 11.178 100 94 179 0 1104411536 53405520 20013424
05:48:35 15037 8192000000 9998422144 10373627904 1044683648 11.310 100 67 123 1 1111354640 54061616 12609376
05:49:56 1365 8192000000 10035325712 10409848832 1067584784 11.529 100 92 174 2 1153565472 67308192 18672496
05:51:02 4400 8192000000 10062495824 10439639040 1090228624 11.745 100 84 152 5 1184737840 79470688 15038528
05:52:18 1797 8192000000 10121015392 10510811136 1105985600 11.894 100 81 153 0 1241620752 119128032 16507120
05:53:22 4274 8192000000 10112712800 10504646656 1115274912 11.982 100 65 118 1 1240276224 112616432 12384880
05:54:37 3911 8192000000 10177918752 10571767808 1122234768 12.048 100 82 157 1 1294644896 154165088 18245040
05:55:49 4439 8192000000 10168985952 10565423104 1126583984 12.089 100 69 127 1 1293555584 154504672 12466928
05:56:58 2546 8192000000 10246187312 10644320256 1145078160 12.263 100 79 153 0 1366913392 205190848 16644384
05:58:03 1333 8192000000 10290727072 10688831488 1153043872 12.338 100 96 185 1 1408054352 234042688 20967792
05:59:08 1353 8192000000 10298021520 10704912384 1154958304 12.356 100 82 160 1 1408101344 235807872 17335168
06:00:13 1418 8192000000 10320933952 10719756288 1153323424 12.341 100 95 178 1 1430365120 257155408 19886288
06:01:18 874 8192000000 10312813248 10732695552 1161231872 12.415 100 98 193 1 1427825760 244217504 22376384
06:02:26 1140 8192000000 10311794032 10723536896 1172365472 12.519 100 96 192 0 1422733664 229919376 20448816
06:03:31 975 8192000000 10311406432 10746032128 1169916704 12.496 100 92 175 1 1422735584 232146912 20671968
06:04:38 1566 8192000000 10344895920 10776113152 1177612800 12.568 100 90 179 0 1453091040 255890352 19587888
06:05:45 1178 8192000000 10344853600 10762350592 1188687296 12.671 100 97 186 2 1452051296 240203584 23160416
06:06:49 885 8192000000 10388121824 10799886336 1188238048 12.667 100 97 184 3 1493744944 283353200 22153696
06:07:53 1118 8192000000 10385227888 10824441856 1183364016 12.622 100 89 172 1 1493894160 290438928 20091216
06:08:58 828 8192000000 10414337408 10864414720 1193060400 12.712 100 93 182 2 1512506304 298464800 20981104
06:10:02 1104 8192000000 10389886176 10832371712 1183331424 12.621 100 96 190 2 1491057136 285420704 22305008
06:11:06 1034 8192000000 10368632160 10797690880 1169068912 12.488 100 88 160 1 1473372672 286165040 18138720
06:12:12 7828 8192000000 10354388608 10781806592 1158429376 12.389 99 46 90 0 1460637168 291296032 10911760
06:13:24 7389 8192000000 10322318656 10764288000 1158267152 12.387 100 47 94 0 1438340256 268396336 11676768
06:14:43 2563 8192000000 10356800944 10777739264 1166944656 12.468 99 71 141 0 1459470304 275330800 17194848
06:15:49 1038 8192000000 10425471584 10834198528 1187860000 12.663 100 96 187 2 1531949088 321836400 22252688
06:16:53 1472 8192000000 10424178192 10879938560 1186774032 12.653 100 99 192 0 1524092656 315051104 22267520
06:17:57 1373 8192000000 10439867968 10893123584 1178488224 12.576 100 87 171 0 1539742320 341160048 20094048
06:19:01 7554 8192000000 10440579328 10897227776 1186703968 12.653 100 55 110 0 1547911264 346955744 14251552
06:20:27 13580 8192000000 10432918320 10880860160 1167563648 12.474 100 36 72 0 1547313392 370340784 9408960
06:21:44 14873 8192000000 10440897728 10909450240 1160129376 12.404 100 42 84 0 1564471728 393773744 10568608
06:23:01 15661 8192000000 10368003440 10827796480 1177288320 12.565 100 37 74 0 1483640416 297678144 8673952
06:24:28 39816 8192000000 10321434944 10804514816 1139563648 12.211 98 35 70 3 1449517456 300276992 9676816
06:26:10 19416 8192000000 10364185456 10808504320 1160055600 12.404 100 43 82 0 1460027472 290439968 9531904
06:27:49 36124 8192000000 10296656256 10772340736 1117315392 12.002 98 29 58 0 1406807568 282518976 6973200
06:29:26 93753 8192000000 10091900384 10606166016 1110342176 11.936 98 8 16 0 1181692400 69595424 1754800
06:32:00 36609 8192000000 10097800176 10525638656 1105496320 11.890 98 40 79 0 1164212944 49777008 8939616
06:33:41 36426 8192000000 10074869744 10509459456 1130756912 12.128 100 28 56 1 1165451536 29014544 5680080
06:35:34 42641 8192000000 10087205440 10510045184 1125989312 12.084 99 38 66 1 1163973264 30757408 7226544
06:37:18 45825 8192000000 10123833440 10586300416 1132262592 12.143 100 13 26 0 1203878304 69141888 2473824
06:39:34 195169 8192000000 10098288512 10528485376 1111131440 11.943 97 23 45 0 1148426688 32336688 4958560
06:43:55 93986 8192000000 10084276288 10544861184 1100942800 11.847 96 29 58 0 1151191760 44364080 5884880
06:46:47 55909 8192000000 10070680720 10510368768 1139647680 12.212 100 29 55 2 1173380800 28184528 5548592
06:48:44 13036 8192000000 10097127776 10525601792 1122669712 12.052 99 41 77 2 1194342048 64003472 7668864
06:49:57 27966 8192000000 10140415536 10598715392 1127866448 12.101 98 31 62 0 1235666272 100553280 7246544
06:51:32 67514 8192000000 10095122704 10547806208 1110115984 11.934 97 16 32 1 1161524464 48346832 3061648
06:53:40 89580 8192000000 10141638192 10580090880 1089604464 11.739 95 16 32 0 1197959472 104434656 3920352
06:56:57 94055 8192000000 10143869280 10615648256 1137011184 12.187 100 17 33 2 1217869600 76487968 4370448
06:59:39 65512 8192000000 10106691280 10571538432 1089171008 11.735 95 26 53 0 1183717104 88156208 6389888
07:01:45 47895 8192000000 10201338992 10652573696 1145156592 12.264 100 26 50 1 1303937216 152778320 6002304
07:03:35 42546 8192000000 10177450320 10615832576 1140816368 12.223 99 22 44 1 1262193472 116277504 5099600
07:05:18 49908 8192000000 10169345504 10668507136 1136699664 12.184 99 21 42 1 1261045328 119626896 4718768
07:08:16 20363 8192000000 10179485712 10612203520 1157031168 12.375 100 45 80 1 1269175152 103298720 8845264
07:09:40 54277 8192000000 10118069920 10608009216 1112429216 11.955 97 18 34 1 1200961760 84908592 3623952
07:13:04 128806 8192000000 10159654880 10666266624 1131933152 12.140 98 37 65 1 1252505024 113464880 7106992
07:16:24 42308 8192000000 10070429920 10516959232 1111633120 11.948 98 24 44 2 1172716864 55799984 5283760
07:18:14 56721 8192000000 10118241424 10556428288 1120494704 12.032 98 21 40 0 1196158608 71766064 3897840
07:21:05 45482 8192000000 10061924736 10509012992 1127215648 12.095 99 23 42 1 1161290352 29524880 4549824
07:22:51 56813 8192000000 10078975696 10536325120 1128875488 12.111 99 24 46 2 1187646816 53276544 5494784
07:24:48 56251 8192000000 10068895632 10503548928 1119710800 12.024 98 19 38 0 1166574576 43088384 3775392
07:26:45 83051 8192000000 10061579472 10522673152 1130540064 12.126 100 11 22 0 1174789840 42019472 2230304
07:29:11 57613 8192000000 10087904272 10542239744 1108837152 11.921 97 22 44 0 1172569504 59246480 4485872
07:31:09 77589 8192000000 10074426192 10587213824 1132400128 12.144 100 8 15 1 1164142128 30408256 1333744
07:33:27 56473 8192000000 10101376336 10551238656 1116221248 11.991 97 20 42 0 1172314208 51695808 4397152
07:35:24 36886 8192000000 10117983376 10568925184 1108685808 11.920 97 24 48 0 1186373728 73033760 4654160
07:37:05 41449 8192000000 10096136544 10525065216 1087649488 11.720 96 47 93 1 1150188000 53081200 9457312
07:38:50 25086 8192000000 10157656688 10588397568 1127194736 12.095 99 51 100 1 1237395888 100514144 9687008
07:40:16 14761 8192000000 10109367680 10562953216 1121067056 12.037 99 34 66 3 1190339936 62603520 6669360
07:41:32 14948 8192000000 10142934592 10568994816 1132139200 12.142 100 51 98 2 1237151152 95301360 9710592
07:42:48 73850 8192000000 10090135200 10545868800 1108523488 11.918 98 18 36 0 1193257584 81212448 3521648
07:45:09 66973 8192000000 10093132832 10542604288 1107310960 11.907 98 29 58 1 1174380160 61209648 5859552
07:47:24 57906 8192000000 9464266304 9836670976 587097312 6.687 52 10 20 0 639577504 50436128 2044064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-03-2022 07:51:09 to 26-03-2022 07:51:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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 26-03-2022 07:51:26 to 26-03-2022 07:51:28.

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          205474
MON$OLDEST_ACTIVE               206134
MON$OLDEST_SNAPSHOT             206134
MON$NEXT_TRANSACTION            206134
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                       2094080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             752
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Mar 26 07:49:02 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Mar 26 07:49:06 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

26.03.22 07:51:29. 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-03-26 07:51:32 No severe PSQL-related problems occured
26.03.22 07:51:33. End of report.