Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.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 16694000 kB
MemAvailable 31569724 kB
Buffers 0 kB
Cached 15052780 kB
SwapCached 2556 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-04-23 10:37:12
DTS_END                         2022-04-23 10:37:12.3740
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/59962
TRN_ID                          214938
ATT_ID                          637
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: 8669.42 22292 1040331
customer order: creation 164.03 455 19683
customer order: refuse 13.72 399 1646
order to supplier: creation 42.40 1392 5088
order to supplier: removal 3.43 162517 411
invoice (draft): creation 44.21 3407 5305
invoice (draft): removal 47.91 1050 5749
invoice accept: apply 39.64 27088 4757
invoice accept: cancel 43.45 1223 5214
customer reserve: creation 3752.16 254 450259
customer reserve: removal 4245.01 104 509401
realization accept: apply 78.08 279 9370
realization accept: cancel 72.29 164 8675
payment from customer: creation 55.92 183 6710
payment from customer: removal 5.28 112 634
payment to supplier: creation 46.59 215 5591
payment to supplier: removal 3.33 120 399
service: total inventory turnovers 3.83 3865 459
service: total monetary turnovers 7.29 343 875
service: aggregate perf. data 0.83 15821 100
service: refresh index statistics 0.04 226857 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 10:39:08 to 23-04-2022 10:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7358
TEST_TIME 2 6907
TEST_TIME 3 7035
TEST_TIME 4 6771
TEST_TIME 5 6626
TEST_TIME 6 6277
TEST_TIME 7 6231
TEST_TIME 8 6292
TEST_TIME 9 6174
TEST_TIME 10 6212
TEST_TIME 11 6610
TEST_TIME 12 7004
TEST_TIME 13 7095
TEST_TIME 14 7196
TEST_TIME 15 7452
TEST_TIME 16 7493
TEST_TIME 17 7460
TEST_TIME 18 7506
TEST_TIME 19 7579
TEST_TIME 20 7773
TEST_TIME 21 7811
TEST_TIME 22 8211
TEST_TIME 23 8074
TEST_TIME 24 8032
TEST_TIME 25 8192
TEST_TIME 26 8293
TEST_TIME 27 9003
TEST_TIME 28 9579
TEST_TIME 29 9945
TEST_TIME 30 10227
TEST_TIME 31 10694
TEST_TIME 32 10571
TEST_TIME 33 10374
TEST_TIME 34 10225
TEST_TIME 35 10220
TEST_TIME 36 10262
TEST_TIME 37 10075
TEST_TIME 38 9989
TEST_TIME 39 9888
TEST_TIME 40 9911
TEST_TIME 41 9768
TEST_TIME 42 9777
TEST_TIME 43 9672
TEST_TIME 44 9573
TEST_TIME 45 9457
TEST_TIME 46 9321
TEST_TIME 47 9220
TEST_TIME 48 9214
TEST_TIME 49 9096
TEST_TIME 50 9025
TEST_TIME 51 8981
TEST_TIME 52 8946
TEST_TIME 53 8945
TEST_TIME 54 8823
TEST_TIME 55 8711
TEST_TIME 56 8700
TEST_TIME 57 8924
TEST_TIME 58 9029
TEST_TIME 59 9136
TEST_TIME 60 9080
TEST_TIME 61 9072
TEST_TIME 62 9007
TEST_TIME 63 9128
TEST_TIME 64 9147
TEST_TIME 65 9096
TEST_TIME 66 9180
TEST_TIME 67 9199
TEST_TIME 68 9359
TEST_TIME 69 9417
TEST_TIME 70 9471
TEST_TIME 71 9418
TEST_TIME 72 9328
TEST_TIME 73 9270
TEST_TIME 74 9230
TEST_TIME 75 9168
TEST_TIME 76 9108
TEST_TIME 77 9127
TEST_TIME 78 9104
TEST_TIME 79 9120
TEST_TIME 80 9064
TEST_TIME 81 9020
TEST_TIME 82 8983
TEST_TIME 83 8986
TEST_TIME 84 8988
TEST_TIME 85 9055
TEST_TIME 86 9045
TEST_TIME 87 9038
TEST_TIME 88 9141
TEST_TIME 89 9112
TEST_TIME 90 9103
TEST_TIME 91 9137
TEST_TIME 92 9103
TEST_TIME 93 9180
TEST_TIME 94 9157
TEST_TIME 95 9081
TEST_TIME 96 9043
TEST_TIME 97 8996
TEST_TIME 98 8994
TEST_TIME 99 8943
TEST_TIME 100 8921
TEST_TIME 101 8899
TEST_TIME 102 8894
TEST_TIME 103 8895
TEST_TIME 104 8859
TEST_TIME 105 8887
TEST_TIME 106 8902
TEST_TIME 107 8889
TEST_TIME 108 8942
TEST_TIME 109 8906
TEST_TIME 110 8898
TEST_TIME 111 8913
TEST_TIME 112 8953
TEST_TIME 113 8965
TEST_TIME 114 8902
TEST_TIME 115 8857
TEST_TIME 116 8864
TEST_TIME 117 8814
TEST_TIME 118 8777
TEST_TIME 119 8731
TEST_TIME 120 8699

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 10:39:12 to 23-04-2022 10:39:15.

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 1454769 1454769 0 0.00 0 16635 13 0 0
doc_list_biud 1454769 1454769 0 0.00 0 40890 42 0 0
sp_add_invoice_to_stock 4868 4757 111 2.28 50 216244 27089 0 111
sp_cancel_adding_invoice 5214 5214 0 0.00 5 69282 1224 0 0
sp_cancel_client_order 1646 1646 0 0.00 7 9195 400 0 0
sp_cancel_customer_reserve 509401 509401 0 0.00 1 40895 105 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 4397 113 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 9777 120 0 0
sp_cancel_supplier_invoice 5754 5749 5 0.08 2 13000 1051 0 5
sp_cancel_supplier_order 411 411 0 0.00 9 1168742 162517 0 0
sp_cancel_write_off 8675 8675 0 0.00 2 10070 165 0 0
sp_client_order 19683 19683 0 0.00 5 31871 456 0 0
sp_customer_reserve 450259 450259 0 0.00 0 98440 254 0 0
sp_fill_shopping_cart 30064 30064 0 0.00 1 16301 85 0 0
sp_fill_shopping_cart_clo_res 450234 450234 0 0.00 0 16901 19 0 0
sp_get_clo_for_invoice 4729 4729 0 0.00 0 21762 342 0 0
sp_kill_cost_storno 14922 14922 0 0.00 0 11079 72 0 0
sp_kill_qstorno_ret_qs2qd 1033637 1033637 0 0.00 0 40885 47 0 0
sp_kill_qty_storno 545195 545195 0 0.00 0 40889 105 0 0
sp_lock_selected_doc 19538 19538 0 0.00 0 11608 11 0 0
sp_make_cost_storno 26400 26400 0 0.00 0 15792 85 0 0
sp_multiply_rows_for_pdistr 26400 26400 0 0.00 0 9631 39 0 0
sp_multiply_rows_for_qdistr 19683 19683 0 0.00 0 11862 173 0 0
sp_pay_from_customer 6710 6710 0 0.00 6 15193 184 0 0
sp_pay_to_supplier 5591 5591 0 0.00 6 17606 215 0 0
sp_payment_common 12301 12301 0 0.00 2 10830 45 0 0
sp_qd_handle_on_cancel_clo 1646 1646 0 0.00 0 9186 324 0 0
sp_qd_handle_on_invoice_upd_sts 9943 9943 0 0.00 0 37679 495 0 0
sp_qd_handle_on_reserve_upd_sts 9370 9370 0 0.00 0 14077 68 0 0
sp_reserve_write_off 9370 9370 0 0.00 8 18271 280 0 0
sp_supplier_invoice 5305 5305 0 0.00 7 95395 3408 0 0
sp_supplier_order 5088 5088 0 0.00 8 24549 1392 0 0
srv_aggregate_perf_data 100 100 0 0.00 2297 163051 15822 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 923 131807 39031 0 0
srv_find_qd_qs_mism 993323 993323 0 0.00 0 16634 9 0 0
srv_make_invnt_saldo 459 459 0 0.00 50 67580 3865 0 0
srv_make_money_saldo 875 875 0 0.00 1 12240 344 0 0
srv_recalc_idx_stat 5 5 0 0.00 76557 349237 226857 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4767 4767 0 0.00 0 20574 840 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 2 6804 465 0 0
t$perf-norm:sp_cancel_client_order 1646 1646 0 0.00 0 4286 30 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 451 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 545 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 3849 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1726 38 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 97588 5890 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 3157 21 0 0
t$perf-norm:sp_client_order 19683 19683 0 0.00 0 13928 35 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 1328 16 0 0
t$perf-norm:sp_pay_from_customer 6710 6710 0 0.00 0 11608 20 0 0
t$perf-norm:sp_pay_to_supplier 5591 5591 0 0.00 0 12262 20 0 0
t$perf-norm:sp_reserve_write_off 9370 9370 0 0.00 0 12519 24 0 0
t$perf-norm:sp_supplier_invoice 5307 5307 0 0.00 0 24219 35 0 0
t$perf-norm:sp_supplier_order 5088 5088 0 0.00 0 12493 33 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 529 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 199 8 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 441 12 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 874 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 201 110 0 0
v_add_invoice_to_stock 9674 9452 222 2.29 0 15532 28 0 222
v_all_customers 35785 35785 0 0.00 0 160 3 0 0
v_all_suppliers 29088 29088 0 0.00 0 101 4 0 0
v_all_wares 39366 39366 0 0.00 0 191 3 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 705 16 0 0
v_cancel_client_order 3292 3292 0 0.00 0 3833 11 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 1662 10 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1522 10 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 636 10 0 0
v_cancel_supplier_invoice 710 700 10 1.40 0 905 21 0 10
v_cancel_supplier_order 822 822 0 0.00 0 662 11 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2680 23 0 0
v_max_id_clo_ord 5088 5088 0 0.00 0 1228 4 0 0
v_max_id_clo_res 946 946 0 0.00 0 1688 16 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 4588 7 0 0
v_max_non_paid_invoice 1428 1428 0 0.00 0 179 7 0 0
v_max_non_paid_realizn 3536 3536 0 0.00 0 5257 8 0 0
v_min_id_clo_ord 5088 5088 0 0.00 0 2696 10 0 0
v_min_id_clo_res 946 946 0 0.00 0 4377 12 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 4844 12 0 0
v_min_non_paid_invoice 5591 5591 0 0.00 0 8197 16 0 0
v_min_non_paid_realizn 6709 6709 0 0.00 0 6861 18 0 0
v_our_firm 9928 9928 0 0.00 0 148 4 0 0
v_reserve_write_off 18740 18740 0 0.00 0 10212 11 0 0
x_lock_depdocs_on_canc_invoice 10613 10613 0 0.00 0 10186 126 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 2649 311 0 0
x_make_qstorno_1000_1200 5088 5088 0 0.00 3 24533 1232 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 3 87573 3152 0 0
x_make_qstorno_2100_3300 450258 450258 0 0.00 0 98423 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 10:39:16 to 23-04-2022 10:39:20.

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:16 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:17 24704 8192000000 9733291072 10035322880 811415136 9.012 100 91 180 1 841002848 7921632 21666080
08:09:54 28514 8192000000 9778696064 10092519424 847439920 9.374 100 85 170 0 881258624 13613264 20205440
08:11:42 14983 8192000000 9838526512 10164973568 894204544 9.841 100 83 166 0 949864288 36805440 18854304
08:13:04 6741 8192000000 9910832256 10246459392 937433664 10.268 100 87 174 0 1011941744 56065968 18442112
08:14:13 3434 8192000000 9950455312 10296430592 972545440 10.612 100 90 175 0 1062617680 72287840 17784400
08:15:18 1222 8192000000 9975410672 10334875648 1009405360 10.970 100 87 165 4 1091961792 65995120 16561312
08:16:21 840 8192000000 10010491440 10374586368 1036033968 11.227 100 94 182 0 1129755232 75196224 18525040
08:17:23 1023 8192000000 10045153808 10416861184 1050695264 11.367 100 100 195 0 1163057296 93353552 19008480
08:18:25 1005 8192000000 10035459984 10409963520 1060085632 11.457 100 98 190 1 1152135264 72334240 19715392
08:19:27 8303 8192000000 10027974848 10404085760 1066567824 11.519 100 80 151 1 1152879872 71603328 14708720
08:20:36 938 8192000000 10055944640 10441306112 1075947648 11.609 100 93 184 0 1181350384 86723696 18679040
08:21:38 10931 8192000000 10016196560 10401026048 1077066784 11.620 100 77 151 0 1152309696 59906960 15335952
08:22:50 1100 8192000000 10030551680 10419990528 1085455792 11.699 100 96 189 1 1148387040 44354448 18576800
08:23:51 1248 8192000000 10047786992 10436284416 1090531648 11.748 100 95 180 0 1165679200 55819776 19327776
08:24:53 1296 8192000000 10076874576 10463391744 1092990784 11.771 100 92 182 1 1194486352 82961024 18534544
08:25:55 1280 8192000000 10067390576 10456059904 1089947104 11.742 100 98 191 0 1188249776 78624032 19678640
08:26:58 2486 8192000000 10027892736 10424082432 1091620784 11.758 100 69 132 1 1148236704 42629952 13985968
08:28:01 1228 8192000000 10036464768 10424950784 1067671328 11.530 98 86 165 1 1161357536 76205792 17480416
08:29:03 1437 8192000000 10013023648 10395897856 1067947072 11.532 100 96 177 2 1141872576 55489856 18435648
08:30:05 1319 8192000000 10025543104 10407849984 1063456352 11.490 100 95 183 0 1147571248 65312688 18802208
08:31:07 1177 8192000000 10044292272 10428301312 1077726784 11.626 100 96 186 1 1164057488 67263424 19067280
08:32:10 1178 8192000000 9994625120 10365063168 1034215248 11.209 100 96 182 0 1115808848 61903840 19689760
08:33:12 1313 8192000000 10038127600 10414075904 1046680880 11.329 100 95 187 0 1160552624 93809456 20062288
08:34:14 1220 8192000000 9990454768 10366214144 1025768304 11.128 98 88 174 2 1109454608 66430368 17255936
08:35:16 1400 8192000000 10024506512 10400735232 1043518240 11.298 100 94 184 3 1143530544 81275056 18737248
08:36:18 6554 8192000000 9942816928 10338480128 1040554432 11.270 100 50 98 1 1092710288 40779872 11375984
08:37:27 1311 8192000000 10001553440 10380263424 1057836368 11.436 100 92 178 3 1121256160 45195152 18224640
08:38:35 2730 8192000000 10000968032 10374823936 1049323328 11.354 99 72 139 1 1120141504 57259840 13558336
08:39:44 6541 8192000000 10021675456 10411925504 1067603824 11.529 100 68 129 2 1142360336 62116912 12639600
08:40:57 1232 8192000000 10070339584 10454102016 1089604512 11.739 100 85 164 2 1193066048 85143872 18317664
08:42:02 14544 8192000000 10091984208 10486009856 1097187344 11.811 100 66 127 3 1203766416 93573776 13005296
08:43:22 3261 8192000000 10116445952 10507870208 1106331424 11.898 100 66 131 1 1222100736 101724976 14044336
08:44:30 1499 8192000000 10147848176 10542411776 1120249920 12.029 100 94 178 1 1260039872 121462736 18327216
08:45:36 1643 8192000000 10164956224 10558083072 1129456672 12.116 100 92 174 1 1276404688 127713552 19234464
08:46:42 911 8192000000 10224897184 10613854208 1140001936 12.216 100 93 182 1 1333291984 172836000 20454048
08:47:46 2180 8192000000 10259202272 10657923072 1154839584 12.355 100 80 158 1 1377596544 204885360 17871600
08:48:54 959 8192000000 10264189136 10683293696 1153508640 12.342 100 96 191 5 1372696736 197124912 22063184
08:49:58 1371 8192000000 10303324944 10703663104 1168343248 12.481 100 96 189 0 1409071328 219908048 20820032
08:51:03 952 8192000000 10284058464 10705649664 1159600720 12.400 99 93 183 2 1390980944 209580816 21799408
08:52:05 2189 8192000000 10361198016 10769584128 1176523376 12.558 100 93 185 1 1473592384 275421408 21647600
08:53:11 1201 8192000000 10373879456 10778484736 1181756688 12.607 100 94 181 1 1477502240 274657984 21087568
08:54:15 1482 8192000000 10426117344 10832601088 1195260160 12.732 100 95 186 1 1532955760 315462912 22232688
08:55:20 3000 8192000000 10451511840 10873376768 1192017168 12.702 100 93 188 0 1560972608 346175536 22779904
08:56:26 3034 8192000000 10471746512 10903621632 1201162176 12.787 100 90 176 0 1578221568 355960528 21098864
08:57:31 1060 8192000000 10486773408 10921971712 1206193024 12.834 100 86 169 1 1591243136 364586640 20463472
08:58:38 7568 8192000000 10491616384 10939129856 1205748704 12.830 100 68 134 0 1620402096 397494192 17159200
08:59:54 7024 8192000000 10496355952 10925375488 1197530048 12.753 100 55 110 0 1602223424 390403040 14290336
09:01:04 2771 8192000000 10543768928 10967003136 1191090976 12.694 100 64 125 0 1649062656 442023168 15948512
09:02:19 8766 8192000000 10564891280 11022700544 1194526176 12.725 100 56 110 0 1663246688 454024768 14695744
09:03:34 26895 8192000000 10513906832 10998116352 1165096704 12.451 99 39 78 0 1628056320 452304944 10654672
09:05:02 22269 8192000000 10325399888 10809241600 1153360384 12.341 100 26 52 0 1475606352 315512640 6733328
09:06:28 35871 8192000000 10249367808 10792017920 1152662336 12.334 100 19 38 0 1384087504 226718176 4706992
09:08:17 22877 8192000000 10139225776 10617987072 1137863872 12.195 99 51 98 0 1231498576 83594160 10040544
09:09:44 44027 8192000000 10125574576 10548645888 1110528592 11.937 97 55 109 3 1195862992 74136192 11198208
09:11:30 87250 8192000000 10115373616 10588901376 1120571600 12.032 98 15 31 0 1200765872 76759040 3435232
09:15:22 78590 8192000000 10121341408 10571235328 1138077584 12.197 99 26 52 0 1218454656 74631520 5745552
09:17:47 44351 8192000000 10094793408 10550210560 1122236064 12.048 98 40 77 2 1179543152 48561184 8745904
09:19:35 69575 8192000000 10039226464 10480889856 1092351424 11.765 96 36 72 0 1161465488 61654960 7459104
09:21:46 96801 8192000000 10046453344 10484862976 1119002048 12.018 99 31 63 1 1157425440 31713792 6709600
09:24:34 57624 8192000000 10119408976 10556682240 1117285744 12.001 100 48 92 0 1188446336 61360320 9800272
09:27:49 35095 8192000000 10150815168 10592456704 1153749120 12.345 100 52 101 0 1249830672 84523008 11558544
09:29:37 56405 8192000000 10179815824 10665390080 1137150384 12.189 98 27 52 1 1276190256 132583664 6456208
09:32:22 55013 8192000000 10219747152 10691772416 1127519568 12.098 98 18 34 2 1309397824 177640544 4237712
09:34:43 46033 8192000000 10079617872 10567274496 1119064336 12.018 99 17 33 2 1237253568 113811696 4377536
09:36:39 38877 8192000000 10066306240 10496241664 1124330112 12.068 99 23 44 1 1163911936 35342800 4239024
09:38:25 69021 8192000000 10115304352 10562478080 1126148432 12.085 99 25 50 1 1195058768 64028480 4881856
09:40:37 45773 8192000000 10102324592 10546507776 1146770416 12.279 100 22 44 1 1226349616 74826976 4752224
09:42:27 53707 8192000000 10214260896 10660638720 1163140224 12.433 100 28 56 0 1295512080 126563872 5807984
09:44:32 83564 8192000000 10121687328 10637950976 1118676336 12.014 97 10 20 0 1255306640 134130016 2500288
09:47:27 85578 8192000000 10097118992 10573185024 1138598016 12.202 99 9 18 1 1193963472 53667696 1697760
09:49:54 73919 8192000000 10087507904 10541101056 1115579280 11.985 97 27 53 0 1168659536 47603872 5476384
09:52:13 17154 8192000000 10100433936 10541416448 1139594160 12.212 100 39 76 0 1207800256 59308928 8897168
09:53:31 45523 8192000000 10159904464 10600001536 1138353440 12.200 99 39 78 2 1229776224 83575232 7847552
09:55:24 63820 8192000000 10125557280 10560520192 1136449600 12.182 99 28 56 0 1206897168 64355968 6091600
09:57:28 72400 8192000000 10074089888 10532335616 1109447440 11.927 97 8 16 0 1164811504 53521824 1842240
09:59:47 84245 8192000000 10096595840 10535514112 1077001840 11.619 94 17 32 1 1164616608 84073344 3541424
10:02:12 39502 8192000000 10072304880 10539905024 1138255280 12.199 100 13 26 1 1174771984 33813184 2703520
10:03:52 94544 8192000000 10115956752 10607894528 1112390192 11.955 97 31 61 1 1174707552 55925648 6391712
10:06:27 60054 8192000000 10072174608 10514477056 1103745824 11.873 97 22 44 0 1139791200 31234320 4811056
10:08:31 52063 8192000000 10083237376 10512252928 1089726016 11.740 96 23 40 0 1153008912 59561616 3721280
10:10:49 32680 8192000000 10064373536 10490355712 1106641408 11.901 98 29 57 0 1153602736 41296560 5664768
10:12:21 106946 8192000000 10026598512 10449608704 1084367760 11.689 96 37 70 1 1125207344 34156400 6683184
10:15:09 60343 8192000000 10079095664 10525757440 1113330160 11.964 98 22 44 1 1188394976 70815888 4248928
10:17:11 68440 8192000000 10079450288 10547085312 1125080848 12.075 99 6 12 0 1181260720 54786176 1393696
10:19:20 40233 8192000000 10047042016 10491293696 1088704544 11.730 97 45 87 3 1127486912 29992288 8790080
10:21:01 67090 8192000000 10128490384 10557153280 1109762432 11.930 98 40 80 0 1167029104 48504576 8762096
10:23:26 104553 8192000000 10092085264 10521841664 1064810592 11.502 94 20 40 1 1147844496 78633520 4400384
10:26:13 28240 8192000000 10050564928 10469556224 1105106464 11.886 99 39 77 0 1147206992 34245216 7855312
10:27:43 7241 8192000000 10069867008 10480930816 1098434720 11.823 99 43 83 0 1154715056 47943936 8336400
10:28:51 5689 8192000000 10075727936 10490638336 1094373488 11.784 100 43 86 1 1160758736 57394304 8990944
10:30:41 131803 8192000000 10061706560 10488102912 1080268240 11.650 97 51 97 5 1135173840 44140144 10765456
10:33:54 49009 8192000000 10062935248 10530430976 1113974240 11.970 100 8 16 0 1167243952 51708368 1561344
10:35:43 76781 8192000000 10103604064 10556334080 1106506400 11.899 98 17 34 3 1190814720 80408784 3899536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-04-2022 10:39:22 to 23-04-2022 10:39:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 10:39:39 to 23-04-2022 10:39:41.

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          212822
MON$OLDEST_ACTIVE               215162
MON$OLDEST_SNAPSHOT             215162
MON$NEXT_TRANSACTION            215162
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                       2032480
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:539: 
:540: mscs-test-161.budzdorov.ru    Sat Apr 23 10:37:20 2022
:541:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:542: 
:543: 
:544: mscs-test-161.budzdorov.ru    Sat Apr 23 10:37:27 2022
:545:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:546: 
--- end of diff output ---

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

23.04.22 10:39:42. 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-04-23 10:39:44 No severe PSQL-related problems occured
23.04.22 10:39:46. End of report.