Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 16: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 15349204 kB
MemAvailable 30573676 kB
Buffers 0 kB
Cached 16341360 kB
SwapCached 2032 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-26 16:38:27
DTS_END                         2022-09-26 16:38:27.3110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46246
TRN_ID                          239918
ATT_ID                          675
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: 9846.53 20800 1181584
customer order: creation 171.40 378 20568
customer order: refuse 14.88 358 1786
order to supplier: creation 45.88 1241 5505
order to supplier: removal 3.64 104913 437
invoice (draft): creation 45.88 3078 5505
invoice (draft): removal 59.63 728 7156
invoice accept: apply 41.98 16643 5037
invoice accept: cancel 53.68 709 6442
customer reserve: creation 3886.17 164 466340
customer reserve: removal 5215.33 58 625839
realization accept: apply 84.13 172 10095
realization accept: cancel 93.24 98 11189
payment from customer: creation 59.68 109 7161
payment from customer: removal 5.63 73 675
payment to supplier: creation 49.82 136 5978
payment to supplier: removal 3.36 77 403
service: total inventory turnovers 4.60 1239 552
service: total monetary turnovers 6.80 35 816
service: aggregate perf. data 0.80 24214 96
service: refresh index statistics 0.03 261572 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-09-2022 16:39:27 to 26-09-2022 16:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3451
TEST_TIME 2 3645
TEST_TIME 3 4626
TEST_TIME 4 4923
TEST_TIME 5 4464
TEST_TIME 6 4291
TEST_TIME 7 4317
TEST_TIME 8 4774
TEST_TIME 9 5208
TEST_TIME 10 5407
TEST_TIME 11 5462
TEST_TIME 12 5831
TEST_TIME 13 5952
TEST_TIME 14 6874
TEST_TIME 15 7176
TEST_TIME 16 7556
TEST_TIME 17 7675
TEST_TIME 18 8163
TEST_TIME 19 8177
TEST_TIME 20 8759
TEST_TIME 21 8916
TEST_TIME 22 8774
TEST_TIME 23 8934
TEST_TIME 24 9274
TEST_TIME 25 9460
TEST_TIME 26 9838
TEST_TIME 27 10149
TEST_TIME 28 10896
TEST_TIME 29 11602
TEST_TIME 30 12023
TEST_TIME 31 11840
TEST_TIME 32 11865
TEST_TIME 33 11668
TEST_TIME 34 11425
TEST_TIME 35 11427
TEST_TIME 36 11179
TEST_TIME 37 11074
TEST_TIME 38 11244
TEST_TIME 39 11139
TEST_TIME 40 11089
TEST_TIME 41 11123
TEST_TIME 42 10928
TEST_TIME 43 10769
TEST_TIME 44 10743
TEST_TIME 45 10722
TEST_TIME 46 11062
TEST_TIME 47 10911
TEST_TIME 48 10758
TEST_TIME 49 10720
TEST_TIME 50 10628
TEST_TIME 51 10571
TEST_TIME 52 10564
TEST_TIME 53 10565
TEST_TIME 54 10464
TEST_TIME 55 10407
TEST_TIME 56 10371
TEST_TIME 57 10274
TEST_TIME 58 10133
TEST_TIME 59 10116
TEST_TIME 60 10119
TEST_TIME 61 10106
TEST_TIME 62 10173
TEST_TIME 63 10153
TEST_TIME 64 10100
TEST_TIME 65 10240
TEST_TIME 66 10201
TEST_TIME 67 10188
TEST_TIME 68 10295
TEST_TIME 69 10254
TEST_TIME 70 10283
TEST_TIME 71 10385
TEST_TIME 72 10497
TEST_TIME 73 10477
TEST_TIME 74 10356
TEST_TIME 75 10295
TEST_TIME 76 10211
TEST_TIME 77 10132
TEST_TIME 78 10193
TEST_TIME 79 10122
TEST_TIME 80 10107
TEST_TIME 81 10066
TEST_TIME 82 10023
TEST_TIME 83 9961
TEST_TIME 84 9865
TEST_TIME 85 9980
TEST_TIME 86 9949
TEST_TIME 87 9913
TEST_TIME 88 9932
TEST_TIME 89 9941
TEST_TIME 90 9953
TEST_TIME 91 9967
TEST_TIME 92 10055
TEST_TIME 93 10042
TEST_TIME 94 10064
TEST_TIME 95 10057
TEST_TIME 96 10072
TEST_TIME 97 10148
TEST_TIME 98 10148
TEST_TIME 99 10109
TEST_TIME 100 10201
TEST_TIME 101 10127
TEST_TIME 102 10050
TEST_TIME 103 10008
TEST_TIME 104 10001
TEST_TIME 105 10014
TEST_TIME 106 10036
TEST_TIME 107 9981
TEST_TIME 108 9954
TEST_TIME 109 9932
TEST_TIME 110 9928
TEST_TIME 111 9873
TEST_TIME 112 9836
TEST_TIME 113 9846
TEST_TIME 114 9832
TEST_TIME 115 9848
TEST_TIME 116 9850
TEST_TIME 117 9859
TEST_TIME 118 9881
TEST_TIME 119 9875
TEST_TIME 120 9847

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2022 16:39:31 to 26-09-2022 16:39:34.

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 1616190 1616190 0 0.00 0 15086 5 0 0
doc_list_biud 1616190 1616190 0 0.00 0 36246 28 0 0
sp_add_invoice_to_stock 5150 5037 113 2.19 35 152443 16643 0 113
sp_cancel_adding_invoice 6442 6442 0 0.00 4 38360 710 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 11461 358 0 0
sp_cancel_customer_reserve 625839 625839 0 0.00 0 29512 58 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 4725 73 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 6082 78 0 0
sp_cancel_supplier_invoice 7166 7156 10 0.13 1 36256 728 0 10
sp_cancel_supplier_order 437 437 0 0.00 6 957530 104913 0 0
sp_cancel_write_off 11189 11189 0 0.00 1 10560 98 0 0
sp_client_order 20568 20568 0 0.00 3 33047 379 0 0
sp_customer_reserve 466340 466340 0 0.00 0 53595 164 0 0
sp_fill_shopping_cart 31562 31562 0 0.00 1 18163 35 0 0
sp_fill_shopping_cart_clo_res 466333 466333 0 0.00 0 14071 14 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 31073 270 0 0
sp_kill_cost_storno 18709 18709 0 0.00 0 8560 52 0 0
sp_kill_qstorno_ret_qs2qd 1270460 1270460 0 0.00 0 36238 31 0 0
sp_kill_qty_storno 667974 667974 0 0.00 0 36242 67 0 0
sp_lock_selected_doc 20991 20991 0 0.00 0 8797 7 0 0
sp_make_cost_storno 28264 28264 0 0.00 0 14134 46 0 0
sp_multiply_rows_for_pdistr 28264 28264 0 0.00 0 6867 26 0 0
sp_multiply_rows_for_qdistr 20568 20568 0 0.00 0 11320 156 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 17807 109 0 0
sp_pay_to_supplier 5978 5978 0 0.00 5 10743 136 0 0
sp_payment_common 13139 13139 0 0.00 1 10632 30 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 11455 323 0 0
sp_qd_handle_on_invoice_upd_sts 11472 11472 0 0.00 0 21018 308 0 0
sp_qd_handle_on_reserve_upd_sts 10095 10095 0 0.00 0 17671 57 0 0
sp_reserve_write_off 10095 10095 0 0.00 6 17727 173 0 0
sp_supplier_invoice 5505 5505 0 0.00 7 87460 3078 0 0
sp_supplier_order 5505 5505 0 0.00 5 34845 1241 0 0
srv_aggregate_perf_data 96 96 0 0.00 2470 584234 24214 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 739 139395 41913 0 0
srv_find_qd_qs_mism 1134985 1134985 0 0.00 0 15085 4 0 0
srv_make_invnt_saldo 552 552 0 0.00 20 118765 1239 0 0
srv_make_money_saldo 816 816 0 0.00 1 6179 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 216554 330115 261572 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5037 5037 0 0.00 0 15219 159 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 7118 109 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 2540 12 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1709 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 4707 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 486 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 3431 29 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 20811 1529 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 5923 20 0 0
t$perf-norm:sp_client_order 20568 20568 0 0.00 0 7776 17 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 2653 10 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 10724 11 0 0
t$perf-norm:sp_pay_to_supplier 5978 5978 0 0.00 0 9067 15 0 0
t$perf-norm:sp_reserve_write_off 10095 10095 0 0.00 0 6291 10 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 7056 16 0 0
t$perf-norm:sp_supplier_order 5505 5505 0 0.00 0 9989 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 41 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4871 84 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 1760 16 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 1075 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 10286 10060 226 2.19 0 6331 19 0 226
v_all_customers 37464 37464 0 0.00 0 18 1 0 0
v_all_suppliers 31060 31060 0 0.00 0 21 1 0 0
v_all_wares 41136 41136 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1645 10 0 0
v_cancel_client_order 3572 3572 0 0.00 0 5332 12 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 4630 10 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 3261 9 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 3242 7 0 0
v_cancel_supplier_invoice 872 852 20 2.29 0 4261 20 0 20
v_cancel_supplier_order 874 874 0 0.00 0 5321 11 0 0
v_cancel_write_off 2072 2072 0 0.00 0 5889 16 0 0
v_max_id_clo_ord 5505 5505 0 0.00 0 1263 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 1972 13 0 0
v_max_id_ord_sup 5488 5488 0 0.00 0 1956 2 0 0
v_max_non_paid_invoice 1441 1441 0 0.00 0 7622 12 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 1896 4 0 0
v_min_id_clo_ord 5505 5505 0 0.00 0 2642 7 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 7147 32 0 0
v_min_id_ord_sup 5488 5488 0 0.00 0 4061 5 0 0
v_min_non_paid_invoice 5978 5978 0 0.00 0 10628 24 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 7158 20 0 0
v_our_firm 10352 10352 0 0.00 0 85 1 0 0
v_reserve_write_off 20190 20190 0 0.00 0 6662 8 0 0
x_lock_depdocs_on_canc_invoice 13172 13172 0 0.00 0 14218 108 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 15357 418 0 0
x_make_qstorno_1000_1200 5505 5505 0 0.00 1 34821 1165 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 3 81305 2957 0 0
x_make_qstorno_2100_3300 466340 466340 0 0.00 0 53593 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-09-2022 16:39:35 to 26-09-2022 16:39:40.

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
14:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:31 18694 8192000000 9896079984 10201686016 978144832 10.666 100 93 186 0 1009113008 6195552 24772624
14:10:53 17803 8192000000 9930853104 10253492224 1020474800 11.077 100 82 164 0 1049793856 8009616 21309440
14:12:14 17665 8192000000 9973114320 10302210048 1055849216 11.417 100 85 170 0 1086546720 9328704 21368800
14:13:53 10567 8192000000 10026125600 10371117056 1112576496 11.957 100 76 152 0 1140861856 10136736 18148624
14:15:06 5669 8192000000 10073809552 10424680448 1160778288 12.411 100 82 167 0 1191464880 11764672 18921920
14:16:19 3309 8192000000 10126990832 10490822656 1210446176 12.873 100 84 164 0 1241662032 13118976 18096880
14:17:24 1340 8192000000 10150605088 10524127232 1243009552 13.174 100 87 175 0 1275726320 14034192 18682576
14:18:26 1344 8192000000 10189158080 10566787072 1271907648 13.439 100 82 164 0 1302482768 13038800 17536320
14:19:29 2882 8192000000 10193445232 10581352448 1293474352 13.636 100 80 159 0 1323823392 12282928 18066112
14:20:33 3398 8192000000 10184447840 10588037120 1290735840 13.611 100 48 94 2 1311991392 11257808 9997744
14:21:37 4287 8192000000 10192915248 10603368448 1302845440 13.721 100 30 59 2 1319627744 10265536 6516768
14:22:48 13041 8192000000 10199641152 10612666368 1308052960 13.768 100 15 30 1 1317339440 6441728 2844752
14:24:03 60270 8192000000 10221508656 10630426624 1320320704 13.880 100 38 76 0 1335065840 5292368 9452768
14:26:04 5355 8192000000 10217641936 10637815808 1328795312 13.956 100 25 49 1 1340449856 5572736 6081808
14:27:09 6537 8192000000 10219834144 10634326016 1316576656 13.846 99 24 48 0 1327664640 5990256 5097728
14:28:18 14929 8192000000 10202439184 10618355712 1294683568 13.647 98 7 14 0 1301455248 5381984 1389696
14:29:37 17188 8192000000 10193837312 10611974144 1290680480 13.610 98 8 17 0 1296800768 4595888 1524400
14:30:55 5472 8192000000 10125159312 10520924160 1199663808 12.773 95 19 37 1 1208144592 4615936 3864848
14:32:03 7500 8192000000 10160622304 10555269120 1259070224 13.321 100 28 57 2 1270515424 4617456 6827744
14:33:11 62892 8192000000 10160664912 10568826880 1264308192 13.369 99 21 42 0 1274074288 4631328 5134768
14:35:16 8390 8192000000 10195230464 10604675072 1297369520 13.671 100 36 75 0 1314310576 9592128 7348928
14:36:26 5401 8192000000 10197706064 10610171904 1308445968 13.772 100 28 56 0 1321310352 5468576 7395808
14:37:33 19650 8192000000 10204545264 10621042688 1314479600 13.827 100 22 44 0 1324876992 5222576 5174816
14:38:54 29343 8192000000 10250044864 10662801408 1335503232 14.017 100 45 88 1 1359401536 13317280 10581024
14:40:27 23448 8192000000 10254761584 10674053120 1340066864 14.058 99 38 76 0 1361779648 13397216 8315568
14:41:58 22258 8192000000 10274883104 10702663680 1373763424 14.361 100 37 74 0 1395905584 13277712 8864448
14:43:27 29383 8192000000 10299678928 10727813120 1392024960 14.524 100 32 64 1 1419892288 19997248 7870080
14:44:58 11867 8192000000 10315333200 10736795648 1388232320 14.490 99 52 100 2 1422822064 22676480 11913264
14:46:15 9039 8192000000 10314725616 10744705024 1391181536 14.516 99 37 71 1 1422472320 22467184 8823600
14:47:35 2732 8192000000 10346078192 10767237120 1414919456 14.728 100 61 121 2 1455030464 26076192 14034816
14:48:43 8636 8192000000 10339697312 10766340096 1405085472 14.640 99 48 94 1 1445746320 28808944 11851904
14:49:57 1422 8192000000 10381488688 10796396544 1426568704 14.831 100 83 166 1 1484796656 37825168 20402784
14:51:01 4650 8192000000 10368439136 10790506496 1420024704 14.773 100 66 133 1 1479727376 42721568 16981104
14:52:13 4748 8192000000 10364440112 10786385920 1408824512 14.673 100 66 127 1 1476030928 50814016 16392400
14:53:25 5621 8192000000 10352995760 10779328512 1395592832 14.556 99 57 112 1 1454410752 44434992 14382928
14:54:37 1941 8192000000 10358907424 10772086784 1399272224 14.589 100 74 149 0 1462614832 45351648 17990960
14:55:44 1688 8192000000 10350194240 10761900032 1384952064 14.461 100 73 146 1 1451161696 48090000 18119632
14:56:49 5446 8192000000 10366244176 10784940032 1401645664 14.610 100 64 127 0 1464398960 47247456 15505840
14:58:00 14396 8192000000 10355952272 10786078720 1397639968 14.574 100 40 80 0 1466434096 57375984 11418144
14:59:22 25505 8192000000 10347452752 10779320320 1399186624 14.588 100 36 72 0 1458115072 48734176 10194272
15:00:51 739 8192000000 10354404208 10774585344 1392245696 14.526 100 57 115 0 1458150944 50090784 15814464
15:01:57 14510 8192000000 10329625872 10766508032 1356066304 14.202 98 32 63 0 1428098144 63201200 8830640
15:03:29 13213 8192000000 10337059120 10770108416 1375328736 14.375 100 33 65 0 1446660032 61925104 9406192
15:04:58 21261 8192000000 10334622752 10772787200 1374633088 14.369 100 32 64 0 1442665280 58879808 9152384
15:06:26 28332 8192000000 10322332128 10788696064 1383728368 14.450 100 16 32 0 1437707648 49461920 4517360
15:08:06 134550 8192000000 10274643792 10719223808 1300058400 13.696 94 12 25 0 1329331008 26818656 2453952
15:11:23 39240 8192000000 10281472480 10718040064 1337638176 14.036 97 12 20 0 1349962864 9962400 2362288
15:13:03 13691 8192000000 10318939328 10757455872 1389245728 14.499 100 51 102 0 1417456320 16108496 12102096
15:14:19 139394 8192000000 10301311808 10747490304 1369897216 14.326 99 18 36 0 1391267632 16965056 4405360
15:17:46 74158 8192000000 10325986528 10761613312 1384423680 14.456 99 25 48 0 1405681232 15733184 5524368
15:20:04 53089 8192000000 10302861296 10750234624 1355724048 14.199 97 16 32 0 1372112288 12628144 3760096
15:21:58 13739 8192000000 10332483696 10775531520 1402337520 14.616 100 48 96 0 1435472192 21940416 11194256
15:23:28 17164 8192000000 10345837104 10784276480 1405207536 14.641 100 42 85 1 1437384928 21666048 10511344
15:24:51 38064 8192000000 10316725344 10795495424 1399381536 14.589 100 7 14 0 1420849184 19584656 1882992
15:26:30 59155 8192000000 10323655424 10789216256 1410435376 14.688 100 12 24 0 1430901712 17238240 3228096
15:29:08 72650 8192000000 10309623376 10782879744 1395037456 14.551 99 12 24 0 1415152624 17353232 2761936
15:31:33 28491 8192000000 10309603600 10776481792 1407851040 14.665 100 25 50 0 1423888752 10483712 5554000
15:33:02 70822 8192000000 10311121744 10782597120 1408481760 14.670 100 21 42 0 1426605536 13311456 4812320
15:35:28 35427 8192000000 10296867920 10769027072 1375809728 14.379 98 12 24 0 1388381584 9685408 2886448
15:37:07 41610 8192000000 10293682432 10762260480 1388818160 14.495 100 20 40 0 1405070736 11125264 5127312
15:38:51 40610 8192000000 10281323952 10737983488 1356332832 14.204 97 16 32 0 1366828784 7267344 3228608
15:40:33 78140 8192000000 10267699936 10716356608 1301827776 13.712 95 8 16 0 1311849168 8327552 1693840
15:42:51 50412 8192000000 10270639984 10726191104 1328488048 13.953 97 12 24 0 1346487312 14685248 3314016
15:44:50 8520 8192000000 10285958608 10722918400 1352867936 14.173 99 54 108 1 1373388576 8629760 11890880
15:46:02 45156 8192000000 10288863216 10733223936 1358575088 14.225 99 19 38 0 1380702768 17853312 4274368
15:47:49 70283 8192000000 10299776448 10765426688 1372190320 14.347 99 18 37 0 1394721296 18379536 4151440
15:50:07 139159 8192000000 10261112656 10716094464 1255804304 13.292 91 7 14 0 1272531216 15405984 1320928
15:53:27 79678 8192000000 10253378096 10710241280 1321958768 13.894 97 18 36 0 1339427552 13541904 3926880
15:55:50 71783 8192000000 10255374240 10720186368 1335094032 14.013 99 25 51 0 1354961264 14210032 5657200
15:58:49 70999 8192000000 10252726896 10705764352 1335508688 14.017 98 17 34 0 1346831104 7340288 3982128
16:01:02 44580 8192000000 10266252816 10737152000 1367910448 14.308 100 8 17 0 1379905552 9807904 2187200
16:03:02 79091 8192000000 10264018704 10734641152 1347357152 14.124 98 7 14 0 1367182336 18152336 1672848
16:05:32 56097 8192000000 10289927968 10747469824 1349924656 14.147 98 25 50 0 1366766672 11169888 5672128
16:07:28 29339 8192000000 10293747136 10736795648 1357987600 14.219 99 33 66 1 1383482640 18131808 7363232
16:09:01 57709 8192000000 10297430720 10761199616 1383390624 14.447 100 19 38 0 1402087552 14626240 4070688
16:11:30 86040 8192000000 10320039680 10781122560 1386320368 14.473 99 14 29 1 1402850976 13697696 2832912
16:13:57 13627 8192000000 10304238528 10756292608 1370909040 14.335 98 39 75 3 1387407248 8199568 8298640
16:15:13 64801 8192000000 10304227440 10777460736 1366426384 14.295 98 22 45 0 1392412848 20181984 5804480
16:17:31 14932 8192000000 10327490576 10771218432 1393125552 14.534 99 44 88 0 1413486448 11038400 9322496
16:18:47 55305 8192000000 10315101504 10776944640 1377692640 14.396 98 20 40 1 1395091424 12906112 4492672
16:22:03 52273 8192000000 10284216192 10753351680 1343962592 14.093 96 11 20 0 1362840576 17023840 1854144
16:24:00 91473 8192000000 10286909328 10754805760 1356938096 14.210 98 9 17 0 1371235488 12149120 2148272
16:26:34 63109 8192000000 10297745552 10757902336 1355226736 14.194 97 21 41 0 1368966560 8967488 4772336
16:28:38 58658 8192000000 10299593696 10768904192 1396866400 14.567 100 13 26 0 1409949152 10250960 2831792
16:30:37 56598 8192000000 10270376000 10743468032 1324889168 13.921 95 8 16 0 1340492704 13484320 2119216
16:32:35 53767 8192000000 10275805488 10722631680 1282029792 13.532 93 24 48 0 1300503152 13223376 5249984
16:34:29 57202 8192000000 10292189152 10747686912 1365778480 14.289 99 16 33 0 1383034768 13561216 3695072
16:36:30 20216 8192000000 10275493856 10721447936 1343950784 14.093 98 31 62 1 1358972912 8477440 6544688
16:37:51 45205 8192000000 9191561760 9451986944 339446496 3.978 25 5 10 0 349574720 9317136 811088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2022 16:39:42 to 26-09-2022 16:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 16:39:58 to 26-09-2022 16:40:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239917
MON$OLDEST_ACTIVE               241287
MON$OLDEST_SNAPSHOT             241287
MON$NEXT_TRANSACTION            241287
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-03 16:41:55.3470
MON$PAGES                       2052896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Mon Sep 26 16:38:38 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Mon Sep 26 16:38:43 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
--- end of diff output ---

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

26.09.22 16:40:01. There are 3 logs in /var/tmp/logs-oltp30 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-09-26 16:40:04 No severe PSQL-related problems occured
26.09.22 16:40:05. End of report.