Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 01:40
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 16693772 kB
MemAvailable 31488876 kB
Buffers 0 kB
Cached 15056584 kB
SwapCached 2656 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.10
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-05-14 01:38:14
DTS_END                         2022-05-14 01:38:14.5800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47180
TRN_ID                          237885
ATT_ID                          693
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: 9166.57 19004 1099988
customer order: creation 168.78 378 20254
customer order: refuse 14.85 337 1782
order to supplier: creation 45.35 1311 5442
order to supplier: removal 3.66 91616 439
invoice (draft): creation 45.59 3205 5471
invoice (draft): removal 51.43 823 6172
invoice accept: apply 41.88 17749 5026
invoice accept: cancel 46.68 739 5602
customer reserve: creation 3855.74 177 462689
customer reserve: removal 4605.17 56 552620
realization accept: apply 84.28 169 10113
realization accept: cancel 75.88 112 9105
payment from customer: creation 58.55 118 7026
payment from customer: removal 5.77 118 692
payment to supplier: creation 47.82 124 5738
payment to supplier: removal 3.49 56 419
service: total inventory turnovers 4.38 1096 525
service: total monetary turnovers 6.33 22 760
service: aggregate perf. data 0.91 18828 109
service: refresh index statistics 0.03 243047 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-05-2022 01:40:32 to 14-05-2022 01:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3393
TEST_TIME 2 5074
TEST_TIME 3 4983
TEST_TIME 4 5110
TEST_TIME 5 4891
TEST_TIME 6 4684
TEST_TIME 7 5265
TEST_TIME 8 5137
TEST_TIME 9 5547
TEST_TIME 10 6001
TEST_TIME 11 6254
TEST_TIME 12 6477
TEST_TIME 13 6827
TEST_TIME 14 6962
TEST_TIME 15 7209
TEST_TIME 16 7399
TEST_TIME 17 7791
TEST_TIME 18 8517
TEST_TIME 19 9273
TEST_TIME 20 9428
TEST_TIME 21 9631
TEST_TIME 22 9898
TEST_TIME 23 9992
TEST_TIME 24 10482
TEST_TIME 25 10534
TEST_TIME 26 10668
TEST_TIME 27 10643
TEST_TIME 28 10359
TEST_TIME 29 10315
TEST_TIME 30 10259
TEST_TIME 31 10093
TEST_TIME 32 10362
TEST_TIME 33 10329
TEST_TIME 34 10144
TEST_TIME 35 10159
TEST_TIME 36 10050
TEST_TIME 37 9877
TEST_TIME 38 9704
TEST_TIME 39 9651
TEST_TIME 40 9808
TEST_TIME 41 9764
TEST_TIME 42 9689
TEST_TIME 43 9513
TEST_TIME 44 9592
TEST_TIME 45 9556
TEST_TIME 46 9547
TEST_TIME 47 9407
TEST_TIME 48 9371
TEST_TIME 49 9297
TEST_TIME 50 9309
TEST_TIME 51 9391
TEST_TIME 52 9234
TEST_TIME 53 9157
TEST_TIME 54 9090
TEST_TIME 55 9211
TEST_TIME 56 9269
TEST_TIME 57 9220
TEST_TIME 58 9281
TEST_TIME 59 9307
TEST_TIME 60 9287
TEST_TIME 61 9375
TEST_TIME 62 9351
TEST_TIME 63 9439
TEST_TIME 64 9460
TEST_TIME 65 9489
TEST_TIME 66 9462
TEST_TIME 67 9481
TEST_TIME 68 9454
TEST_TIME 69 9470
TEST_TIME 70 9503
TEST_TIME 71 9532
TEST_TIME 72 9406
TEST_TIME 73 9407
TEST_TIME 74 9329
TEST_TIME 75 9296
TEST_TIME 76 9312
TEST_TIME 77 9324
TEST_TIME 78 9276
TEST_TIME 79 9222
TEST_TIME 80 9197
TEST_TIME 81 9132
TEST_TIME 82 9190
TEST_TIME 83 9236
TEST_TIME 84 9287
TEST_TIME 85 9260
TEST_TIME 86 9324
TEST_TIME 87 9289
TEST_TIME 88 9330
TEST_TIME 89 9294
TEST_TIME 90 9376
TEST_TIME 91 9369
TEST_TIME 92 9383
TEST_TIME 93 9380
TEST_TIME 94 9417
TEST_TIME 95 9403
TEST_TIME 96 9328
TEST_TIME 97 9314
TEST_TIME 98 9307
TEST_TIME 99 9275
TEST_TIME 100 9268
TEST_TIME 101 9233
TEST_TIME 102 9178
TEST_TIME 103 9186
TEST_TIME 104 9115
TEST_TIME 105 9162
TEST_TIME 106 9132
TEST_TIME 107 9122
TEST_TIME 108 9140
TEST_TIME 109 9170
TEST_TIME 110 9156
TEST_TIME 111 9154
TEST_TIME 112 9185
TEST_TIME 113 9184
TEST_TIME 114 9190
TEST_TIME 115 9216
TEST_TIME 116 9247
TEST_TIME 117 9250
TEST_TIME 118 9268
TEST_TIME 119 9246
TEST_TIME 120 9169

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 01:40:36 to 14-05-2022 01:40:39.

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 1528448 1528448 0 0.00 0 30261 6 0 0
doc_list_biud 1528448 1528448 0 0.00 0 59305 26 0 0
sp_add_invoice_to_stock 5132 5026 106 2.06 29 156850 17750 0 106
sp_cancel_adding_invoice 5602 5602 0 0.00 3 76198 739 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 16607 338 0 0
sp_cancel_customer_reserve 552620 552620 0 0.00 1 59307 57 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 9585 119 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 1281 57 0 0
sp_cancel_supplier_invoice 6186 6172 14 0.22 2 30542 824 0 14
sp_cancel_supplier_order 439 439 0 0.00 8 1003015 91616 0 0
sp_cancel_write_off 9105 9105 0 0.00 1 19121 112 0 0
sp_client_order 20254 20254 0 0.00 3 59131 378 0 0
sp_customer_reserve 462689 462689 0 0.00 0 26779 177 0 0
sp_fill_shopping_cart 31148 31148 0 0.00 1 14871 38 0 0
sp_fill_shopping_cart_clo_res 462667 462667 0 0.00 0 14390 13 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 28077 305 0 0
sp_kill_cost_storno 15818 15818 0 0.00 0 7296 56 0 0
sp_kill_qstorno_ret_qs2qd 1120956 1120956 0 0.00 0 59301 31 0 0
sp_kill_qty_storno 590836 590836 0 0.00 0 59305 67 0 0
sp_lock_selected_doc 20824 20824 0 0.00 0 7701 4 0 0
sp_make_cost_storno 27880 27880 0 0.00 0 15773 52 0 0
sp_multiply_rows_for_pdistr 27880 27880 0 0.00 0 7262 26 0 0
sp_multiply_rows_for_qdistr 20254 20254 0 0.00 0 59124 174 0 0
sp_pay_from_customer 7026 7026 0 0.00 4 13519 118 0 0
sp_pay_to_supplier 5738 5738 0 0.00 4 14558 125 0 0
sp_payment_common 12764 12764 0 0.00 1 14538 30 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 15046 298 0 0
sp_qd_handle_on_invoice_upd_sts 10605 10605 0 0.00 0 20124 302 0 0
sp_qd_handle_on_reserve_upd_sts 10113 10113 0 0.00 0 11139 54 0 0
sp_reserve_write_off 10113 10113 0 0.00 6 15784 169 0 0
sp_supplier_invoice 5471 5471 0 0.00 5 69718 3206 0 0
sp_supplier_order 5442 5442 0 0.00 5 34250 1312 0 0
srv_aggregate_perf_data 109 109 0 0.00 2970 147767 18828 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2134 134945 49685 0 0
srv_find_qd_qs_mism 1052725 1052725 0 0.00 0 30261 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 42 129021 1096 0 0
srv_make_money_saldo 760 760 0 0.00 0 1321 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 164719 421086 243048 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5026 5026 0 0.00 0 12440 131 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 4393 111 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 921 7 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 650 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 222 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 10021 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1293 13 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 21674 1472 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 4784 10 0 0
t$perf-norm:sp_client_order 20254 20254 0 0.00 0 17000 14 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3030 10 0 0
t$perf-norm:sp_pay_from_customer 7026 7026 0 0.00 0 7805 11 0 0
t$perf-norm:sp_pay_to_supplier 5738 5738 0 0.00 0 8991 12 0 0
t$perf-norm:sp_reserve_write_off 10113 10113 0 0.00 0 9055 10 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 9935 21 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 9967 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 59 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 1368 15 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 1062 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10218 10006 212 2.07 0 5354 17 0 212
v_all_customers 36996 36996 0 0.00 0 22 0 0 0
v_all_suppliers 30052 30052 0 0.00 0 13 1 0 0
v_all_wares 40508 40508 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 62 4 0 0
v_cancel_client_order 3564 3564 0 0.00 0 6237 6 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 7331 28 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 2194 6 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 362 4 0 0
v_cancel_supplier_invoice 778 750 28 3.59 0 3838 21 0 28
v_cancel_supplier_order 878 878 0 0.00 0 5099 8 0 0
v_cancel_write_off 1926 1926 0 0.00 0 2890 13 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 213 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 2941 15 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 4994 3 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 3782 5 0 0
v_max_non_paid_realizn 3725 3725 0 0.00 0 3560 6 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 4180 8 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 4467 10 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 4961 5 0 0
v_min_non_paid_invoice 5738 5738 0 0.00 0 14534 21 0 0
v_min_non_paid_realizn 7026 7026 0 0.00 0 13498 22 0 0
v_our_firm 10114 10114 0 0.00 0 86 1 0 0
v_reserve_write_off 20226 20226 0 0.00 0 7156 7 0 0
x_lock_depdocs_on_canc_invoice 11399 11399 0 0.00 0 21667 120 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 6612 288 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 1 34244 1238 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 2 66920 3064 0 0
x_make_qstorno_2100_3300 462689 462689 0 0.00 0 22574 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 01:40:40 to 14-05-2022 01:40:44.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:17 23971 8192000000 9915386480 10226831360 996124656 10.841 100 92 184 0 1026996752 6202672 24669424
23:10:44 14733 8192000000 9951788544 10273116160 1029553600 11.164 100 89 178 0 1061045872 8577424 22914848
23:12:10 21633 8192000000 9969809584 10300092416 1053728992 11.396 100 86 172 0 1085610864 10164208 21717664
23:13:41 9431 8192000000 10020814384 10366132224 1109355376 11.926 100 75 150 0 1137611360 10420944 17835040
23:14:56 8114 8192000000 10087092592 10447278080 1174528032 12.539 100 87 174 1 1206826864 12310608 19988224
23:16:08 3747 8192000000 10134444720 10503208960 1218614656 12.949 100 85 170 0 1252308736 14444096 19249984
23:17:14 3668 8192000000 10162023984 10536660992 1248220048 13.222 100 76 152 2 1276428640 11510032 16698560
23:18:20 1908 8192000000 10207365568 10590998528 1288220928 13.588 100 89 178 1 1319855120 11668032 19966160
23:19:23 24061 8192000000 10185267264 10596380672 1297058256 13.668 100 41 82 0 1313468880 6381984 10028640
23:20:49 7409 8192000000 10194934512 10606694400 1306493168 13.754 100 27 54 2 1319727888 7535456 5699264
23:22:01 8439 8192000000 10213804848 10629132288 1323064832 13.904 100 19 39 0 1333189824 6023760 4101232
23:23:10 18296 8192000000 10217888496 10639699968 1331893488 13.984 100 35 70 0 1346369296 6140752 8335056
23:24:33 11157 8192000000 10228199856 10645585920 1332516384 13.990 100 20 40 1 1342181728 5325136 4340208
23:25:45 7809 8192000000 10234600720 10652209152 1339402240 14.052 100 26 52 0 1352147888 6574480 6171168
23:26:53 5272 8192000000 10232584944 10648346624 1336264048 14.024 100 27 54 2 1348060928 5632720 6164160
23:28:02 57987 8192000000 10187509760 10598858752 1245550880 13.197 94 28 56 0 1257671008 5590784 6529344
23:30:05 7837 8192000000 10164137664 10561814528 1216023648 12.925 95 14 29 0 1223921520 4972224 2925648
23:31:14 7723 8192000000 10142601568 10538188800 1214127472 12.907 97 18 36 0 1224022912 5220672 4674768
23:32:24 9095 8192000000 10142525008 10533629952 1240559312 13.151 99 29 58 0 1253570896 6573360 6438224
23:33:34 17165 8192000000 10140339888 10550345728 1259767648 13.328 100 24 46 0 1274790480 9188368 5834464
23:34:52 7552 8192000000 10173392976 10582761472 1284754832 13.556 100 33 66 1 1299768720 8124208 6889680
23:36:03 6960 8192000000 10183993920 10599882752 1298284960 13.680 100 13 26 0 1306854752 5199744 3370048
23:37:10 10785 8192000000 10198594928 10610343936 1304375024 13.735 100 13 26 1 1312737680 5323264 3039392
23:38:21 51135 8192000000 10227647440 10635177984 1321696656 13.892 100 56 112 0 1342459392 8056112 12706624
23:40:21 2414 8192000000 10267665344 10671640576 1348953024 14.138 100 63 126 0 1373222080 10233632 14035424
23:41:26 29992 8192000000 10262463472 10680451072 1350485168 14.152 99 45 87 0 1371493856 10819328 10189360
23:43:00 20667 8192000000 10274218304 10694893568 1370104848 14.328 100 46 92 2 1393451856 12071776 11275232
23:44:25 18437 8192000000 10299102560 10721411072 1388495568 14.492 100 54 108 0 1415388256 14063728 12828960
23:45:51 3534 8192000000 10324705520 10745929728 1406815856 14.656 100 54 109 2 1437080880 16840448 13424576
23:47:01 11084 8192000000 10336307664 10761510912 1411061776 14.693 100 56 112 1 1448282576 23210224 14010576
23:48:16 5828 8192000000 10331780624 10763198464 1412803792 14.709 100 44 87 3 1446831936 22497632 11530512
23:49:25 9606 8192000000 10346958272 10768494592 1410709248 14.690 100 67 132 2 1451776752 24970848 16096656
23:50:43 3725 8192000000 10355154752 10776297472 1415623488 14.734 100 69 136 0 1460841936 27668048 17550400
23:51:54 4679 8192000000 10355702256 10778255360 1417089776 14.747 100 70 135 1 1461024000 25804256 18129968
23:53:02 3248 8192000000 10346159472 10759266304 1399504848 14.591 100 79 158 1 1451870048 31989952 20375248
23:54:18 2134 8192000000 10362394016 10773544960 1407447536 14.661 100 84 168 2 1462357712 33814704 21095472
23:55:24 5762 8192000000 10341465872 10765770752 1399166128 14.588 100 54 107 0 1451253120 37504768 14582224
23:56:36 12739 8192000000 10324690544 10754666496 1387710464 14.485 100 46 92 2 1441141584 40232832 13198288
23:57:55 9230 8192000000 10302716208 10731806720 1360904400 14.245 99 33 65 1 1394386144 25263728 8218016
23:59:07 9486 8192000000 10265083376 10696073216 1335113792 14.013 99 30 60 1 1366267200 23605648 7547760
00:00:19 22514 8192000000 10264104544 10691039232 1321921504 13.894 98 26 51 1 1354474000 26120544 6431952
00:01:43 32660 8192000000 10295910384 10730213376 1339948656 14.057 98 25 50 1 1381850480 34810752 7091072
00:03:19 36761 8192000000 10277600912 10704011264 1336606640 14.027 99 33 65 0 1363847616 19291152 7949824
00:05:01 29823 8192000000 10275283184 10703446016 1350521824 14.152 100 43 85 0 1384492352 23875184 10095344
00:06:36 47744 8192000000 10277701120 10715971584 1343904320 14.093 99 21 43 1 1373925952 24767280 5254352
00:09:07 60617 8192000000 10248649472 10699960320 1300349904 13.698 96 17 33 0 1329478592 25046976 4081712
00:11:12 10526 8192000000 10263870000 10682052608 1340758208 14.064 99 64 125 1 1368175024 11470288 15946528
00:12:32 82073 8192000000 10279689440 10718236672 1373488000 14.358 100 44 88 1 1393616944 10315216 9813728
00:15:00 60193 8192000000 10281507264 10719813632 1349246512 14.141 98 28 56 0 1368931312 13061888 6622912
00:17:00 44377 8192000000 10295890208 10744111104 1381227200 14.428 100 14 28 1 1405775200 20710224 3837776
00:19:00 29514 8192000000 10291126080 10762977280 1375730272 14.378 100 7 13 0 1402497872 25087728 1679872
00:20:32 88122 8192000000 10289408032 10731905024 1356465696 14.206 98 35 71 0 1372442288 8183808 7792784
00:23:17 103588 8192000000 10275832048 10733281280 1367258256 14.302 99 26 52 1 1384865680 11448816 6158608
00:26:01 25005 8192000000 10291088992 10741092352 1391480672 14.519 100 32 61 0 1409276608 10786848 7009088
00:27:38 50131 8192000000 10297718272 10757558272 1395771136 14.557 100 12 24 0 1411978928 13393200 2814592
00:29:37 94129 8192000000 10311789776 10778120192 1399583072 14.591 100 10 20 1 1423255616 21605040 2067504
00:32:32 75451 8192000000 10312506352 10775146496 1393191856 14.534 99 17 34 2 1416679152 18668352 4818944
00:35:02 57457 8192000000 10309700336 10764738560 1392001056 14.524 99 11 22 0 1404966128 10446800 2518272
00:37:01 74250 8192000000 10328634560 10774491136 1399749616 14.593 99 26 53 1 1415303744 9795152 5758976
00:39:19 97082 8192000000 10292952288 10739474432 1325898752 13.930 94 21 41 0 1339096768 8978448 4219568
00:41:56 120506 8192000000 10294994080 10737676288 1277582224 13.491 91 14 27 1 1290319552 9628672 3108656
00:45:03 37500 8192000000 10275807504 10701668352 1292631728 13.628 95 38 76 1 1312475296 11564160 8279408
00:47:21 21846 8192000000 10273952624 10702282752 1338864272 14.047 99 44 88 0 1360968880 12735328 9369280
00:48:47 80995 8192000000 10252198736 10703056896 1320307536 13.879 98 9 18 1 1337783232 15641600 1834096
00:51:08 57907 8192000000 10251256080 10698301440 1318908160 13.867 97 22 44 1 1331598720 8161664 4528896
00:53:09 8708 8192000000 10277786896 10712981504 1364319792 14.276 100 36 72 1 1380610000 8650784 7639424
00:54:19 81959 8192000000 10248235152 10707787776 1336491504 14.026 98 19 38 0 1353006416 11962640 4552272
00:57:18 47048 8192000000 10259948480 10704449536 1334294672 14.006 98 21 42 1 1352776400 13815600 4666128
00:59:06 79814 8192000000 10266515952 10726555648 1360990176 14.246 100 13 26 1 1382900880 18495776 3414928
01:01:30 69445 8192000000 10249694992 10702577664 1302314624 13.716 96 18 36 1 1324791648 18751808 3725216
01:03:52 57419 8192000000 10233516912 10684440576 1289819712 13.603 96 36 72 0 1308567056 10458736 8288608
01:06:19 48801 8192000000 10262313552 10713415680 1356719536 14.208 100 18 36 2 1376112192 15002304 4390352
01:08:14 64740 8192000000 10284822992 10727010304 1371233440 14.338 100 15 29 0 1386439120 12662032 2543648
01:10:22 127546 8192000000 10303740016 10752954368 1362863872 14.263 98 18 36 1 1378518192 11748512 3905808
01:13:30 59266 8192000000 10299839792 10748452864 1369894480 14.326 98 24 48 2 1383781376 8450272 5436624
01:15:31 89032 8192000000 10296157696 10768343040 1397457024 14.572 100 9 17 1 1411410288 11250672 2702592
01:18:00 58138 8192000000 10290902816 10762510336 1396563920 14.564 100 6 12 0 1410163600 12557264 1042416
01:19:58 89496 8192000000 10293724432 10761666560 1385778528 14.468 99 10 20 0 1397486384 9368320 2339536
01:22:40 62382 8192000000 10292393888 10765918208 1387580560 14.484 99 30 60 0 1405222800 10702528 6939712
01:25:02 50152 8192000000 10291847824 10754166784 1378664496 14.405 99 16 32 1 1396033984 13820240 3549248
01:26:58 79708 8192000000 10287576992 10748002304 1332037440 13.986 95 7 14 0 1345372736 11742464 1592832
01:29:33 104759 8192000000 10278310528 10728607744 1301003344 13.704 94 21 42 0 1316575520 11464432 4107744
01:32:27 46226 8192000000 10300690752 10747932672 1384897056 14.460 100 17 34 0 1399264768 10529504 3838208
01:34:13 134945 8192000000 10278804560 10725167104 1235942080 13.109 91 17 34 2 1250874352 11822368 3109904
01:37:28 76355 8192000000 9055131248 9279823872 225572144 2.679 17 2 4 0 230239200 4402928 264128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-05-2022 01:40:46 to 14-05-2022 01:41:01.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-05-2022 01:41:02 to 14-05-2022 01:41:04.

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          238396
MON$OLDEST_ACTIVE               238397
MON$OLDEST_SNAPSHOT             238397
MON$NEXT_TRANSACTION            238397
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                       2014016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:281: 
:282: mscs-test-161.budzdorov.ru    Sat May 14 01:39:09 2022
:283:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:284: 
--- end of diff output ---

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

14.05.22 01:41:05. 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-05-14 01:41:07 No severe PSQL-related problems occured
14.05.22 01:41:09. End of report.