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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16543168 kB
MemAvailable 31470364 kB
Buffers 0 kB
Cached 15132600 kB
SwapCached 2688 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-16 07:37:12
DTS_END                         2022-04-16 07:37:12.9410
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/36390
TRN_ID                          220717
ATT_ID                          663
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: 8742.11 22440 1049053
customer order: creation 171.00 461 20520
customer order: refuse 14.58 367 1750
order to supplier: creation 44.86 1289 5383
order to supplier: removal 3.38 162422 406
invoice (draft): creation 45.93 3040 5511
invoice (draft): removal 49.53 952 5944
invoice accept: apply 40.49 26335 4859
invoice accept: cancel 44.63 1131 5356
customer reserve: creation 3710.39 258 445247
customer reserve: removal 4333.82 102 520058
realization accept: apply 82.73 253 9927
realization accept: cancel 72.16 162 8659
payment from customer: creation 58.63 158 7035
payment from customer: removal 5.21 105 625
payment to supplier: creation 48.58 185 5830
payment to supplier: removal 3.35 91 402
service: total inventory turnovers 4.32 3325 518
service: total monetary turnovers 7.63 344 916
service: aggregate perf. data 0.86 13604 103
service: refresh index statistics 0.03 234209 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 07:38:01 to 16-04-2022 07:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6726
TEST_TIME 2 5825
TEST_TIME 3 5650
TEST_TIME 4 5047
TEST_TIME 5 5507
TEST_TIME 6 5771
TEST_TIME 7 5824
TEST_TIME 8 6184
TEST_TIME 9 6194
TEST_TIME 10 6185
TEST_TIME 11 6202
TEST_TIME 12 6269
TEST_TIME 13 6343
TEST_TIME 14 6767
TEST_TIME 15 6747
TEST_TIME 16 6830
TEST_TIME 17 6897
TEST_TIME 18 7175
TEST_TIME 19 7383
TEST_TIME 20 7799
TEST_TIME 21 7901
TEST_TIME 22 8147
TEST_TIME 23 8210
TEST_TIME 24 8742
TEST_TIME 25 9034
TEST_TIME 26 9376
TEST_TIME 27 9949
TEST_TIME 28 10475
TEST_TIME 29 10759
TEST_TIME 30 10781
TEST_TIME 31 10609
TEST_TIME 32 10368
TEST_TIME 33 10349
TEST_TIME 34 10112
TEST_TIME 35 10007
TEST_TIME 36 9904
TEST_TIME 37 9805
TEST_TIME 38 9799
TEST_TIME 39 9769
TEST_TIME 40 9711
TEST_TIME 41 9622
TEST_TIME 42 9501
TEST_TIME 43 9436
TEST_TIME 44 9503
TEST_TIME 45 9661
TEST_TIME 46 9615
TEST_TIME 47 9499
TEST_TIME 48 9493
TEST_TIME 49 9713
TEST_TIME 50 9612
TEST_TIME 51 9592
TEST_TIME 52 9644
TEST_TIME 53 9664
TEST_TIME 54 9653
TEST_TIME 55 9594
TEST_TIME 56 9523
TEST_TIME 57 9392
TEST_TIME 58 9416
TEST_TIME 59 9420
TEST_TIME 60 9464
TEST_TIME 61 9378
TEST_TIME 62 9274
TEST_TIME 63 9281
TEST_TIME 64 9244
TEST_TIME 65 9212
TEST_TIME 66 9168
TEST_TIME 67 9167
TEST_TIME 68 9112
TEST_TIME 69 9096
TEST_TIME 70 9040
TEST_TIME 71 9066
TEST_TIME 72 9096
TEST_TIME 73 9066
TEST_TIME 74 9174
TEST_TIME 75 9259
TEST_TIME 76 9280
TEST_TIME 77 9520
TEST_TIME 78 9464
TEST_TIME 79 9422
TEST_TIME 80 9386
TEST_TIME 81 9384
TEST_TIME 82 9356
TEST_TIME 83 9288
TEST_TIME 84 9228
TEST_TIME 85 9224
TEST_TIME 86 9146
TEST_TIME 87 9083
TEST_TIME 88 9037
TEST_TIME 89 9063
TEST_TIME 90 9031
TEST_TIME 91 9033
TEST_TIME 92 9006
TEST_TIME 93 9012
TEST_TIME 94 9096
TEST_TIME 95 9051
TEST_TIME 96 9039
TEST_TIME 97 9139
TEST_TIME 98 9114
TEST_TIME 99 9084
TEST_TIME 100 9090
TEST_TIME 101 9104
TEST_TIME 102 9123
TEST_TIME 103 9079
TEST_TIME 104 8996
TEST_TIME 105 8995
TEST_TIME 106 8956
TEST_TIME 107 8942
TEST_TIME 108 8965
TEST_TIME 109 8901
TEST_TIME 110 8911
TEST_TIME 111 8894
TEST_TIME 112 8895
TEST_TIME 113 8870
TEST_TIME 114 8847
TEST_TIME 115 8847
TEST_TIME 116 8829
TEST_TIME 117 8831
TEST_TIME 118 8843
TEST_TIME 119 8827
TEST_TIME 120 8763

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-04-2022 07:38:04 to 16-04-2022 07:38:08.

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 1461887 1461887 0 0.00 0 20501 13 0 0
doc_list_biud 1461882 1461882 0 0.00 0 60602 42 0 0
sp_add_invoice_to_stock 4950 4859 91 1.83 38 208146 26336 0 91
sp_cancel_adding_invoice 5356 5356 0 0.00 6 118266 1131 0 0
sp_cancel_client_order 1750 1750 0 0.00 5 13050 367 0 0
sp_cancel_customer_reserve 520058 520058 0 0.00 1 60611 102 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 4 3760 105 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 1235 92 0 0
sp_cancel_supplier_invoice 5948 5944 4 0.06 3 28131 953 0 4
sp_cancel_supplier_order 406 406 0 0.00 12 1383907 162422 0 0
sp_cancel_write_off 8659 8659 0 0.00 2 15499 162 0 0
sp_client_order 20520 20520 0 0.00 5 21893 462 0 0
sp_customer_reserve 445247 445247 0 0.00 1 105692 258 0 0
sp_fill_shopping_cart 31408 31408 0 0.00 2 34328 79 0 0
sp_fill_shopping_cart_clo_res 445223 445223 0 0.00 0 21653 20 0 0
sp_get_clo_for_invoice 4815 4815 0 0.00 0 24520 316 0 0
sp_kill_cost_storno 15042 15042 0 0.00 0 6181 62 0 0
sp_kill_qstorno_ret_qs2qd 1055125 1055125 0 0.00 0 60586 46 0 0
sp_kill_qty_storno 556914 556914 0 0.00 0 60600 102 0 0
sp_lock_selected_doc 20424 20424 0 0.00 0 7580 7 0 0
sp_make_cost_storno 27606 27606 0 0.00 0 12692 74 0 0
sp_multiply_rows_for_pdistr 27606 27606 0 0.00 0 8964 35 0 0
sp_multiply_rows_for_qdistr 20520 20520 0 0.00 0 14502 200 0 0
sp_pay_from_customer 7035 7035 0 0.00 5 12890 158 0 0
sp_pay_to_supplier 5830 5830 0 0.00 6 12519 186 0 0
sp_payment_common 12865 12865 0 0.00 2 8269 43 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 13013 307 0 0
sp_qd_handle_on_invoice_upd_sts 10170 10170 0 0.00 0 32619 467 0 0
sp_qd_handle_on_reserve_upd_sts 9927 9927 0 0.00 0 30548 63 0 0
sp_reserve_write_off 9927 9927 0 0.00 9 30586 253 0 0
sp_supplier_invoice 5511 5511 0 0.00 9 119458 3041 0 0
sp_supplier_order 5383 5383 0 0.00 8 33518 1289 0 0
srv_aggregate_perf_data 103 103 0 0.00 2265 167671 13605 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 813 134984 37030 0 0
srv_find_qd_qs_mism 1002462 1002462 0 0.00 0 20500 11 0 0
srv_make_invnt_saldo 518 518 0 0.00 72 93591 3325 0 0
srv_make_money_saldo 916 916 0 0.00 1 11140 344 0 0
srv_recalc_idx_stat 4 4 0 0.00 125964 374099 234209 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 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 4860 4860 0 0.00 1 29084 800 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 6774 343 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 5186 29 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 978 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 768 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 325 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1632 34 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 75932 5547 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 3407 26 0 0
t$perf-norm:sp_client_order 20520 20520 0 0.00 0 13852 33 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1183 15 0 0
t$perf-norm:sp_pay_from_customer 7035 7035 0 0.00 0 3503 16 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 8459 19 0 0
t$perf-norm:sp_reserve_write_off 9927 9927 0 0.00 0 10753 20 0 0
t$perf-norm:sp_supplier_invoice 5511 5511 0 0.00 0 5195 31 0 0
t$perf-norm:sp_supplier_order 5383 5383 0 0.00 0 2338 27 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 345 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 37 3 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 2451 17 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 195 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 127 40 0 0
v_add_invoice_to_stock 9810 9628 182 1.85 0 5467 23 0 182
v_all_customers 37872 37872 0 0.00 0 195 3 0 0
v_all_suppliers 30570 30570 0 0.00 0 123 4 0 0
v_all_wares 41040 41040 0 0.00 0 174 3 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 243 12 0 0
v_cancel_client_order 3500 3500 0 0.00 0 1133 8 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 3300 13 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 226 8 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 833 9 0 0
v_cancel_supplier_invoice 792 784 8 1.01 0 2951 28 0 8
v_cancel_supplier_order 812 812 0 0.00 0 1739 13 0 0
v_cancel_write_off 2052 2052 0 0.00 0 3331 23 0 0
v_max_id_clo_ord 5383 5383 0 0.00 0 5847 9 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2749 21 0 0
v_max_id_ord_sup 5504 5504 0 0.00 0 7102 8 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 750 7 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 2886 8 0 0
v_min_id_clo_ord 5383 5383 0 0.00 0 3985 10 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 4642 12 0 0
v_min_id_ord_sup 5504 5504 0 0.00 0 6104 14 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 5882 15 0 0
v_min_non_paid_realizn 7035 7035 0 0.00 0 8261 20 0 0
v_our_firm 10086 10086 0 0.00 0 193 4 0 0
v_reserve_write_off 19854 19854 0 0.00 0 5569 9 0 0
x_lock_depdocs_on_canc_invoice 10908 10908 0 0.00 0 24278 122 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10827 318 0 0
x_make_qstorno_1000_1200 5383 5383 0 0.00 3 33508 1139 0 0
x_make_qstorno_1200_2000 5511 5511 0 0.00 5 94639 2808 0 0
x_make_qstorno_2100_3300 445247 445247 0 0.00 0 105690 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 07:38:09 to 16-04-2022 07:38:13.

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:16 10 8192000000 8800627504 8812486656 6120928 0.074 2 2 3 0 6274816 92512 61376
05:08:17 22102 8192000000 9725300512 10024968192 801222736 8.909 100 82 163 1 829126192 7883792 20019664
05:09:44 18127 8192000000 9774760672 10086514688 849242608 9.392 100 87 174 0 881535584 11243104 21049872
05:11:07 16725 8192000000 9813106880 10135625728 883440688 9.734 100 87 174 0 922048816 19044848 19563280
05:12:34 9633 8192000000 9869958688 10205274112 925049088 10.146 100 84 168 0 983180400 40038672 18092640
05:13:49 4643 8192000000 9937283536 10283151360 967045664 10.558 100 90 179 1 1049527472 63605376 18876432
05:14:58 3247 8192000000 9982746608 10336575488 999499360 10.874 100 94 184 0 1101809920 84218608 18091952
05:16:04 994 8192000000 9998663888 10360668160 1026620784 11.136 100 97 184 3 1117283056 70918832 19743440
05:17:07 1045 8192000000 10026417472 10397605888 1049133232 11.352 100 93 177 0 1139911104 73015456 17762416
05:18:08 992 8192000000 10025480256 10404827136 1064088688 11.496 100 97 185 2 1145736480 63301744 18346048
05:19:10 776 8192000000 10073456048 10454597632 1077115440 11.620 100 97 194 1 1194301104 97777952 19407712
05:20:12 974 8192000000 10044122480 10431328256 1084126832 11.687 100 93 181 0 1167676384 64331648 19217904
05:21:14 1273 8192000000 10052919056 10438467584 1089355712 11.737 100 90 169 2 1176636992 69604688 17676592
05:22:16 2240 8192000000 10043458816 10438791168 1092210928 11.764 100 69 134 1 1165647728 59346000 14090800
05:23:20 1112 8192000000 10045677312 10437459968 1101638496 11.853 100 86 160 1 1162630320 44815072 16176752
05:24:22 3706 8192000000 10053003344 10452414464 1101362800 11.851 100 77 152 2 1177531664 61229376 14939488
05:25:29 1546 8192000000 10097598720 10491363328 1100817360 11.845 100 96 183 2 1220900816 101206448 18877008
05:26:31 1250 8192000000 10077690720 10466611200 1105370416 11.889 100 98 188 1 1193582544 69513088 18699040
05:27:33 1016 8192000000 10072903728 10466963456 1104115904 11.877 100 94 178 0 1196282624 73154672 19012048
05:28:35 6674 8192000000 10038318400 10434842624 1091455120 11.756 100 61 121 0 1153219328 49566688 12197520
05:29:42 1274 8192000000 10027787712 10408005632 1066115008 11.515 100 86 163 1 1147873472 66160512 15597952
05:30:45 1828 8192000000 10017106048 10397310976 1055805232 11.416 99 84 159 2 1137932400 65196416 16930752
05:31:48 901 8192000000 10019848032 10399068160 1067931456 11.532 100 95 182 1 1141582624 55511632 18139536
05:32:50 971 8192000000 10007261568 10382651392 1044794832 11.311 100 89 174 1 1126494608 64948128 16751648
05:33:52 1303 8192000000 9982132752 10354962432 1025342064 11.124 99 83 164 0 1110010080 67450768 17217248
05:34:53 981 8192000000 9984198800 10356641792 1023414448 11.105 100 84 159 2 1100871872 61787648 15669776
05:35:55 1686 8192000000 9984502208 10366418944 1040358976 11.268 100 71 130 0 1113363248 59853648 13150624
05:36:58 1302 8192000000 9987435872 10373816320 1061568384 11.471 100 77 150 1 1119799248 42917376 15313488
05:38:07 13929 8192000000 9999913040 10384650240 1067269664 11.526 100 61 118 1 1116660576 37472528 11918384
05:39:28 8373 8192000000 10034829392 10415075328 1074306672 11.593 99 62 118 0 1152563792 66516304 11740816
05:40:40 13151 8192000000 10077666704 10465054720 1099191808 11.830 100 73 134 4 1189519776 77048496 13279472
05:42:05 3157 8192000000 10153136976 10540363776 1115706480 11.986 100 84 151 0 1267700608 135691088 16303040
05:43:10 4041 8192000000 10162347264 10553450496 1127039744 12.093 100 84 157 1 1283690944 139531200 17120000
05:44:18 4628 8192000000 10191930480 10585174016 1125401168 12.078 99 76 150 0 1299228128 157765728 16061232
05:45:27 1073 8192000000 10230449424 10626936832 1148526304 12.296 100 96 177 3 1345577296 176480544 20570448
05:46:31 1292 8192000000 10247623792 10642808832 1157252448 12.378 100 95 176 1 1362651840 183662944 21736448
05:47:35 1098 8192000000 10290035440 10691932160 1164710496 12.447 100 97 188 2 1404357568 218530128 21116944
05:48:41 1687 8192000000 10309124080 10712113152 1166152512 12.461 100 76 148 1 1422015408 238852048 17010848
05:49:48 798 8192000000 10344222352 10742558720 1175909696 12.552 100 92 175 2 1452743200 257194384 19639120
05:50:52 941 8192000000 10364891968 10790154240 1177719808 12.569 100 92 180 1 1472355184 273475264 21160112
05:51:57 1334 8192000000 10386932672 10788491264 1190603488 12.689 100 90 175 0 1496383552 286615584 19164480
05:53:04 2632 8192000000 10409474208 10833727488 1184760448 12.635 100 79 155 0 1518559952 314597520 19201984
05:54:13 1712 8192000000 10449376752 10859180032 1170321872 12.500 99 97 195 0 1552016512 356751680 24942960
05:55:19 889 8192000000 10490112800 10916462592 1184286688 12.630 100 94 184 0 1597114656 390429760 22398208
05:56:22 816 8192000000 10525457664 10943700992 1193016304 12.711 100 95 184 1 1632260208 415930640 23313264
05:57:24 2799 8192000000 10524138352 10954276864 1180319936 12.593 100 67 134 1 1627843296 431213168 16310192
05:58:31 8962 8192000000 10490838816 10922934272 1159947328 12.403 100 41 82 0 1596170112 425154096 11068688
05:59:42 3879 8192000000 10450666080 10912911360 1159583200 12.399 100 57 108 0 1562625920 388697904 14344816
06:00:52 9056 8192000000 10428558560 10927919104 1146540640 12.277 99 57 114 0 1535444656 375097184 13806832
06:02:05 10312 8192000000 10416853296 10875244544 1155395440 12.360 100 50 100 0 1519717312 352197872 12124000
06:03:18 25151 8192000000 10357537440 10821124096 1140205392 12.217 99 33 65 1 1461611056 312437104 8968560
06:04:44 48246 8192000000 10144184304 10637742080 1115232640 11.982 98 17 35 1 1277802864 158137504 4432720
06:06:33 19273 8192000000 10091285632 10548850688 1105030608 11.885 97 29 56 0 1164453968 52485472 6937888
06:08:10 12296 8192000000 10074574432 10502782976 1120087136 12.028 99 64 120 0 1152036480 20232064 11717280
06:09:31 69965 8192000000 10053970544 10472693760 1105066240 11.886 98 41 76 1 1163237520 49818864 8352416
06:11:46 40240 8192000000 10080307872 10495377408 1122179744 12.048 99 67 129 1 1163155648 25970944 15004960
06:13:28 62239 8192000000 10117941312 10554732544 1114279888 11.973 98 37 73 1 1195639744 73235168 8124688
06:15:30 47413 8192000000 10133382624 10605232128 1114129920 11.972 98 15 30 1 1238843200 120755856 3957424
06:17:20 30751 8192000000 10074303696 10528821248 1106968544 11.904 98 13 26 0 1165873872 55956608 2948720
06:19:42 58716 8192000000 10142630576 10628661248 1141721520 12.232 100 15 30 0 1242125168 96790208 3613440
06:21:41 48091 8192000000 10121210288 10585587712 1098327584 11.822 97 24 48 0 1185910032 82292464 5289984
06:23:51 31510 8192000000 10112599408 10535301120 1081469616 11.661 95 43 86 1 1148957280 58566496 8921168
06:25:28 53961 8192000000 10117748576 10595229696 1095810304 11.798 97 28 56 1 1214943664 112837120 6296240
06:27:30 22391 8192000000 10092897536 10520846336 1121798528 12.044 99 48 95 3 1163961888 32693280 9470080
06:28:55 39986 8192000000 10073636672 10515558400 1112256336 11.954 99 38 75 1 1177970656 58129824 7584496
06:30:38 67250 8192000000 10103168752 10575589376 1118291328 12.011 99 31 62 1 1177071104 52362432 6417344
06:32:53 23953 8192000000 10147126336 10575974400 1094596560 11.786 96 54 102 2 1209779056 104419696 10762800
06:34:22 98367 8192000000 10082110064 10551214080 1112357232 11.955 99 25 46 2 1195535280 76828080 6349968
06:37:02 46718 8192000000 10079453872 10569519104 1130115072 12.122 100 12 20 1 1187918432 55794544 2008816
06:38:50 67267 8192000000 10081772912 10546032640 1121243680 12.039 99 8 17 0 1149324048 26581840 1498528
06:41:13 54832 8192000000 10093982256 10525167616 1103057456 11.867 97 34 66 0 1166424272 56863040 6503776
06:43:09 25014 8192000000 10125369440 10561794048 1147697472 12.288 100 18 36 1 1227659856 75353680 4608704
06:44:38 60568 8192000000 10186196720 10619191296 1150436896 12.314 99 40 80 0 1271305376 111663440 9205040
06:47:02 19917 8192000000 10267522496 10698510336 1178324400 12.575 100 51 102 0 1348362768 158400432 11637936
06:48:30 18034 8192000000 10271152304 10707935232 1161373392 12.416 99 47 94 0 1353792768 181752144 10667232
06:49:49 25120 8192000000 10294934080 10744655872 1156424624 12.370 98 40 74 5 1363044688 198167408 8452656
06:51:17 22418 8192000000 10289892064 10774052864 1126141728 12.085 98 38 76 1 1380382688 245487408 8753552
06:53:28 29990 8192000000 10235994848 10726334464 1139151344 12.208 100 23 44 1 1355030608 210409552 5469712
06:55:15 89863 8192000000 10108891728 10547847168 1128819264 12.110 99 26 52 0 1186385168 51834080 5731824
06:58:00 61283 8192000000 10096976608 10591268864 1130230112 12.124 100 30 60 1 1227712944 91907280 5575552
07:00:03 54378 8192000000 10056974304 10507157504 1111042208 11.942 98 9 18 1 1161290960 48481776 1766976
07:02:02 42315 8192000000 10077321264 10538270720 1097401088 11.813 97 14 28 0 1159152048 58028496 3722464
07:03:45 67704 8192000000 10092451472 10545557504 1135961264 12.178 100 30 59 1 1190874272 47904560 7008448
07:06:04 106022 8192000000 10093683328 10557997056 1106614592 11.900 97 11 22 1 1199152432 90150976 2386864
07:09:00 82137 8192000000 10125842448 10578391040 1108847120 11.922 96 20 40 1 1183663264 70684512 4131632
07:11:22 71435 8192000000 10090728848 10533294080 1127274208 12.096 98 24 46 2 1196356288 64088032 4994048
07:13:40 32437 8192000000 10128149648 10590822400 1142856608 12.242 99 33 66 0 1237219344 87119728 7243008
07:15:13 33764 8192000000 10094827024 10537570304 1148216656 12.293 100 30 59 0 1194167296 39611952 6338688
07:16:47 23550 8192000000 10137032048 10573811712 1151565088 12.324 100 49 98 2 1215140544 53065136 10510320
07:18:12 109085 8192000000 10103414640 10550046720 1100436896 11.842 95 40 80 0 1175646336 67684256 7525184
07:21:05 102376 8192000000 10117733648 10589282304 1125979488 12.083 98 31 62 1 1221652992 89252368 6421136
07:23:51 134984 8192000000 10096524480 10649956352 1115809264 11.987 97 12 24 1 1235056976 116798208 2449504
07:27:06 54993 8192000000 10067357040 10549878784 1116497600 11.994 98 14 28 0 1154290096 35215760 2576736
07:29:02 67541 8192000000 10036697728 10475180032 1041255808 11.277 91 21 41 1 1065706048 19609664 4840576
07:31:10 79123 8192000000 10080100768 10536275968 1110083344 11.933 98 19 38 0 1157278528 43183120 4012064
07:33:32 35025 8192000000 10105929456 10541981696 1109328496 11.926 98 40 80 1 1177645568 60236704 8080368
07:35:07 61760 8192000000 10063127568 10516447232 1075903408 11.608 97 28 56 0 1140107056 58565184 5638464
07:37:11 15873 8192000000 9086835888 9332191232 222248144 2.641 20 2 4 0 248897152 26334144 314864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-04-2022 07:38:15 to 16-04-2022 07:38:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 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 16-04-2022 07:38:32 to 16-04-2022 07:38:34.

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          222327
MON$OLDEST_ACTIVE               222328
MON$OLDEST_SNAPSHOT             222328
MON$NEXT_TRANSACTION            222328
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                       2038944
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             781
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 ---
:158: 
:159: mscs-test-161.budzdorov.ru    Sat Apr 16 07:37:31 2022
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:161: 
--- end of diff output ---

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

16.04.22 07:38:35. 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-16 07:38:37 No severe PSQL-related problems occured
16.04.22 07:38:38. End of report.