Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2022 07:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14948048 kB
MemAvailable 30151688 kB
Buffers 0 kB
Cached 16789524 kB
SwapCached 1716 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-07 07:37:21
DTS_END                         2022-12-07 07:37:21.6500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38152
TRN_ID                          216821
ATT_ID                          595
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: 8649.37 33324 1037924
customer order: creation 161.53 465 19383
customer order: refuse 14.01 464 1681
order to supplier: creation 43.48 1393 5218
order to supplier: removal 3.20 186759 384
invoice (draft): creation 43.23 3198 5187
invoice (draft): removal 48.21 1102 5785
invoice accept: apply 40.53 31865 4864
invoice accept: cancel 43.38 1304 5205
customer reserve: creation 3776.33 306 453160
customer reserve: removal 4201.27 109 504153
realization accept: apply 80.61 282 9673
realization accept: cancel 69.52 177 8342
payment from customer: creation 56.63 192 6795
payment from customer: removal 5.36 150 643
payment to supplier: creation 46.76 206 5611
payment to supplier: removal 3.17 126 380
service: total inventory turnovers 3.98 4104 478
service: total monetary turnovers 7.23 484 867
service: aggregate perf. data 0.93 14789 111
service: refresh index statistics 0.03 419011 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-12-2022 07:39:47 to 07-12-2022 07:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8604
TEST_TIME 2 8602
TEST_TIME 3 7683
TEST_TIME 4 6711
TEST_TIME 5 6531
TEST_TIME 6 6384
TEST_TIME 7 6343
TEST_TIME 8 6592
TEST_TIME 9 6808
TEST_TIME 10 6680
TEST_TIME 11 6629
TEST_TIME 12 6665
TEST_TIME 13 6739
TEST_TIME 14 6815
TEST_TIME 15 6683
TEST_TIME 16 6846
TEST_TIME 17 6880
TEST_TIME 18 6896
TEST_TIME 19 6921
TEST_TIME 20 7014
TEST_TIME 21 7110
TEST_TIME 22 7267
TEST_TIME 23 7241
TEST_TIME 24 7437
TEST_TIME 25 7629
TEST_TIME 26 8144
TEST_TIME 27 8160
TEST_TIME 28 8399
TEST_TIME 29 8930
TEST_TIME 30 9319
TEST_TIME 31 9151
TEST_TIME 32 9340
TEST_TIME 33 9799
TEST_TIME 34 10100
TEST_TIME 35 10780
TEST_TIME 36 10760
TEST_TIME 37 10606
TEST_TIME 38 10349
TEST_TIME 39 10120
TEST_TIME 40 10003
TEST_TIME 41 9985
TEST_TIME 42 9887
TEST_TIME 43 9955
TEST_TIME 44 9943
TEST_TIME 45 9885
TEST_TIME 46 9744
TEST_TIME 47 9681
TEST_TIME 48 9771
TEST_TIME 49 9640
TEST_TIME 50 9566
TEST_TIME 51 9573
TEST_TIME 52 9501
TEST_TIME 53 9572
TEST_TIME 54 9556
TEST_TIME 55 9479
TEST_TIME 56 9453
TEST_TIME 57 9481
TEST_TIME 58 9392
TEST_TIME 59 9397
TEST_TIME 60 9361
TEST_TIME 61 9417
TEST_TIME 62 9393
TEST_TIME 63 9416
TEST_TIME 64 9407
TEST_TIME 65 9393
TEST_TIME 66 9388
TEST_TIME 67 9346
TEST_TIME 68 9350
TEST_TIME 69 9384
TEST_TIME 70 9271
TEST_TIME 71 9267
TEST_TIME 72 9179
TEST_TIME 73 9196
TEST_TIME 74 9138
TEST_TIME 75 9116
TEST_TIME 76 9088
TEST_TIME 77 9066
TEST_TIME 78 9047
TEST_TIME 79 9043
TEST_TIME 80 9010
TEST_TIME 81 8998
TEST_TIME 82 9014
TEST_TIME 83 9033
TEST_TIME 84 9036
TEST_TIME 85 9017
TEST_TIME 86 9066
TEST_TIME 87 9056
TEST_TIME 88 9076
TEST_TIME 89 9063
TEST_TIME 90 9100
TEST_TIME 91 9050
TEST_TIME 92 8986
TEST_TIME 93 8991
TEST_TIME 94 8945
TEST_TIME 95 8925
TEST_TIME 96 8913
TEST_TIME 97 8877
TEST_TIME 98 8847
TEST_TIME 99 8808
TEST_TIME 100 8784
TEST_TIME 101 8802
TEST_TIME 102 8807
TEST_TIME 103 8866
TEST_TIME 104 8858
TEST_TIME 105 8841
TEST_TIME 106 8864
TEST_TIME 107 8884
TEST_TIME 108 8853
TEST_TIME 109 8924
TEST_TIME 110 8904
TEST_TIME 111 8896
TEST_TIME 112 8885
TEST_TIME 113 8946
TEST_TIME 114 8896
TEST_TIME 115 8839
TEST_TIME 116 8809
TEST_TIME 117 8772
TEST_TIME 118 8750
TEST_TIME 119 8742
TEST_TIME 120 8672

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2022 07:39:51 to 07-12-2022 07:39:54.

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 1452772 1452772 0 0.00 0 30180 16 0 0
doc_list_biud 1452763 1452763 0 0.00 0 32382 44 0 0
sp_add_invoice_to_stock 4970 4864 106 2.13 35 258881 31865 0 106
sp_cancel_adding_invoice 5205 5205 0 0.00 7 64146 1304 0 0
sp_cancel_client_order 1681 1681 0 0.00 6 12043 464 0 0
sp_cancel_customer_reserve 504153 504153 0 0.00 1 31288 110 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 4 9070 151 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 4 1859 127 0 0
sp_cancel_supplier_invoice 5789 5785 4 0.06 3 32448 1102 0 4
sp_cancel_supplier_order 384 384 0 0.00 5 1575303 186759 0 0
sp_cancel_write_off 8342 8342 0 0.00 2 7092 177 0 0
sp_client_order 19383 19383 0 0.00 6 21431 466 0 0
sp_customer_reserve 453160 453160 0 0.00 0 121116 307 0 0
sp_fill_shopping_cart 29775 29775 0 0.00 1 11871 81 0 0
sp_fill_shopping_cart_clo_res 453134 453134 0 0.00 0 15851 21 0 0
sp_get_clo_for_invoice 4828 4828 0 0.00 0 22304 316 0 0
sp_kill_cost_storno 14570 14570 0 0.00 0 8756 87 0 0
sp_kill_qstorno_ret_qs2qd 1022817 1022817 0 0.00 0 32380 48 0 0
sp_kill_qty_storno 540049 540049 0 0.00 0 32381 110 0 0
sp_lock_selected_doc 19992 19992 0 0.00 0 7612 6 0 0
sp_make_cost_storno 26907 26907 0 0.00 0 14339 80 0 0
sp_multiply_rows_for_pdistr 26904 26904 0 0.00 0 10554 42 0 0
sp_multiply_rows_for_qdistr 19383 19383 0 0.00 0 9945 181 0 0
sp_pay_from_customer 6795 6795 0 0.00 6 16244 193 0 0
sp_pay_to_supplier 5611 5611 0 0.00 6 13746 207 0 0
sp_payment_common 12405 12405 0 0.00 2 12285 50 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 12035 395 0 0
sp_qd_handle_on_invoice_upd_sts 10032 10032 0 0.00 0 31286 584 0 0
sp_qd_handle_on_reserve_upd_sts 9672 9672 0 0.00 0 18128 65 0 0
sp_reserve_write_off 9673 9673 0 0.00 7 27912 283 0 0
sp_supplier_invoice 5187 5187 0 0.00 10 94778 3198 0 0
sp_supplier_order 5218 5218 0 0.00 7 40575 1393 0 0
srv_aggregate_perf_data 111 111 0 0.00 2520 120711 14789 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 818 131271 33176 0 0
srv_find_qd_qs_mism 989706 989706 0 0.00 0 30177 13 0 0
srv_make_invnt_saldo 478 478 0 0.00 46 53125 4104 0 0
srv_make_money_saldo 867 867 0 0.00 1 20481 485 0 0
srv_recalc_idx_stat 4 4 0 0.00 234182 513513 419012 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4870 4870 0 0.00 1 39383 1004 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 4856 420 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 8802 32 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 2704 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 529 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 4229 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 2043 46 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 103751 8063 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1009 19 0 0
t$perf-norm:sp_client_order 19383 19383 0 0.00 0 26966 41 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 477 12 0 0
t$perf-norm:sp_pay_from_customer 6795 6795 0 0.00 0 25231 23 0 0
t$perf-norm:sp_pay_to_supplier 5611 5611 0 0.00 0 24005 24 0 0
t$perf-norm:sp_reserve_write_off 9673 9673 0 0.00 0 28334 33 0 0
t$perf-norm:sp_supplier_invoice 5187 5187 0 0.00 0 8490 34 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 5204 32 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 523 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 151 6 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 763 19 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 804 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 12 171 64 0 0
v_add_invoice_to_stock 9850 9638 212 2.15 0 22665 32 0 212
v_all_customers 35428 35428 0 0.00 0 67 4 0 0
v_all_suppliers 29152 29152 0 0.00 0 297 5 0 0
v_all_wares 38759 38759 0 0.00 0 123 4 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 471 13 0 0
v_cancel_client_order 3362 3362 0 0.00 0 2006 9 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 3183 13 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 904 11 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1357 15 0 0
v_cancel_supplier_invoice 720 712 8 1.11 0 1372 26 0 8
v_cancel_supplier_order 768 768 0 0.00 0 786 13 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2503 25 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 8872 7 0 0
v_max_id_clo_res 960 960 0 0.00 0 2098 18 0 0
v_max_id_ord_sup 5179 5179 0 0.00 0 5696 8 0 0
v_max_non_paid_invoice 1429 1429 0 0.00 0 100 8 0 0
v_max_non_paid_realizn 3572 3572 0 0.00 0 4767 10 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 4132 8 0 0
v_min_id_clo_res 960 960 0 0.00 0 1153 9 0 0
v_min_id_ord_sup 5179 5179 0 0.00 0 6577 9 0 0
v_min_non_paid_invoice 5610 5610 0 0.00 0 9140 19 0 0
v_min_non_paid_realizn 6794 6794 0 0.00 0 7124 18 0 0
v_our_firm 9774 9774 0 0.00 0 51 5 0 0
v_reserve_write_off 19344 19344 0 0.00 0 27770 12 0 0
x_lock_depdocs_on_canc_invoice 10634 10634 0 0.00 0 14929 154 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 17417 444 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 3 40548 1219 0 0
x_make_qstorno_1200_2000 5187 5187 0 0.00 4 93806 2974 0 0
x_make_qstorno_2100_3300 453160 453160 0 0.00 0 121108 273 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2022 07:39:55 to 07-12-2022 07:39:59.

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:07:25 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 25425 8192000000 9724598480 10022756352 802473216 8.921 100 87 174 0 831245360 6989472 21782672
05:09:53 28383 8192000000 9764064544 10073788416 838304400 9.283 100 85 171 0 871278352 12887520 20086432
05:11:36 14620 8192000000 9807209280 10128551936 872257104 9.623 100 86 172 0 918822080 26180208 20384768
05:13:03 9116 8192000000 9887127280 10221867008 928118240 10.176 100 87 174 0 996235088 49416752 18700096
05:14:15 4266 8192000000 9924359040 10265288704 957075536 10.460 100 89 170 0 1034848608 61002080 16770992
05:15:22 1540 8192000000 9959442000 10314579968 995697344 10.837 100 93 182 3 1078568736 64276688 18594704
05:16:29 1452 8192000000 10025528784 10384515072 1020597664 11.078 100 95 181 1 1136251792 97669104 17985024
05:17:33 2942 8192000000 10005751536 10375950336 1040291680 11.267 100 82 162 1 1126868928 71398784 15178464
05:18:38 978 8192000000 10018290432 10397417472 1059691984 11.454 100 93 181 0 1142730192 64505712 18532496
05:19:39 1020 8192000000 10033661072 10416058368 1070126288 11.553 100 95 174 1 1155818928 67833312 17859328
05:20:42 956 8192000000 10060129216 10445348864 1079934000 11.647 100 83 161 2 1184006224 87897456 16174768
05:21:44 1192 8192000000 10059500528 10443472896 1082207232 11.668 100 91 174 2 1179071760 79222208 17642320
05:22:47 1241 8192000000 10078188336 10466537472 1087011792 11.714 100 98 189 0 1196806848 90358928 19436128
05:23:49 1253 8192000000 10035993552 10417201152 1091715664 11.759 100 85 153 6 1152516576 46131792 14669120
05:24:51 2047 8192000000 10041404512 10427789312 1096515552 11.805 100 74 137 2 1161994688 53762464 11716672
05:25:55 1094 8192000000 10076724992 10465460224 1098639888 11.825 100 96 187 2 1199408208 81835520 18932800
05:26:57 1055 8192000000 10073197728 10460798976 1097486960 11.814 100 93 180 1 1190812896 75029632 18296304
05:27:59 1034 8192000000 10055279552 10449858560 1093073600 11.772 100 81 155 0 1178903344 70181328 15648416
05:29:01 1075 8192000000 10043566944 10438074368 1087947440 11.723 100 83 150 2 1166089360 61451648 16690272
05:30:03 1269 8192000000 10049626224 10438651904 1084472208 11.690 100 89 161 2 1175422224 73563856 17386160
05:31:05 7827 8192000000 9989641568 10374524928 1034175792 11.209 96 58 112 0 1093898880 48475888 11247200
05:32:15 2880 8192000000 10030786384 10419777536 1071145472 11.563 100 77 143 2 1159498640 74157168 14196000
05:33:19 1164 8192000000 10012718672 10392342528 1048835680 11.350 100 87 169 1 1127999968 62058032 17106256
05:34:21 17085 8192000000 9982213056 10374647808 1022181056 11.093 98 55 106 0 1097305328 64712000 10412272
05:35:39 1076 8192000000 9961199280 10332069888 1012703136 11.002 99 82 159 1 1082949664 53113664 17132864
05:36:41 5703 8192000000 9986182112 10370867200 1043119296 11.295 100 61 119 1 1115159312 60296544 11743472
05:37:52 2243 8192000000 9991789664 10369404928 1056421056 11.422 100 89 161 2 1109861680 38714800 14725824
05:39:02 1229 8192000000 10057530624 10436157440 1063733072 11.492 100 83 162 2 1170277728 91055424 15489232
05:40:19 11055 8192000000 10008347328 10391924736 1062280576 11.478 100 68 129 2 1133992720 58746960 12965184
05:41:40 4117 8192000000 10047705584 10432299008 1074111216 11.591 100 68 134 0 1166569664 78742016 13716432
05:43:16 1969 8192000000 10118627728 10505129984 1097612944 11.815 100 90 164 2 1230094160 115618448 16862768
05:44:22 1028 8192000000 10173894240 10561081344 1114661632 11.977 100 92 172 1 1291490640 157411296 19417712
05:45:28 3274 8192000000 10183710080 10580221952 1122291648 12.049 100 82 149 4 1293185600 155211456 15682496
05:46:39 1841 8192000000 10198171824 10611003392 1135162512 12.170 100 77 148 0 1325693600 174236128 16294960
05:47:50 2667 8192000000 10246604672 10642964480 1158745792 12.392 100 87 170 1 1354972048 177300800 18925456
05:48:57 1632 8192000000 10271464480 10669588480 1161613456 12.418 100 87 170 0 1387917552 207562080 18742016
05:50:02 2281 8192000000 10308696320 10712539136 1169554304 12.493 100 87 160 3 1419333888 231683136 18096448
05:51:09 995 8192000000 10352687008 10753228800 1179441440 12.585 100 90 175 1 1464771824 265207184 20123200
05:52:12 941 8192000000 10379665536 10780295168 1192535792 12.707 100 90 169 0 1487992032 275592672 19863568
05:53:16 1260 8192000000 10437439056 10851475456 1199086368 12.768 100 97 191 1 1547849984 326602720 22160896
05:54:24 1737 8192000000 10455304432 10874306560 1196945760 12.748 100 95 184 2 1557815104 339523728 21345616
05:55:29 1080 8192000000 10492651840 10896891904 1194722016 12.727 100 91 180 0 1601169216 385409184 21038016
05:56:34 1877 8192000000 10539955408 10951872512 1195351184 12.733 100 96 186 0 1644957984 427087248 22519552
05:57:41 1380 8192000000 10547941632 10977878016 1203567232 12.809 100 99 194 1 1654951600 427786816 23597552
05:58:45 811 8192000000 10549527712 10969378816 1177128016 12.563 100 94 181 0 1654282288 454438448 22715824
05:59:49 1002 8192000000 10581481360 10992922624 1189822112 12.682 100 90 172 0 1686950720 476179024 20949584
06:00:55 1197 8192000000 10581553136 11027648512 1186561632 12.651 100 94 184 1 1684014928 474917536 22535760
06:02:02 851 8192000000 10565013568 11026272256 1186499696 12.651 100 89 176 1 1668185520 460833984 20851840
06:03:04 1036 8192000000 10573165248 11048443904 1196588160 12.745 100 96 192 0 1681250624 461197712 23464752
06:04:08 2598 8192000000 10600873792 11023679488 1197200128 12.750 100 70 135 1 1701385072 487247584 16937360
06:05:14 6846 8192000000 10530793008 11033993216 1183991744 12.627 99 63 122 3 1640680736 440537392 16151600
06:06:26 20165 8192000000 10482568368 10921521152 1177501904 12.567 100 49 98 0 1592128224 402062848 12563472
06:07:52 33236 8192000000 10452779184 10899951616 1167720160 12.476 99 35 68 1 1547233568 370966016 8547392
06:09:30 32258 8192000000 10394487472 10937475072 1149424848 12.304 100 27 54 0 1526263632 369640160 7198624
06:11:04 40499 8192000000 10281487952 10792980480 1124127440 12.066 99 16 30 0 1399576752 271320224 4129088
06:13:12 45681 8192000000 10068202512 10497048576 1062993696 11.485 94 29 52 0 1113046480 44723920 5328864
06:14:59 90051 8192000000 10144026080 10575331328 1135897040 12.177 100 53 106 1 1238536240 91561936 11077264
06:17:49 90917 8192000000 10133260496 10562801664 1076278800 11.612 94 49 94 1 1183706528 97917520 9510208
06:20:23 62285 8192000000 10163657776 10617749504 1143972848 12.253 100 24 48 0 1249255888 100329584 4953456
06:22:48 23258 8192000000 10141819680 10641211392 1141087776 12.226 99 18 34 0 1238080160 93315872 3676512
06:24:13 72858 8192000000 10154289536 10623164416 1147193792 12.283 100 23 47 0 1266531600 113844048 5493760
06:27:14 93507 8192000000 10146579888 10651676672 1154944944 12.356 100 15 29 0 1291679072 133049968 3684160
06:29:49 3889 8192000000 10124016384 10569072640 1142303392 12.237 100 37 70 2 1227339264 76915648 8120224
06:30:54 44587 8192000000 10110779024 10584858624 1131859600 12.139 99 24 48 0 1214756912 77703680 5193632
06:32:39 69279 8192000000 10083175200 10581561344 1104321152 11.879 97 22 44 0 1160557040 51314976 4920912
06:34:56 73661 8192000000 10082525264 10516045824 1108262736 11.916 97 33 57 0 1148585248 34829856 5492656
06:37:11 28765 8192000000 10105880480 10544013312 1126855472 12.092 100 45 86 1 1175710320 40277568 8577280
06:38:53 33713 8192000000 10086116704 10547134464 1129100192 12.113 99 40 73 1 1173070048 36688016 7281840
06:40:30 58103 8192000000 10094490752 10531971072 1110165568 11.934 97 28 55 0 1172598848 56747536 5685744
06:42:29 33230 8192000000 10076479360 10522914816 1095373600 11.794 96 32 63 0 1146688384 45225952 6088832
06:44:05 44441 8192000000 10121648592 10559250432 1115480864 11.984 98 37 72 0 1175856512 53249808 7125840
06:45:52 96150 8192000000 10102020448 10579890176 1132026848 12.140 99 29 58 0 1230129328 92016656 6085824
06:48:28 10790 8192000000 10119689472 10554949632 1145080688 12.263 100 70 124 1 1211368896 54255184 12033024
06:49:47 9053 8192000000 10127289136 10565894144 1148007696 12.291 100 43 86 0 1231264848 73968912 9288240
06:51:30 78527 8192000000 10082547328 10577211392 1133164288 12.151 99 10 20 0 1191382624 56131984 2086352
06:54:01 40488 8192000000 10066477728 10531758080 1135470720 12.173 99 10 20 0 1184639024 47672224 1496080
06:55:42 31993 8192000000 10099020592 10557038592 1149566368 12.305 100 32 63 0 1209729328 53839712 6323248
06:57:22 8115 8192000000 10099999616 10536620032 1145800848 12.270 99 56 105 2 1192797056 36948752 10047456
06:58:31 57108 8192000000 10132608832 10573066240 1123663392 12.062 97 32 64 0 1207238720 77234368 6340960
07:00:37 34203 8192000000 10093133984 10543452160 1112174448 11.953 98 28 56 0 1183586432 65672848 5739136
07:02:12 76741 8192000000 10083698496 10574053376 1099556864 11.833 97 22 44 0 1185843856 81773360 4513632
07:04:30 62821 8192000000 10086800544 10544947200 1075229536 11.602 95 21 41 0 1144416832 65050080 4137216
07:06:35 38029 8192000000 10146433616 10598526976 1135238256 12.171 100 36 72 1 1227669136 85218288 7212592
07:08:26 131270 8192000000 10066681984 10522128384 1124163872 12.066 99 28 56 0 1198056080 66898928 6993280
07:11:48 19823 8192000000 10081932160 10531348480 1137230064 12.189 100 30 60 1 1197747920 54489536 6028320
07:13:12 53839 8192000000 10050607984 10537123840 1096426064 11.804 97 9 18 0 1140867504 42403296 2038144
07:15:06 36748 8192000000 10046578064 10515959808 1109816224 11.931 99 11 22 1 1147647440 35254752 2576464
07:16:43 98593 8192000000 10100564592 10578579456 1099103520 11.829 97 14 28 0 1187213312 84876624 3233168
07:19:22 39673 8192000000 10112036240 10569322496 1113182640 11.963 98 26 52 1 1193541696 75283696 5075360
07:21:02 36549 8192000000 10082151744 10518884352 1117690640 12.005 98 28 52 0 1162434416 38623152 6120624
07:22:38 39783 8192000000 10118560608 10564300800 1104319104 11.879 97 37 74 0 1197824400 86013216 7492080
07:24:19 29713 8192000000 10088836208 10538983424 1077368704 11.622 95 34 66 0 1144331360 60259856 6702800
07:25:50 32505 8192000000 10107652000 10569101312 1115287936 11.982 99 37 73 0 1221905216 99462032 7155248
07:27:23 25718 8192000000 10077846320 10511257600 1124896336 12.073 100 45 90 0 1169291008 35509504 8885168
07:28:53 6480 8192000000 10082216592 10501529600 1122014256 12.046 100 51 102 1 1177361232 45683520 9663456
07:30:02 27618 8192000000 10104569760 10552893440 1117185744 12.000 100 45 90 0 1208118832 82219296 8713792
07:31:35 111081 8192000000 10124855808 10559164416 1120911632 12.036 99 43 86 0 1183455424 53600528 8943264
07:34:30 69784 8192000000 10138068800 10593329152 1129248320 12.114 99 11 22 0 1232348032 100882096 2217616
07:36:40 66954 8192000000 9268137152 9613238272 375414432 4.381 34 6 12 0 443743392 67128208 1200752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-12-2022 07:40:01 to 07-12-2022 07:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

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

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          217070
MON$OLDEST_ACTIVE               217071
MON$OLDEST_SNAPSHOT             217071
MON$NEXT_TRANSACTION            217071
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                       2053984
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2882 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2882 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2882 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2882 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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Wed Dec  7 07:37:49 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

07.12.22 07:40:22. 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-12-07 07:40:24 No severe PSQL-related problems occured
07.12.22 07:40:26. End of report.