Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.2022 19: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 16285556 kB
MemAvailable 31234744 kB
Buffers 0 kB
Cached 15458856 kB
SwapCached 2496 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-06-25 19:38:22
DTS_END                         2022-06-25 19:38:22.7250
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/53626
TRN_ID                          239056
ATT_ID                          665
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: 9318.43 20686 1118212
customer order: creation 170.93 346 20511
customer order: refuse 14.56 339 1747
order to supplier: creation 45.13 1337 5415
order to supplier: removal 3.61 89241 433
invoice (draft): creation 46.03 3502 5523
invoice (draft): removal 52.82 808 6338
invoice accept: apply 42.01 17876 5041
invoice accept: cancel 47.73 747 5728
customer reserve: creation 3901.08 177 468129
customer reserve: removal 4698.81 52 563857
realization accept: apply 82.14 180 9857
realization accept: cancel 84.68 101 10162
payment from customer: creation 58.98 114 7077
payment from customer: removal 5.80 87 696
payment to supplier: creation 48.81 144 5857
payment to supplier: removal 3.48 69 417
service: total inventory turnovers 4.80 1200 576
service: total monetary turnovers 6.34 61 761
service: aggregate perf. data 0.69 28444 83
service: refresh index statistics 0.03 268890 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 19:40:53 to 25-06-2022 19:40:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5487
TEST_TIME 2 3454
TEST_TIME 3 4341
TEST_TIME 4 4213
TEST_TIME 5 4282
TEST_TIME 6 5148
TEST_TIME 7 4868
TEST_TIME 8 4951
TEST_TIME 9 4978
TEST_TIME 10 5164
TEST_TIME 11 5396
TEST_TIME 12 5710
TEST_TIME 13 5948
TEST_TIME 14 6115
TEST_TIME 15 6031
TEST_TIME 16 6954
TEST_TIME 17 7510
TEST_TIME 18 7853
TEST_TIME 19 7811
TEST_TIME 20 7840
TEST_TIME 21 8005
TEST_TIME 22 8427
TEST_TIME 23 8793
TEST_TIME 24 8825
TEST_TIME 25 9823
TEST_TIME 26 9917
TEST_TIME 27 10045
TEST_TIME 28 10679
TEST_TIME 29 10667
TEST_TIME 30 10675
TEST_TIME 31 10502
TEST_TIME 32 10755
TEST_TIME 33 10490
TEST_TIME 34 10512
TEST_TIME 35 10364
TEST_TIME 36 10323
TEST_TIME 37 10179
TEST_TIME 38 10024
TEST_TIME 39 10026
TEST_TIME 40 10135
TEST_TIME 41 10018
TEST_TIME 42 9880
TEST_TIME 43 9708
TEST_TIME 44 9641
TEST_TIME 45 9635
TEST_TIME 46 9667
TEST_TIME 47 9675
TEST_TIME 48 9611
TEST_TIME 49 9503
TEST_TIME 50 9435
TEST_TIME 51 9483
TEST_TIME 52 9437
TEST_TIME 53 9448
TEST_TIME 54 9399
TEST_TIME 55 9357
TEST_TIME 56 9413
TEST_TIME 57 9419
TEST_TIME 58 9471
TEST_TIME 59 9426
TEST_TIME 60 9519
TEST_TIME 61 9548
TEST_TIME 62 9527
TEST_TIME 63 9620
TEST_TIME 64 9669
TEST_TIME 65 9726
TEST_TIME 66 9805
TEST_TIME 67 9782
TEST_TIME 68 9737
TEST_TIME 69 9661
TEST_TIME 70 9595
TEST_TIME 71 9659
TEST_TIME 72 9567
TEST_TIME 73 9654
TEST_TIME 74 9588
TEST_TIME 75 9515
TEST_TIME 76 9504
TEST_TIME 77 9510
TEST_TIME 78 9502
TEST_TIME 79 9439
TEST_TIME 80 9405
TEST_TIME 81 9418
TEST_TIME 82 9462
TEST_TIME 83 9436
TEST_TIME 84 9437
TEST_TIME 85 9441
TEST_TIME 86 9432
TEST_TIME 87 9495
TEST_TIME 88 9477
TEST_TIME 89 9494
TEST_TIME 90 9479
TEST_TIME 91 9543
TEST_TIME 92 9570
TEST_TIME 93 9566
TEST_TIME 94 9508
TEST_TIME 95 9463
TEST_TIME 96 9433
TEST_TIME 97 9457
TEST_TIME 98 9415
TEST_TIME 99 9368
TEST_TIME 100 9348
TEST_TIME 101 9358
TEST_TIME 102 9291
TEST_TIME 103 9323
TEST_TIME 104 9308
TEST_TIME 105 9281
TEST_TIME 106 9294
TEST_TIME 107 9346
TEST_TIME 108 9364
TEST_TIME 109 9369
TEST_TIME 110 9373
TEST_TIME 111 9363
TEST_TIME 112 9398
TEST_TIME 113 9409
TEST_TIME 114 9421
TEST_TIME 115 9481
TEST_TIME 116 9485
TEST_TIME 117 9434
TEST_TIME 118 9402
TEST_TIME 119 9394
TEST_TIME 120 9320

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-06-2022 19:40:56 to 25-06-2022 19:41:00.

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 1550977 1550977 0 0.00 0 32790 6 0 0
doc_list_biud 1550976 1550976 0 0.00 0 28269 25 0 0
sp_add_invoice_to_stock 5121 5041 80 1.56 19 216437 17876 0 80
sp_cancel_adding_invoice 5728 5728 0 0.00 4 50690 747 0 0
sp_cancel_client_order 1747 1747 0 0.00 4 10239 339 0 0
sp_cancel_customer_reserve 563857 563857 0 0.00 1 23539 53 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 6228 87 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 7919 70 0 0
sp_cancel_supplier_invoice 6347 6338 9 0.14 2 20341 809 0 9
sp_cancel_supplier_order 433 433 0 0.00 7 1041811 89241 0 0
sp_cancel_write_off 10162 10162 0 0.00 1 8491 101 0 0
sp_client_order 20511 20511 0 0.00 3 15299 346 0 0
sp_customer_reserve 468129 468129 0 0.00 0 89228 177 0 0
sp_fill_shopping_cart 31448 31448 0 0.00 1 17495 40 0 0
sp_fill_shopping_cart_clo_res 468127 468127 0 0.00 0 13309 14 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 27948 311 0 0
sp_kill_cost_storno 17003 17003 0 0.00 0 7873 54 0 0
sp_kill_qstorno_ret_qs2qd 1144647 1144647 0 0.00 0 23505 29 0 0
sp_kill_qty_storno 603158 603158 0 0.00 0 28266 63 0 0
sp_lock_selected_doc 20678 20678 0 0.00 0 10005 6 0 0
sp_make_cost_storno 27828 27828 0 0.00 0 16918 61 0 0
sp_multiply_rows_for_pdistr 27828 27828 0 0.00 0 7524 28 0 0
sp_multiply_rows_for_qdistr 20511 20511 0 0.00 0 12361 163 0 0
sp_pay_from_customer 7077 7077 0 0.00 4 18206 114 0 0
sp_pay_to_supplier 5857 5857 0 0.00 5 15790 145 0 0
sp_payment_common 12934 12934 0 0.00 1 18202 29 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 9183 303 0 0
sp_qd_handle_on_invoice_upd_sts 10764 10764 0 0.00 0 28262 327 0 0
sp_qd_handle_on_reserve_upd_sts 9857 9857 0 0.00 0 14948 55 0 0
sp_reserve_write_off 9857 9857 0 0.00 6 15025 180 0 0
sp_supplier_invoice 5523 5523 0 0.00 7 95501 3503 0 0
sp_supplier_order 5415 5415 0 0.00 5 34755 1338 0 0
srv_aggregate_perf_data 83 83 0 0.00 3066 483287 28444 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1511 114620 49941 0 0
srv_find_qd_qs_mism 1070045 1070045 0 0.00 0 32788 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 30 62246 1200 0 0
srv_make_money_saldo 761 761 0 0.00 1 20448 62 0 0
srv_recalc_idx_stat 4 4 0 0.00 141483 530496 268891 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 0 13526 175 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 5449 128 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 8844 21 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 4657 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 6767 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 840 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1683 23 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 24158 1569 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 5288 18 0 0
t$perf-norm:sp_client_order 20511 20511 0 0.00 0 8461 18 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 3849 11 0 0
t$perf-norm:sp_pay_from_customer 7077 7077 0 0.00 0 6694 8 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 3818 9 0 0
t$perf-norm:sp_reserve_write_off 9857 9857 0 0.00 0 6694 10 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 14820 26 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 8794 17 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 208 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 545 14 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 7801 39 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1027 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10232 10072 160 1.56 0 5667 20 0 160
v_all_customers 37560 37560 0 0.00 0 19 1 0 0
v_all_suppliers 30360 30360 0 0.00 0 19 1 0 0
v_all_wares 41022 41022 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 4355 17 0 0
v_cancel_client_order 3494 3494 0 0.00 0 4280 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 5995 16 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 3575 14 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 447 4 0 0
v_cancel_supplier_invoice 816 798 18 2.20 0 3260 16 0 18
v_cancel_supplier_order 866 866 0 0.00 0 3062 11 0 0
v_cancel_write_off 1908 1908 0 0.00 0 4285 16 0 0
v_max_id_clo_ord 5415 5415 0 0.00 0 175 1 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 2198 14 0 0
v_max_id_ord_sup 5522 5522 0 0.00 0 6446 5 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 733 3 0 0
v_max_non_paid_realizn 3784 3784 0 0.00 0 3981 5 0 0
v_min_id_clo_ord 5415 5415 0 0.00 0 6854 9 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 3195 11 0 0
v_min_id_ord_sup 5522 5522 0 0.00 0 3398 5 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 8305 21 0 0
v_min_non_paid_realizn 7077 7077 0 0.00 0 18195 23 0 0
v_our_firm 10048 10048 0 0.00 0 16 1 0 0
v_reserve_write_off 19714 19714 0 0.00 0 6486 8 0 0
x_lock_depdocs_on_canc_invoice 11667 11667 0 0.00 0 16186 106 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 8538 279 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 2 34727 1255 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 4 95484 3358 0 0
x_make_qstorno_2100_3300 468129 468129 0 0.00 0 89227 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 19:41:01 to 25-06-2022 19:41:05.

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
17:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 19681 8192000000 9898194096 10206961664 982801072 10.711 100 91 182 1 1011765568 5894960 23069536
17:10:51 18982 8192000000 9952815744 10274549760 1035837360 11.225 100 89 178 0 1067135088 8827760 22469968
17:12:13 17536 8192000000 9971965232 10299924480 1054767952 11.406 100 84 168 0 1085979696 10123088 21088656
17:13:33 10200 8192000000 10013183792 10357325824 1099793584 11.836 100 81 162 0 1130393648 10376624 20223440
17:14:45 8315 8192000000 10070116352 10428071936 1154849056 12.355 100 76 152 0 1184212560 11973600 17389904
17:15:57 4200 8192000000 10107701568 10476240896 1197762640 12.756 100 75 150 0 1227553312 12222784 17567888
17:17:03 4374 8192000000 10155448080 10532864000 1239993904 13.146 100 81 163 0 1270063088 12463056 17606128
17:18:12 2148 8192000000 10194403104 10572324864 1276399248 13.480 100 93 186 0 1312185440 15162880 20623312
17:19:16 2039 8192000000 10213580176 10599391232 1297344160 13.671 100 94 187 1 1331238448 12779232 21115056
17:20:20 6923 8192000000 10196633008 10601058304 1306724816 13.756 100 45 91 3 1323688128 5813200 11150112
17:21:30 4210 8192000000 10201220928 10613194752 1312482448 13.809 100 32 64 1 1327460528 7910864 7067216
17:22:34 16448 8192000000 10200659968 10620817408 1319995760 13.877 100 14 28 1 1333215424 10266624 2953040
17:23:53 24314 8192000000 10214714000 10641436672 1328821712 13.957 100 19 38 0 1338932000 5590960 4519328
17:25:45 9256 8192000000 10235762096 10655494144 1333949472 14.003 100 34 68 2 1352958352 11150096 7858784
17:26:56 6655 8192000000 10222949568 10643677184 1321890144 13.894 99 23 44 2 1332212656 5573152 4749360
17:28:03 9663 8192000000 10208743728 10624446464 1304665584 13.738 99 10 19 1 1311650288 4936848 2047856
17:29:14 56903 8192000000 10138851328 10543284224 1172638368 12.521 91 38 77 0 1187174944 5275680 9260896
17:31:14 9684 8192000000 10133448832 10529460224 1212394176 12.891 97 18 36 0 1221924784 5223120 4307488
17:32:23 10063 8192000000 10132177776 10528382976 1227655088 13.032 99 35 71 0 1241189856 5089568 8445200
17:33:38 56212 8192000000 10156404544 10570104832 1259621376 13.327 99 30 60 0 1272764336 6083120 7059840
17:35:37 5151 8192000000 10186572848 10591449088 1287593584 13.582 100 49 98 1 1306876928 7194560 12088784
17:36:43 8957 8192000000 10183183824 10598383616 1294706064 13.647 100 19 38 1 1304725712 5098256 4921392
17:37:54 11049 8192000000 10199588784 10611359744 1304106032 13.733 100 10 20 0 1311541232 5151920 2283280
17:39:09 109811 8192000000 10246926528 10662805504 1335405392 14.016 100 36 73 2 1350228400 6515872 8307136
17:42:06 14515 8192000000 10274025456 10688671744 1361502368 14.251 100 46 92 0 1383730688 11821280 10407040
17:43:27 20117 8192000000 10287638256 10706264064 1368648688 14.315 100 47 92 0 1392040896 13305184 10087024
17:44:56 7274 8192000000 10300669616 10723389440 1386197280 14.472 100 42 84 0 1410675664 14079792 10398592
17:46:07 7446 8192000000 10313328112 10743083008 1399756864 14.593 100 43 81 2 1430144912 20902384 9485664
17:47:19 5377 8192000000 10332213792 10751393792 1403060640 14.622 100 66 132 1 1442918672 24476256 15381776
17:48:32 4900 8192000000 10340165280 10757181440 1399845584 14.594 99 69 137 1 1445111216 29393120 15872512
17:49:42 1812 8192000000 10356201184 10773090304 1411202400 14.695 100 74 145 0 1460860336 32044368 17613568
17:50:52 2208 8192000000 10354585648 10764484608 1389474544 14.501 98 82 163 4 1444524400 35470928 19578928
17:52:00 6335 8192000000 10337059824 10760511488 1392253456 14.526 100 59 118 0 1445431904 37592064 15586384
17:53:13 4826 8192000000 10339328144 10757234688 1386834544 14.478 100 60 118 1 1446525040 43181568 16508928
17:54:24 3020 8192000000 10328794032 10738753536 1367534592 14.305 99 71 141 1 1434838432 48590592 18713248
17:55:31 1511 8192000000 10353807328 10760155136 1385506640 14.466 100 90 180 1 1456829536 48223216 23099680
17:56:36 12490 8192000000 10313933776 10738184192 1375385680 14.375 100 31 63 2 1420301744 36685488 8230576
17:57:52 13205 8192000000 10326596304 10754588672 1385984384 14.470 100 43 86 3 1435226304 38351376 10890544
17:59:11 13890 8192000000 10324772320 10750091264 1373421024 14.358 100 38 76 0 1431378528 47393536 10563968
18:00:32 10258 8192000000 10295265280 10723299328 1334839920 14.011 97 31 62 0 1386180496 42622016 8718560
18:01:47 11475 8192000000 10311662320 10735464448 1341843216 14.074 98 35 70 3 1395256592 44151824 9261552
18:03:01 14454 8192000000 10317344688 10748456960 1369580032 14.323 100 35 70 0 1431144656 51365728 10198896
18:04:19 30550 8192000000 10291562720 10737528832 1364452576 14.277 100 19 38 1 1398959648 29084128 5422944
18:05:58 32264 8192000000 10267886560 10707439616 1340834128 14.065 99 29 58 0 1377857856 29418576 7605152
18:07:52 39897 8192000000 10282350512 10726559744 1363596848 14.270 100 19 36 2 1388851072 20817408 4436816
18:09:52 87214 8192000000 10269379200 10722877440 1305321760 13.744 95 33 66 1 1328249648 15626608 7301280
18:13:09 114606 8192000000 10269975760 10727358464 1355335856 14.195 99 19 38 0 1372787472 12926688 4524928
18:16:03 19272 8192000000 10272327072 10711695360 1362999744 14.264 100 43 82 3 1379951536 5946928 11004864
18:17:29 93065 8192000000 10284348816 10748116992 1371239232 14.338 99 19 38 0 1385622640 10572144 3811264
18:20:13 101395 8192000000 10309724176 10741260288 1377217824 14.392 99 53 108 2 1397787776 7829776 12740176
18:22:57 38361 8192000000 10310968560 10750681088 1393771376 14.540 100 28 56 0 1414338064 13979648 6587040
18:24:36 31022 8192000000 10310493632 10766508032 1398741264 14.584 100 36 73 0 1423459856 14411232 10307360
18:26:08 56377 8192000000 10296588912 10763423744 1400735408 14.602 100 7 14 0 1413383184 10940000 1707776
18:28:05 79260 8192000000 10302899232 10758680576 1395120400 14.552 100 25 50 0 1416888944 15262016 6506528
18:30:40 80043 8192000000 10298795888 10757791744 1396956544 14.568 100 15 30 1 1412443952 11914096 3573312
18:33:06 59897 8192000000 10308535744 10762350592 1402001408 14.613 100 43 86 3 1421470736 9438784 10030544
18:35:15 56257 8192000000 10279376240 10735177728 1373090624 14.355 98 11 22 1 1385844784 10070256 2683904
18:37:18 60258 8192000000 10289605920 10748596224 1361986544 14.255 98 29 58 1 1382017616 13724992 6306080
18:39:20 88814 8192000000 10290528160 10737987584 1323696512 13.910 95 14 28 0 1336134144 9456032 2981600
18:41:50 100643 8192000000 10267118288 10717122560 1252122928 13.258 91 4 8 0 1271815360 19040512 651920
18:44:31 114475 8192000000 10261746048 10706989056 1269735504 13.419 93 13 26 1 1284462560 11753184 2973872
18:47:32 99256 8192000000 10274873056 10721386496 1358122352 14.220 99 43 84 1 1381065024 13388656 9554016
18:50:11 45282 8192000000 10287704688 10753298432 1379199648 14.409 100 12 24 0 1406298336 24681152 2417536
18:51:58 50286 8192000000 10247612864 10708529152 1316186464 13.842 96 17 34 0 1330981840 9959136 4836240
18:53:49 68509 8192000000 10257732752 10715394048 1342748848 14.082 97 22 44 1 1360741488 12538672 5453968
18:56:07 61064 8192000000 10261876448 10726031360 1358910496 14.228 99 7 14 0 1373032464 12217184 1904784
18:58:10 97535 8192000000 10264114960 10721562624 1303077696 13.723 95 12 24 0 1320137856 14753520 2306640
19:00:48 56272 8192000000 10264269472 10712731648 1363355088 14.267 100 20 40 1 1377753248 10027312 4370848
19:02:44 51061 8192000000 10269060672 10716553216 1343508240 14.089 98 21 41 0 1356715216 8707088 4499888
19:04:39 76182 8192000000 10263168064 10721333248 1333618928 14.000 97 13 26 1 1348278752 11501744 3158080
19:06:56 67085 8192000000 10278249664 10723979264 1335122944 14.013 97 32 64 0 1355777296 13693600 6960752
19:09:08 79821 8192000000 10276164000 10717491200 1312828256 13.812 95 15 30 0 1327824432 11668848 3327328
19:11:28 64232 8192000000 10266355648 10722983936 1353207040 14.176 99 10 20 0 1368742736 13493808 2041888
19:14:21 54864 8192000000 10286750144 10741243904 1378993040 14.408 100 38 76 0 1403188912 15925168 8270704
19:16:55 68458 8192000000 10290331984 10750582784 1392816720 14.531 100 7 14 1 1405084848 10643664 1624464
19:19:04 79939 8192000000 10290424912 10752593920 1381261472 14.428 99 12 24 0 1401046896 16877760 2907664
19:21:24 52282 8192000000 10291735440 10751533056 1383362160 14.447 99 11 23 0 1393571136 7732832 2476144
19:23:17 48062 8192000000 10299342800 10754207744 1379455680 14.412 99 22 42 1 1397808384 13150928 5201776
19:25:11 101982 8192000000 10287517552 10752499712 1335272288 14.015 96 7 14 0 1349369632 12163360 1933984
19:27:54 45500 8192000000 10290583056 10738216960 1377702336 14.396 100 32 64 0 1396595104 11284160 7608608
19:29:40 68925 8192000000 10263357696 10716327936 1311571648 13.800 96 18 36 0 1327841456 12415856 3853952
19:31:49 57371 8192000000 10252562544 10691096576 1270875616 13.430 94 22 44 0 1293450944 17798384 4776944
19:33:50 34068 8192000000 10253892864 10690293760 1316331424 13.843 98 23 46 0 1330858848 9727392 4800032
19:35:25 95554 8192000000 10254207248 10705768448 1347349344 14.124 100 21 42 1 1362835744 10618944 4867456
19:38:13 24052 8192000000 9861366240 10261970944 987440576 10.757 73 15 28 1 1009118544 18102800 3575168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-06-2022 19:41:07 to 25-06-2022 19:41:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-06-2022 19:41:22 to 25-06-2022 19:41:24.

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          239761
MON$OLDEST_ACTIVE               240162
MON$OLDEST_SNAPSHOT             240162
MON$NEXT_TRANSACTION            240162
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                       2027040
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:419: 
:420: mscs-test-161.budzdorov.ru    Sat Jun 25 19:39:03 2022
:421:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:422: 
--- end of diff output ---

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

25.06.22 19:41:25. 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-06-25 19:41:28 No severe PSQL-related problems occured
25.06.22 19:41:29. End of report.