Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2022 16:42
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 14793496 kB
MemAvailable 30060932 kB
Buffers 0 kB
Cached 16876256 kB
SwapCached 3204 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-12-24 16:42:21
DTS_END                         2022-12-24 16:42:21.3020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60800
TRN_ID                          242553
ATT_ID                          644
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: 9124.92 24268 1094990
customer order: creation 176.30 335 21156
customer order: refuse 15.43 340 1851
order to supplier: creation 47.09 1212 5651
order to supplier: removal 3.43 108631 411
invoice (draft): creation 47.34 3182 5681
invoice (draft): removal 49.80 890 5976
invoice accept: apply 42.84 18122 5141
invoice accept: cancel 45.06 857 5407
customer reserve: creation 3958.27 178 474992
customer reserve: removal 4447.19 65 533663
realization accept: apply 84.58 188 10150
realization accept: cancel 74.00 114 8880
payment from customer: creation 62.41 101 7489
payment from customer: removal 5.92 76 710
payment to supplier: creation 49.43 124 5932
payment to supplier: removal 3.76 81 451
service: total inventory turnovers 4.92 1205 590
service: total monetary turnovers 6.32 94 758
service: aggregate perf. data 0.81 23223 97
service: refresh index statistics 0.03 326334 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2022 16:43:19 to 24-12-2022 16:43:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5468
TEST_TIME 2 6699
TEST_TIME 3 5519
TEST_TIME 4 5219
TEST_TIME 5 5868
TEST_TIME 6 5753
TEST_TIME 7 5711
TEST_TIME 8 6025
TEST_TIME 9 6637
TEST_TIME 10 6787
TEST_TIME 11 6962
TEST_TIME 12 7627
TEST_TIME 13 7398
TEST_TIME 14 7608
TEST_TIME 15 7592
TEST_TIME 16 7767
TEST_TIME 17 7662
TEST_TIME 18 7966
TEST_TIME 19 8069
TEST_TIME 20 7991
TEST_TIME 21 8116
TEST_TIME 22 8234
TEST_TIME 23 8411
TEST_TIME 24 8863
TEST_TIME 25 8785
TEST_TIME 26 9174
TEST_TIME 27 9147
TEST_TIME 28 9703
TEST_TIME 29 10065
TEST_TIME 30 11094
TEST_TIME 31 10847
TEST_TIME 32 10790
TEST_TIME 33 10787
TEST_TIME 34 10605
TEST_TIME 35 10664
TEST_TIME 36 10585
TEST_TIME 37 10444
TEST_TIME 38 10347
TEST_TIME 39 10321
TEST_TIME 40 10198
TEST_TIME 41 10128
TEST_TIME 42 10013
TEST_TIME 43 9829
TEST_TIME 44 9907
TEST_TIME 45 9846
TEST_TIME 46 9770
TEST_TIME 47 9795
TEST_TIME 48 9684
TEST_TIME 49 9576
TEST_TIME 50 9549
TEST_TIME 51 9550
TEST_TIME 52 9464
TEST_TIME 53 9530
TEST_TIME 54 9474
TEST_TIME 55 9420
TEST_TIME 56 9406
TEST_TIME 57 9344
TEST_TIME 58 9282
TEST_TIME 59 9246
TEST_TIME 60 9167
TEST_TIME 61 9203
TEST_TIME 62 9208
TEST_TIME 63 9146
TEST_TIME 64 9196
TEST_TIME 65 9334
TEST_TIME 66 9334
TEST_TIME 67 9385
TEST_TIME 68 9354
TEST_TIME 69 9313
TEST_TIME 70 9363
TEST_TIME 71 9410
TEST_TIME 72 9464
TEST_TIME 73 9544
TEST_TIME 74 9539
TEST_TIME 75 9465
TEST_TIME 76 9448
TEST_TIME 77 9371
TEST_TIME 78 9380
TEST_TIME 79 9341
TEST_TIME 80 9354
TEST_TIME 81 9295
TEST_TIME 82 9234
TEST_TIME 83 9197
TEST_TIME 84 9188
TEST_TIME 85 9126
TEST_TIME 86 9113
TEST_TIME 87 9163
TEST_TIME 88 9118
TEST_TIME 89 9137
TEST_TIME 90 9150
TEST_TIME 91 9245
TEST_TIME 92 9225
TEST_TIME 93 9215
TEST_TIME 94 9246
TEST_TIME 95 9304
TEST_TIME 96 9255
TEST_TIME 97 9319
TEST_TIME 98 9370
TEST_TIME 99 9362
TEST_TIME 100 9344
TEST_TIME 101 9439
TEST_TIME 102 9352
TEST_TIME 103 9274
TEST_TIME 104 9254
TEST_TIME 105 9224
TEST_TIME 106 9209
TEST_TIME 107 9205
TEST_TIME 108 9160
TEST_TIME 109 9135
TEST_TIME 110 9086
TEST_TIME 111 9104
TEST_TIME 112 9078
TEST_TIME 113 9077
TEST_TIME 114 9097
TEST_TIME 115 9144
TEST_TIME 116 9127
TEST_TIME 117 9187
TEST_TIME 118 9189
TEST_TIME 119 9166
TEST_TIME 120 9129

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2022 16:43:23 to 24-12-2022 16:43:26.

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 1534401 1534401 0 0.00 0 12170 6 0 0
doc_list_biud 1534400 1534400 0 0.00 0 76340 29 0 0
sp_add_invoice_to_stock 5259 5141 118 2.24 34 163287 18123 0 118
sp_cancel_adding_invoice 5407 5407 0 0.00 5 59221 858 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 10503 340 0 0
sp_cancel_customer_reserve 533663 533663 0 0.00 0 19595 66 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 4036 77 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 3411 82 0 0
sp_cancel_supplier_invoice 5986 5976 10 0.16 2 76349 891 0 10
sp_cancel_supplier_order 411 411 0 0.00 10 1037822 108631 0 0
sp_cancel_write_off 8880 8880 0 0.00 1 13191 114 0 0
sp_client_order 21156 21156 0 0.00 3 33678 335 0 0
sp_customer_reserve 474992 474992 0 0.00 0 53010 178 0 0
sp_fill_shopping_cart 32479 32479 0 0.00 1 10213 43 0 0
sp_fill_shopping_cart_clo_res 474986 474986 0 0.00 0 23075 14 0 0
sp_get_clo_for_invoice 5128 5128 0 0.00 0 31335 326 0 0
sp_kill_cost_storno 15448 15448 0 0.00 0 9771 61 0 0
sp_kill_qstorno_ret_qs2qd 1082593 1082593 0 0.00 0 76336 36 0 0
sp_kill_qty_storno 571465 571465 0 0.00 0 76339 77 0 0
sp_lock_selected_doc 21166 21166 0 0.00 0 5822 3 0 0
sp_make_cost_storno 28699 28699 0 0.00 0 10169 52 0 0
sp_multiply_rows_for_pdistr 28698 28698 0 0.00 0 8630 30 0 0
sp_multiply_rows_for_qdistr 21156 21156 0 0.00 0 15148 150 0 0
sp_pay_from_customer 7489 7489 0 0.00 4 11161 102 0 0
sp_pay_to_supplier 5932 5932 0 0.00 4 17695 125 0 0
sp_payment_common 13421 13421 0 0.00 1 11143 27 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 10484 314 0 0
sp_qd_handle_on_invoice_upd_sts 10535 10535 0 0.00 0 19701 366 0 0
sp_qd_handle_on_reserve_upd_sts 10149 10149 0 0.00 0 16477 70 0 0
sp_reserve_write_off 10150 10150 0 0.00 6 16567 189 0 0
sp_supplier_invoice 5681 5681 0 0.00 6 72416 3183 0 0
sp_supplier_order 5651 5651 0 0.00 4 61211 1212 0 0
srv_aggregate_perf_data 97 97 0 0.00 2904 638018 23224 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1704 130042 41165 0 0
srv_find_qd_qs_mism 1045647 1045647 0 0.00 0 12170 6 0 0
srv_make_invnt_saldo 590 590 0 0.00 36 159389 1206 0 0
srv_make_money_saldo 758 758 0 0.00 0 18355 94 0 0
srv_recalc_idx_stat 4 4 0 0.00 240784 455134 326334 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 5141 5141 0 0.00 0 14812 190 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 4262 78 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 3650 13 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1509 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 1532 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 1299 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 1322 22 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 23085 1452 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 7160 14 0 0
t$perf-norm:sp_client_order 21156 21156 0 0.00 0 7086 17 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 3610 9 0 0
t$perf-norm:sp_pay_from_customer 7489 7489 0 0.00 0 6403 10 0 0
t$perf-norm:sp_pay_to_supplier 5932 5932 0 0.00 0 6527 12 0 0
t$perf-norm:sp_reserve_write_off 10150 10150 0 0.00 0 10603 10 0 0
t$perf-norm:sp_supplier_invoice 5681 5681 0 0.00 0 6416 18 0 0
t$perf-norm:sp_supplier_order 5651 5651 0 0.00 0 12722 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 855 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 145 5 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 847 24 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 4229 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10492 10256 236 2.24 0 4470 18 0 236
v_all_customers 39152 39152 0 0.00 0 146 1 0 0
v_all_suppliers 31236 31236 0 0.00 0 121 1 0 0
v_all_wares 42312 42312 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 3441 16 0 0
v_cancel_client_order 3702 3702 0 0.00 0 2490 5 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 1207 5 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 3443 8 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 3368 11 0 0
v_cancel_supplier_invoice 760 740 20 2.63 0 5614 21 0 20
v_cancel_supplier_order 822 822 0 0.00 0 3240 14 0 0
v_cancel_write_off 2004 2004 0 0.00 0 2114 14 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 2572 2 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 3332 17 0 0
v_max_id_ord_sup 5673 5673 0 0.00 0 2252 3 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 522 4 0 0
v_max_non_paid_realizn 3926 3926 0 0.00 0 5072 6 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 3584 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 5597 8 0 0
v_min_id_ord_sup 5673 5673 0 0.00 0 3781 6 0 0
v_min_non_paid_invoice 5932 5932 0 0.00 0 7783 16 0 0
v_min_non_paid_realizn 7489 7489 0 0.00 0 6297 19 0 0
v_our_firm 10286 10286 0 0.00 0 83 1 0 0
v_reserve_write_off 20298 20298 0 0.00 0 3570 5 0 0
x_lock_depdocs_on_canc_invoice 11013 11013 0 0.00 0 12261 126 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 2755 383 0 0
x_make_qstorno_1000_1200 5651 5651 0 0.00 1 61186 1121 0 0
x_make_qstorno_1200_2000 5681 5681 0 0.00 2 68098 3029 0 0
x_make_qstorno_2100_3300 474992 474992 0 0.00 0 50376 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2022 16:43:27 to 24-12-2022 16:43:31.

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:12:25 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:25 15976 8192000000 9898913008 10210885632 981211936 10.696 100 89 179 0 1010905536 6196544 23497056
14:14:48 26824 8192000000 9948311984 10272038912 1030825824 11.176 100 85 170 0 1061385040 8295936 22263280
14:16:27 10637 8192000000 9983314320 10320384000 1072070064 11.572 100 83 166 0 1102903248 10203216 20629968
14:17:42 7544 8192000000 10020089376 10366713856 1108366688 11.917 100 74 148 0 1137217664 11024096 17826880
14:18:51 6070 8192000000 10073132320 10426810368 1154600320 12.353 100 84 169 0 1187009632 12785440 19623872
14:20:00 4026 8192000000 10108813104 10470170624 1191216656 12.695 100 83 163 1 1223053152 13629936 18206560
14:21:09 3189 8192000000 10141074448 10512797696 1232638912 13.078 100 81 160 1 1264234016 14097120 17497984
14:22:15 1462 8192000000 10179722064 10557763584 1266598144 13.390 100 88 175 1 1298900816 12890832 19411840
14:23:18 2300 8192000000 10190427024 10579714048 1285104928 13.560 100 74 149 0 1312719488 11166432 16448128
14:24:24 1364 8192000000 10203858048 10594205696 1293328624 13.635 100 73 140 1 1319063888 10498032 15237232
14:25:27 3322 8192000000 10202767440 10604007424 1301211440 13.706 100 46 92 1 1325246912 14058016 9977456
14:26:32 14885 8192000000 10195827856 10607845376 1311184112 13.797 100 21 41 0 1322393008 6950032 4258864
14:27:48 10812 8192000000 10199959984 10619981824 1318789344 13.866 100 12 24 0 1328191456 6562336 2839776
14:29:01 50185 8192000000 10234624976 10635423744 1324539344 13.918 100 62 125 2 1345040000 5860672 14639984
14:30:52 6330 8192000000 10215130368 10631168000 1321751744 13.893 100 25 50 0 1333681312 6753184 5176384
14:31:59 7370 8192000000 10219069536 10639036416 1329507200 13.963 100 20 42 0 1339145728 4820224 4818304
14:33:06 24130 8192000000 10194402720 10615074816 1281977408 13.531 97 20 40 2 1291887520 4434704 5475408
14:34:34 4989 8192000000 10153720864 10553257984 1238715936 13.134 98 28 56 0 1256214736 11736016 5762784
14:35:42 5274 8192000000 10127820336 10527801344 1218251584 12.946 98 23 47 0 1233616048 10226736 5137728
14:36:49 55663 8192000000 10130647344 10518032384 1196679776 12.745 95 39 72 0 1210343232 4879104 8784352
14:38:48 6285 8192000000 10175397360 10577690624 1274763744 13.465 100 25 51 1 1286302656 5534240 6004672
14:39:55 8596 8192000000 10187524928 10598596608 1293035520 13.632 100 25 47 1 1304405152 5245952 6123680
14:41:04 12343 8192000000 10202604384 10617573376 1307357440 13.762 100 12 23 0 1315399696 5238128 2804128
14:42:19 23847 8192000000 10233186416 10640789504 1326890736 13.939 100 43 82 2 1341183904 6031552 8261616
14:43:53 25111 8192000000 10251206864 10673528832 1352517488 14.170 100 47 94 0 1374011632 11455808 10038336
14:45:28 14442 8192000000 10276346768 10694447104 1369062848 14.319 100 48 95 0 1390731216 11479264 10189104
14:46:46 34144 8192000000 10285167424 10708357120 1375782032 14.379 100 46 92 0 1400746864 14610448 10354384
14:48:23 5674 8192000000 10307416720 10726842368 1393876144 14.540 100 48 94 2 1418590960 14262912 10451904
14:49:33 12626 8192000000 10307628736 10734338048 1393791616 14.540 100 49 99 1 1420459424 15110144 11557664
14:50:48 3131 8192000000 10332163888 10753335296 1406040848 14.649 100 65 128 1 1441702816 20844576 14817392
14:51:56 1968 8192000000 10347410944 10772856832 1422140208 14.792 100 76 147 1 1461111824 20980816 17990800
14:53:02 2134 8192000000 10350919808 10769338368 1414293792 14.722 100 81 159 0 1458773904 25583728 18896384
14:54:06 2218 8192000000 10335727488 10750246912 1392686432 14.530 99 80 160 1 1438941808 26246112 20009264
14:55:13 4667 8192000000 10315745280 10738716672 1377919792 14.398 99 58 116 1 1416760256 24954000 13886464
14:56:28 2022 8192000000 10340906000 10756108288 1395119744 14.552 100 73 142 3 1442928624 30139440 17669440
14:57:35 7281 8192000000 10331032704 10747174912 1388858544 14.496 100 66 133 0 1438985040 32628352 17498144
14:58:51 5121 8192000000 10365441248 10786963456 1414943376 14.728 100 58 116 0 1470244128 40403760 14896992
15:00:06 3475 8192000000 10337898208 10750218240 1376273296 14.383 99 74 145 0 1437841888 42938832 18629760
15:01:16 2081 8192000000 10364448896 10773925888 1405226160 14.642 100 87 171 1 1466367200 39629696 21511344
15:02:25 4207 8192000000 10362735232 10773520384 1397724288 14.575 100 78 154 2 1458556672 41029904 19802480
15:03:38 1792 8192000000 10362723008 10775965696 1402203392 14.615 100 66 128 2 1461216560 42644688 16368480
15:04:49 1703 8192000000 10383955392 10793304064 1416867312 14.745 100 89 175 0 1490879920 51497072 22515536
15:05:59 3067 8192000000 10377154976 10794708992 1410760672 14.691 100 62 122 1 1479877504 52177552 16939280
15:07:04 12242 8192000000 10364292448 10788376576 1399196928 14.588 99 42 84 1 1456872160 46418992 11256240
15:08:22 20339 8192000000 10359876496 10790158336 1381182736 14.427 98 35 70 1 1443014944 51899584 9932624
15:09:44 17289 8192000000 10349886384 10790834176 1365787280 14.289 98 31 61 0 1438512160 63523856 9201024
15:11:24 25311 8192000000 10317019584 10768904192 1365321024 14.285 100 18 36 0 1423862768 53107616 5434128
15:12:51 45683 8192000000 10289727072 10725294080 1360380272 14.241 98 29 56 0 1376576384 9408912 6787200
15:14:38 10585 8192000000 10308455232 10749997056 1389749568 14.504 100 28 57 0 1412353648 16199200 6404880
15:16:40 43849 8192000000 10287017808 10749566976 1354913888 14.192 98 4 8 0 1375177408 19442544 820976
15:18:24 68750 8192000000 10284335632 10728878080 1363375024 14.268 99 16 32 0 1376784672 10170496 3239152
15:20:35 63464 8192000000 10290645360 10740428800 1384415104 14.456 100 29 60 0 1399421376 7990896 7015376
15:22:47 75036 8192000000 10290074032 10744094720 1367467552 14.304 98 28 56 0 1386953536 13190944 6295040
15:25:06 30926 8192000000 10303609872 10748854272 1379506176 14.412 99 49 92 0 1403283968 12637712 11140080
15:26:45 40501 8192000000 10294474736 10753126400 1395389616 14.554 100 10 20 0 1410123392 12558544 2175232
15:28:27 29023 8192000000 10302016624 10759573504 1399280016 14.589 100 10 20 0 1412305792 10295072 2730704
15:29:57 67492 8192000000 10268574496 10725306368 1359722576 14.235 97 6 12 0 1375331488 13783104 1825808
15:32:04 58795 8192000000 10270173312 10732736512 1328620992 13.955 96 11 22 3 1347906080 16289040 2996048
15:34:03 118306 8192000000 10269485648 10735742976 1346141440 14.113 98 25 48 0 1370098352 18085168 5871744
15:37:02 35859 8192000000 10267749120 10729332736 1376065536 14.381 100 10 19 0 1385847440 7794944 1986960
15:38:38 63995 8192000000 10264140144 10722148352 1357846544 14.218 98 14 28 0 1370341344 9331440 3163360
15:40:42 43653 8192000000 10283137728 10737029120 1384713968 14.459 100 24 46 0 1398454848 8250368 5490512
15:43:09 56821 8192000000 10264515024 10728144896 1346899888 14.120 97 9 18 0 1354928704 6193344 1835472
15:45:07 63131 8192000000 10249887216 10703065088 1288053296 13.586 94 21 42 0 1305863344 12755312 5054736
15:47:23 70340 8192000000 10260412928 10717859840 1325681072 13.928 97 13 26 0 1342495168 13692736 3121360
15:49:41 77768 8192000000 10276371728 10725236736 1336439824 14.025 97 10 20 0 1346140320 7760864 1939632
15:52:15 40030 8192000000 10290320352 10739298304 1366643456 14.297 99 42 84 0 1394484784 18237760 9603568
15:54:03 130042 8192000000 10269887424 10721112064 1295350768 13.653 94 12 25 0 1315995088 17852608 2791712
15:57:24 19889 8192000000 10288267984 10715742208 1329034704 13.958 97 49 94 1 1351022928 11258128 10730096
15:58:48 87409 8192000000 10265761168 10727301120 1362950656 14.264 100 35 70 0 1390189040 19326272 7912112
16:01:18 7245 8192000000 10278564752 10725167104 1368636080 14.315 100 27 54 1 1385985456 10792320 6557056
16:02:26 51602 8192000000 10259013008 10723823616 1348829456 14.137 98 6 12 0 1357370800 7396496 1144848
16:04:41 62353 8192000000 10261534064 10717130752 1357560640 14.215 99 15 30 0 1368660864 7484752 3615472
16:06:47 61781 8192000000 10264297952 10713911296 1347816256 14.128 98 22 45 0 1361091456 7832384 5442816
16:08:53 62267 8192000000 10265407872 10723270656 1343382880 14.088 98 12 24 0 1354556752 8581968 2591904
16:10:56 44940 8192000000 10293759104 10748420096 1375523776 14.377 99 23 46 1 1394710672 13990464 5196432
16:12:42 42468 8192000000 10288408464 10749865984 1383049792 14.444 100 8 16 0 1400150000 15277168 1823040
16:15:09 57812 8192000000 10312324960 10753978368 1387231392 14.481 99 24 48 0 1401764880 9180560 5352928
16:17:07 81488 8192000000 10309077136 10759393280 1374818656 14.370 98 19 38 0 1391286304 12578592 3889056
16:19:31 92652 8192000000 10282586784 10744025088 1322524960 13.900 95 7 14 0 1338544128 14610560 1408608
16:22:08 101890 8192000000 10286664944 10751320064 1341987888 14.075 97 10 20 0 1365575360 21463568 2123904
16:24:50 92569 8192000000 10282848352 10735099904 1371717552 14.342 99 20 37 1 1384767632 8914288 4135792
16:27:30 77247 8192000000 10259766368 10721210368 1324744880 13.920 96 7 14 0 1338981584 12627440 1609264
16:29:55 72293 8192000000 10260996256 10713227264 1325048576 13.922 97 9 18 1 1333608144 6985536 1574032
16:32:16 80096 8192000000 10254140464 10714021888 1350706544 14.154 99 15 30 0 1363871728 9747984 3417200
16:35:01 30319 8192000000 10247288832 10691170304 1311066800 13.796 97 16 32 0 1325435728 10690512 3678416
16:36:34 51371 8192000000 10265192192 10718531584 1344764928 14.100 99 19 38 1 1362499408 13269616 4464864
16:38:40 37617 8192000000 10262699264 10702585856 1319985184 13.877 97 21 42 1 1338199776 13273216 4941376
16:40:19 58482 8192000000 10255828912 10703745024 1345178656 14.104 99 30 59 0 1365629856 13928192 6523008
16:42:18 14266 8192000000 9062067792 9286586368 228206464 2.710 17 5 10 0 232407648 3118784 1082400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-12-2022 16:43:33 to 24-12-2022 16:43:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 24-12-2022 16:43:50 to 24-12-2022 16:43:52.

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          242983
MON$OLDEST_ACTIVE               242984
MON$OLDEST_SNAPSHOT             242984
MON$NEXT_TRANSACTION            242984
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                       2057632
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Dec 24 16:42:44 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

24.12.22 16:43:53. 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-12-24 16:43:55 No severe PSQL-related problems occured
24.12.22 16:43:57. End of report.