Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.03.2023 01: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 15330112 kB
MemAvailable 30398828 kB
Buffers 0 kB
Cached 16370412 kB
SwapCached 3028 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 2023-03-07 01:38:44
DTS_END                         2023-03-07 01:38:44.3410
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/42496
TRN_ID                          246178
ATT_ID                          650
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: 9329.58 18178 1119550
customer order: creation 181.03 332 21723
customer order: refuse 15.28 336 1834
order to supplier: creation 48.06 1151 5767
order to supplier: removal 3.45 74244 414
invoice (draft): creation 47.94 3134 5753
invoice (draft): removal 52.91 652 6349
invoice accept: apply 43.48 15273 5217
invoice accept: cancel 47.65 680 5718
customer reserve: creation 3988.13 151 478575
customer reserve: removal 4602.05 42 552246
realization accept: apply 87.18 191 10462
realization accept: cancel 79.10 97 9492
payment from customer: creation 59.86 127 7183
payment from customer: removal 6.13 86 736
payment to supplier: creation 51.62 170 6194
payment to supplier: removal 3.35 63 402
service: total inventory turnovers 4.72 1094 567
service: total monetary turnovers 6.67 36 800
service: aggregate perf. data 0.95 18011 114
service: refresh index statistics 0.03 247684 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-03-2023 01:39:55 to 07-03-2023 01:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2344
TEST_TIME 2 2942
TEST_TIME 3 2415
TEST_TIME 4 2904
TEST_TIME 5 3420
TEST_TIME 6 3585
TEST_TIME 7 4173
TEST_TIME 8 4331
TEST_TIME 9 4696
TEST_TIME 10 6020
TEST_TIME 11 7213
TEST_TIME 12 7300
TEST_TIME 13 7384
TEST_TIME 14 7584
TEST_TIME 15 8137
TEST_TIME 16 8043
TEST_TIME 17 8007
TEST_TIME 18 8209
TEST_TIME 19 8257
TEST_TIME 20 8690
TEST_TIME 21 8717
TEST_TIME 22 8866
TEST_TIME 23 9463
TEST_TIME 24 9471
TEST_TIME 25 9998
TEST_TIME 26 9970
TEST_TIME 27 10168
TEST_TIME 28 10125
TEST_TIME 29 10123
TEST_TIME 30 10230
TEST_TIME 31 9905
TEST_TIME 32 10057
TEST_TIME 33 9951
TEST_TIME 34 9848
TEST_TIME 35 9784
TEST_TIME 36 9807
TEST_TIME 37 9951
TEST_TIME 38 9897
TEST_TIME 39 9758
TEST_TIME 40 9744
TEST_TIME 41 9687
TEST_TIME 42 9801
TEST_TIME 43 9635
TEST_TIME 44 9605
TEST_TIME 45 9529
TEST_TIME 46 9480
TEST_TIME 47 9353
TEST_TIME 48 9385
TEST_TIME 49 9420
TEST_TIME 50 9401
TEST_TIME 51 9388
TEST_TIME 52 9515
TEST_TIME 53 9549
TEST_TIME 54 9506
TEST_TIME 55 9483
TEST_TIME 56 9529
TEST_TIME 57 9766
TEST_TIME 58 9811
TEST_TIME 59 9809
TEST_TIME 60 9816
TEST_TIME 61 9824
TEST_TIME 62 9931
TEST_TIME 63 9783
TEST_TIME 64 9719
TEST_TIME 65 9679
TEST_TIME 66 9666
TEST_TIME 67 9598
TEST_TIME 68 9533
TEST_TIME 69 9466
TEST_TIME 70 9486
TEST_TIME 71 9444
TEST_TIME 72 9466
TEST_TIME 73 9496
TEST_TIME 74 9496
TEST_TIME 75 9471
TEST_TIME 76 9499
TEST_TIME 77 9513
TEST_TIME 78 9669
TEST_TIME 79 9630
TEST_TIME 80 9603
TEST_TIME 81 9614
TEST_TIME 82 9568
TEST_TIME 83 9618
TEST_TIME 84 9660
TEST_TIME 85 9609
TEST_TIME 86 9519
TEST_TIME 87 9492
TEST_TIME 88 9428
TEST_TIME 89 9412
TEST_TIME 90 9369
TEST_TIME 91 9343
TEST_TIME 92 9349
TEST_TIME 93 9321
TEST_TIME 94 9354
TEST_TIME 95 9359
TEST_TIME 96 9384
TEST_TIME 97 9377
TEST_TIME 98 9426
TEST_TIME 99 9441
TEST_TIME 100 9436
TEST_TIME 101 9415
TEST_TIME 102 9479
TEST_TIME 103 9563
TEST_TIME 104 9524
TEST_TIME 105 9562
TEST_TIME 106 9570
TEST_TIME 107 9569
TEST_TIME 108 9486
TEST_TIME 109 9504
TEST_TIME 110 9507
TEST_TIME 111 9482
TEST_TIME 112 9418
TEST_TIME 113 9405
TEST_TIME 114 9390
TEST_TIME 115 9372
TEST_TIME 116 9342
TEST_TIME 117 9350
TEST_TIME 118 9368
TEST_TIME 119 9360
TEST_TIME 120 9330

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-03-2023 01:39:59 to 07-03-2023 01:40:02.

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 1561767 1561767 0 0.00 0 26466 6 0 0
doc_list_biud 1561764 1561764 0 0.00 0 34481 20 0 0
sp_add_invoice_to_stock 5321 5217 104 1.95 27 179464 15273 0 104
sp_cancel_adding_invoice 5718 5718 0 0.00 5 45156 680 0 0
sp_cancel_client_order 1834 1834 0 0.00 4 27294 336 0 0
sp_cancel_customer_reserve 552246 552246 0 0.00 0 32387 43 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 18717 87 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 3006 64 0 0
sp_cancel_supplier_invoice 6357 6349 8 0.12 2 34517 653 0 8
sp_cancel_supplier_order 414 414 0 0.00 5 794337 74245 0 0
sp_cancel_write_off 9492 9492 0 0.00 1 19560 97 0 0
sp_client_order 21723 21723 0 0.00 3 33055 333 0 0
sp_customer_reserve 478575 478575 0 0.00 0 78837 151 0 0
sp_fill_shopping_cart 33229 33229 0 0.00 1 16039 37 0 0
sp_fill_shopping_cart_clo_res 478572 478572 0 0.00 0 13904 13 0 0
sp_get_clo_for_invoice 5214 5214 0 0.00 0 32017 315 0 0
sp_kill_cost_storno 16348 16348 0 0.00 0 19397 45 0 0
sp_kill_qstorno_ret_qs2qd 1120747 1120747 0 0.00 0 34458 24 0 0
sp_kill_qty_storno 591727 591727 0 0.00 0 34474 53 0 0
sp_lock_selected_doc 21743 21743 0 0.00 0 7888 4 0 0
sp_make_cost_storno 29053 29053 0 0.00 0 22014 74 0 0
sp_multiply_rows_for_pdistr 29050 29050 0 0.00 0 20531 27 0 0
sp_multiply_rows_for_qdistr 21723 21723 0 0.00 0 33046 159 0 0
sp_pay_from_customer 7183 7183 0 0.00 4 21089 128 0 0
sp_pay_to_supplier 6194 6194 0 0.00 4 21977 170 0 0
sp_payment_common 13376 13376 0 0.00 1 20182 44 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 27286 313 0 0
sp_qd_handle_on_invoice_upd_sts 10931 10931 0 0.00 0 33540 283 0 0
sp_qd_handle_on_reserve_upd_sts 10461 10461 0 0.00 0 17667 52 0 0
sp_reserve_write_off 10462 10462 0 0.00 6 21343 191 0 0
sp_supplier_invoice 5753 5753 0 0.00 5 97331 3135 0 0
sp_supplier_order 5767 5767 0 0.00 5 46888 1151 0 0
srv_aggregate_perf_data 114 114 0 0.00 2707 260887 18012 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1582 175714 51050 0 0
srv_find_qd_qs_mism 1069495 1069495 0 0.00 0 26362 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 35 42259 1095 0 0
srv_make_money_saldo 800 800 0 0.00 1 7070 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 145066 339641 247685 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5217 5217 0 0.00 0 24187 150 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 6011 85 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 2032 10 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 1532 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 2912 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 837 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 472 472 0 0.00 0 5103 25 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 23928 1379 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 2653 7 0 0
t$perf-norm:sp_client_order 21723 21723 0 0.00 0 8485 18 0 0
t$perf-norm:sp_customer_reserve 1130 1130 0 0.00 0 1103 4 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 9264 12 0 0
t$perf-norm:sp_pay_to_supplier 6194 6194 0 0.00 0 7069 9 0 0
t$perf-norm:sp_reserve_write_off 10462 10462 0 0.00 0 8369 12 0 0
t$perf-norm:sp_supplier_invoice 5753 5753 0 0.00 0 16404 25 0 0
t$perf-norm:sp_supplier_order 5767 5767 0 0.00 0 6172 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 266 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 9504 39 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 2492 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10634 10426 208 1.95 0 8814 18 0 208
v_all_customers 38816 38816 0 0.00 0 25 0 0 0
v_all_suppliers 32122 32122 0 0.00 0 78 1 0 0
v_all_wares 43446 43446 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2195 9 0 0
v_cancel_client_order 3668 3668 0 0.00 0 2389 5 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 2353 6 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 204 3 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2956 9 0 0
v_cancel_supplier_invoice 960 944 16 1.66 0 1801 14 0 16
v_cancel_supplier_order 828 828 0 0.00 0 142 2 0 0
v_cancel_write_off 2064 2064 0 0.00 0 3226 14 0 0
v_max_id_clo_ord 5761 5761 0 0.00 0 2613 2 0 0
v_max_id_clo_res 1130 1130 0 0.00 0 3527 20 0 0
v_max_id_ord_sup 5745 5745 0 0.00 0 5928 5 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 1231 3 0 0
v_max_non_paid_realizn 4088 4088 0 0.00 0 582 3 0 0
v_min_id_clo_ord 5761 5761 0 0.00 0 6861 9 0 0
v_min_id_clo_res 1130 1130 0 0.00 0 2972 6 0 0
v_min_id_ord_sup 5745 5745 0 0.00 0 10403 12 0 0
v_min_non_paid_invoice 6193 6193 0 0.00 0 20168 42 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 1 18833 34 0 0
v_our_firm 10820 10820 0 0.00 0 18 1 0 0
v_reserve_write_off 20922 20922 0 0.00 0 9036 7 0 0
x_lock_depdocs_on_canc_invoice 11595 11595 0 0.00 0 14563 98 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 10068 330 0 0
x_make_qstorno_1000_1200 5767 5767 0 0.00 1 46880 1072 0 0
x_make_qstorno_1200_2000 5753 5753 0 0.00 2 97188 3003 0 0
x_make_qstorno_2100_3300 478575 478575 0 0.00 0 78835 136 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-03-2023 01:40:03 to 07-03-2023 01:40:08.

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
23:08:48 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:48 26705 8192000000 9914832832 10226171904 990720928 10.788 100 90 180 0 1020254400 6201664 23331808
23:11:23 14217 8192000000 9950454032 10273976320 1032871008 11.196 100 94 188 0 1065717328 8589760 24256560
23:12:39 16197 8192000000 9976918256 10308583424 1059523776 11.452 100 83 166 1 1090859312 10860672 20474864
23:13:58 8384 8192000000 10016203184 10361442304 1099523712 11.833 100 80 158 2 1130246496 11741152 18981632
23:15:09 6885 8192000000 10057904144 10418802688 1148722224 12.298 100 72 142 0 1176403648 11057104 16624320
23:16:18 2916 8192000000 10112484240 10481840128 1208239760 12.853 100 69 138 0 1235007248 11069328 15698160
23:17:23 5617 8192000000 10153416640 10530250752 1239673952 13.143 100 72 144 0 1268491840 13293072 15524816
23:18:31 1440 8192000000 10180888560 10565267456 1274811392 13.466 100 83 166 0 1304776656 12377952 17587312
23:19:33 1399 8192000000 10202519840 10589990912 1293833296 13.639 100 83 167 0 1324888368 13101744 17953328
23:20:37 11808 8192000000 10218888880 10607247360 1308546192 13.773 100 72 141 0 1337364576 13686640 15131744
23:21:49 5195 8192000000 10205756336 10615185408 1314670672 13.828 100 39 78 1 1331883456 8849904 8362880
23:22:58 12130 8192000000 10206992128 10628280320 1323598880 13.909 100 16 32 0 1332308032 5048864 3660288
23:24:14 12106 8192000000 10217216592 10636132352 1331518144 13.981 100 16 32 1 1340193856 5549328 3126384
23:25:29 58224 8192000000 10230690160 10649927680 1336395616 14.025 100 36 72 0 1349440016 4671712 8372688
23:27:28 3870 8192000000 10242362848 10654629888 1339848096 14.056 100 31 62 2 1352867504 5291616 7727792
23:28:33 11373 8192000000 10224454304 10641358848 1313760160 13.820 99 19 34 1 1328028016 10130224 4137632
23:29:44 22955 8192000000 10162135760 10565750784 1207752656 12.848 92 25 48 0 1219186832 5968208 5465968
23:31:10 10199 8192000000 10167961680 10580987904 1237952912 13.127 96 11 22 0 1245215568 5035344 2227312
23:32:21 6472 8192000000 10150324704 10555125760 1251891504 13.256 99 27 54 0 1262917408 4991408 6034496
23:33:29 10328 8192000000 10144735088 10547142656 1244931248 13.192 99 19 38 0 1254600544 4925776 4743520
23:34:42 59373 8192000000 10161785168 10570072064 1269582192 13.418 100 26 50 1 1281479392 6360688 5536512
23:36:49 9319 8192000000 10169429232 10584645632 1283956288 13.549 100 16 31 1 1292782192 5579776 3246128
23:37:59 13494 8192000000 10193817360 10607210496 1299413312 13.690 100 9 18 0 1305966928 4834992 1718624
23:39:19 23768 8192000000 10233641296 10642763776 1331605472 13.982 100 42 84 1 1347466944 6730752 9130720
23:40:54 157525 8192000000 10260995232 10693185536 1354915968 14.192 100 30 60 0 1377574464 14765440 7893056
23:44:34 29550 8192000000 10278450144 10698162176 1363299536 14.267 100 29 56 1 1387363152 17189136 6874480
23:46:09 5872 8192000000 10304107824 10718138368 1378618864 14.404 100 58 116 0 1411298128 19235552 13443712
23:47:21 5506 8192000000 10314402144 10736963584 1384858848 14.460 100 51 99 0 1424080448 27612576 11609024
23:48:32 1579 8192000000 10328152096 10739318784 1388879568 14.496 100 80 161 2 1431300960 24065424 18355968
23:49:37 11691 8192000000 10301959376 10721386496 1375029200 14.372 100 57 113 1 1408670384 20296832 13344352
23:50:54 1642 8192000000 10322751632 10737229824 1394822464 14.549 100 74 148 2 1431752464 20344400 16585600
23:51:58 3646 8192000000 10310193008 10717962240 1361259072 14.249 98 72 142 0 1405359952 26896352 17204528
23:53:08 8277 8192000000 10310727312 10724319232 1342653680 14.081 97 58 114 1 1388059712 31996880 13409152
23:54:23 15320 8192000000 10295183968 10717704192 1357167136 14.212 99 34 67 0 1389234400 23650912 8416352
23:55:53 1939 8192000000 10294139456 10708942848 1365162432 14.284 100 54 104 1 1403307344 25572960 12571952
23:57:01 11794 8192000000 10272513472 10692550656 1350112832 14.148 100 36 69 0 1383375936 23956384 9306720
23:58:20 17386 8192000000 10265929792 10688315392 1328653392 13.955 99 26 53 0 1361165232 25595136 6916704
23:59:42 28596 8192000000 10274792928 10700349440 1333273264 13.997 99 34 66 1 1378169744 35990800 8905680
00:01:12 13814 8192000000 10274977760 10705186816 1343803440 14.092 100 35 70 1 1387096224 34358832 8933952
00:02:30 43134 8192000000 10267032464 10704031744 1319652208 13.874 98 21 42 0 1349989824 25088912 5248704
00:04:24 30025 8192000000 10261343840 10696343552 1340683056 14.064 100 12 24 0 1364661488 21033424 2945008
00:06:01 25816 8192000000 10254795904 10695081984 1341373840 14.070 99 14 26 0 1356244688 11356112 3514736
00:07:35 28489 8192000000 10260471168 10687045632 1339328672 14.051 99 33 66 2 1357968976 11636208 7004096
00:09:13 106518 8192000000 10265719088 10702180352 1307450448 13.763 96 18 36 0 1322423008 10942896 4029664
00:12:01 44149 8192000000 10280562480 10703872000 1345620080 14.108 98 58 116 0 1366621888 8274608 12727200
00:13:49 77175 8192000000 10296211024 10743742464 1382910608 14.443 100 37 75 0 1406713520 14957568 8845344
00:16:14 42910 8192000000 10293933792 10743554048 1386636400 14.476 100 13 27 0 1408383792 18389120 3358272
00:17:57 73788 8192000000 10304126752 10760650752 1396658032 14.565 100 18 36 1 1422372240 20515024 5199184
00:20:11 62939 8192000000 10294412752 10755739648 1392533744 14.528 100 17 34 0 1412026688 15112784 4380160
00:22:18 27889 8192000000 10303558240 10759307264 1395763152 14.557 100 32 63 0 1417114656 14434928 6916576
00:23:47 69195 8192000000 10285196944 10747154432 1383899792 14.451 99 18 36 0 1399240048 10423216 4917040
00:26:09 62210 8192000000 10284243888 10755977216 1383048784 14.444 99 4 8 0 1398909632 14916688 944160
00:28:18 37261 8192000000 10308033616 10757431296 1395076624 14.551 100 25 50 0 1412130656 11553376 5500656
00:29:56 62651 8192000000 10299673440 10762002432 1403551008 14.627 100 11 22 0 1417915840 11790368 2574464
00:31:59 49070 8192000000 10317779168 10770034688 1388943744 14.496 99 28 56 0 1411748944 16580144 6225056
00:33:49 58121 8192000000 10304366528 10762760192 1353064672 14.175 97 18 36 1 1388761232 30957584 4738976
00:35:51 95613 8192000000 10283589008 10740256768 1330387728 13.971 96 10 20 0 1344329536 11950368 1991440
00:38:37 58944 8192000000 10265977168 10709438464 1306778320 13.757 95 25 51 0 1324232672 12038736 5415616
00:40:36 82578 8192000000 10268957072 10719485952 1329945488 13.967 97 11 22 1 1347618528 15541664 2131376
00:43:07 18151 8192000000 10268449200 10724671488 1370370784 14.330 100 23 46 0 1388100816 12775120 4954912
00:44:25 56436 8192000000 10267387168 10730696704 1359389040 14.232 99 5 10 0 1371689856 11406480 894336
00:46:37 52993 8192000000 10276173776 10730291200 1367355296 14.303 99 13 25 0 1378448816 8077088 3016432
00:48:30 72681 8192000000 10248496832 10702622720 1308916864 13.776 95 8 16 0 1322442496 11784672 1740960
00:50:57 55374 8192000000 10259005616 10707841024 1337346672 14.033 98 18 36 0 1350791120 9081424 4363024
00:53:02 34438 8192000000 10263775776 10700353536 1344518560 14.098 99 27 54 0 1361497824 10851120 6128144
00:54:37 44079 8192000000 10300916064 10743767040 1379736512 14.414 100 30 58 0 1398356464 11702528 6917424
00:56:25 114127 8192000000 10262001392 10706673664 1280789504 13.520 93 13 25 0 1297524016 13769568 2964944
00:59:22 49982 8192000000 10266043584 10707582976 1323468048 13.908 97 31 62 0 1340540848 10560896 6511904
01:01:16 21111 8192000000 10312156544 10747813888 1380945552 14.425 100 49 98 0 1403965488 12781888 10238048
01:02:40 134494 8192000000 10288141232 10744487936 1389361456 14.500 100 22 45 2 1406195312 11807728 5026128
01:06:04 55599 8192000000 10268828128 10725351424 1371050560 14.336 99 7 14 0 1382811424 10443696 1317168
01:08:00 40661 8192000000 10296608528 10756370432 1392931936 14.532 100 13 26 0 1408641136 13258224 2450976
01:10:04 82848 8192000000 10305462544 10770321408 1381671664 14.431 98 10 20 0 1393459936 9339584 2448688
01:12:51 57941 8192000000 10313929264 10768998400 1401011552 14.604 99 22 44 1 1414497360 8615984 4869824
01:14:50 24203 8192000000 10338887616 10789031936 1419415760 14.768 100 25 51 0 1432040048 7061840 5562448
01:16:14 31087 8192000000 10310688736 10774659072 1390996688 14.515 98 11 22 0 1408211216 14558752 2655776
01:17:46 57129 8192000000 10308727120 10758701056 1351101216 14.157 96 17 34 0 1362852384 8208768 3542400
01:19:46 60831 8192000000 10299803504 10764742656 1374085840 14.364 98 28 55 1 1397709072 17241472 6381760
01:21:49 128346 8192000000 10283587984 10739015680 1279713856 13.510 92 9 15 0 1292467280 11067616 1685808
01:25:10 175708 8192000000 10262665008 10712903680 1292974000 13.631 94 19 38 0 1308246096 10875840 4396256
01:29:24 70597 8192000000 10259800992 10724089856 1365330352 14.285 100 5 10 0 1376793248 10363328 1099568
01:31:36 46755 8192000000 10216559200 10661257216 1298008288 13.677 95 23 42 1 1309462960 6514000 4940672
01:33:23 70313 8192000000 10233293728 10687520768 1298011120 13.677 97 12 24 0 1309155584 8572496 2571968
01:35:34 93482 8192000000 10242641296 10701340672 1309826480 13.784 97 11 20 0 1320605344 8479248 2299616
01:38:12 39310 8192000000 9276006160 9554350080 433932656 5.030 32 4 8 0 439561520 4696976 931888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-03-2023 01:40:10 to 07-03-2023 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-03-2023 01:40:26 to 07-03-2023 01:40:28.

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          246956
MON$OLDEST_ACTIVE               246957
MON$OLDEST_SNAPSHOT             246957
MON$NEXT_TRANSACTION            246957
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                       2023040
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1109: 
:1110: mscs-test-161.budzdorov.ru    Tue Mar  7 01:39:00 2023
:1111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1112: 
:1113: 
:1114: mscs-test-161.budzdorov.ru    Tue Mar  7 01:39:02 2023
:1115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1116: 
--- end of diff output ---

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

07.03.23 01:40:29. 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
2023-03-07 01:40:31 No severe PSQL-related problems occured
07.03.23 01:40:33. End of report.