Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2022 22: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 15607612 kB
MemAvailable 30489952 kB
Buffers 0 kB
Cached 16084780 kB
SwapCached 2172 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-12 22:38:29
DTS_END                         2022-10-12 22:38:29.4860
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/42392
TRN_ID                          243964
ATT_ID                          688
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: 9399.53 19456 1127943
customer order: creation 177.58 404 21309
customer order: refuse 14.98 342 1798
order to supplier: creation 46.94 1320 5633
order to supplier: removal 3.62 86416 434
invoice (draft): creation 47.93 3440 5751
invoice (draft): removal 52.42 780 6290
invoice accept: apply 44.31 18581 5317
invoice accept: cancel 47.35 807 5682
customer reserve: creation 4020.23 188 482427
customer reserve: removal 4654.33 52 558520
realization accept: apply 84.93 202 10191
realization accept: cancel 71.94 121 8633
payment from customer: creation 60.73 186 7287
payment from customer: removal 5.85 88 702
payment to supplier: creation 50.36 155 6043
payment to supplier: removal 3.43 102 411
service: total inventory turnovers 4.97 995 596
service: total monetary turnovers 6.71 28 805
service: aggregate perf. data 0.92 17942 110
service: refresh index statistics 0.03 256962 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2022 22:39:28 to 12-10-2022 22:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2827
TEST_TIME 2 2117
TEST_TIME 3 4210
TEST_TIME 4 4282
TEST_TIME 5 3849
TEST_TIME 6 4879
TEST_TIME 7 5339
TEST_TIME 8 5453
TEST_TIME 9 5505
TEST_TIME 10 5585
TEST_TIME 11 6262
TEST_TIME 12 6241
TEST_TIME 13 6155
TEST_TIME 14 6292
TEST_TIME 15 6803
TEST_TIME 16 7104
TEST_TIME 17 7563
TEST_TIME 18 7885
TEST_TIME 19 8035
TEST_TIME 20 8817
TEST_TIME 21 8585
TEST_TIME 22 9252
TEST_TIME 23 9781
TEST_TIME 24 9986
TEST_TIME 25 10057
TEST_TIME 26 10337
TEST_TIME 27 10357
TEST_TIME 28 10491
TEST_TIME 29 10389
TEST_TIME 30 10326
TEST_TIME 31 10297
TEST_TIME 32 10301
TEST_TIME 33 10018
TEST_TIME 34 10136
TEST_TIME 35 9932
TEST_TIME 36 9818
TEST_TIME 37 9932
TEST_TIME 38 9830
TEST_TIME 39 9914
TEST_TIME 40 9815
TEST_TIME 41 9712
TEST_TIME 42 9613
TEST_TIME 43 9529
TEST_TIME 44 9542
TEST_TIME 45 9419
TEST_TIME 46 9425
TEST_TIME 47 9414
TEST_TIME 48 9536
TEST_TIME 49 9551
TEST_TIME 50 9602
TEST_TIME 51 9596
TEST_TIME 52 9581
TEST_TIME 53 9578
TEST_TIME 54 9624
TEST_TIME 55 9766
TEST_TIME 56 9716
TEST_TIME 57 9781
TEST_TIME 58 9808
TEST_TIME 59 9721
TEST_TIME 60 9597
TEST_TIME 61 9540
TEST_TIME 62 9544
TEST_TIME 63 9518
TEST_TIME 64 9454
TEST_TIME 65 9438
TEST_TIME 66 9373
TEST_TIME 67 9360
TEST_TIME 68 9465
TEST_TIME 69 9448
TEST_TIME 70 9405
TEST_TIME 71 9517
TEST_TIME 72 9504
TEST_TIME 73 9600
TEST_TIME 74 9671
TEST_TIME 75 9639
TEST_TIME 76 9587
TEST_TIME 77 9766
TEST_TIME 78 9772
TEST_TIME 79 9678
TEST_TIME 80 9629
TEST_TIME 81 9613
TEST_TIME 82 9554
TEST_TIME 83 9520
TEST_TIME 84 9479
TEST_TIME 85 9448
TEST_TIME 86 9438
TEST_TIME 87 9450
TEST_TIME 88 9411
TEST_TIME 89 9382
TEST_TIME 90 9447
TEST_TIME 91 9412
TEST_TIME 92 9456
TEST_TIME 93 9500
TEST_TIME 94 9538
TEST_TIME 95 9604
TEST_TIME 96 9594
TEST_TIME 97 9611
TEST_TIME 98 9585
TEST_TIME 99 9592
TEST_TIME 100 9634
TEST_TIME 101 9593
TEST_TIME 102 9530
TEST_TIME 103 9547
TEST_TIME 104 9516
TEST_TIME 105 9517
TEST_TIME 106 9471
TEST_TIME 107 9465
TEST_TIME 108 9431
TEST_TIME 109 9397
TEST_TIME 110 9373
TEST_TIME 111 9403
TEST_TIME 112 9389
TEST_TIME 113 9379
TEST_TIME 114 9415
TEST_TIME 115 9437
TEST_TIME 116 9465
TEST_TIME 117 9470
TEST_TIME 118 9453
TEST_TIME 119 9453
TEST_TIME 120 9404

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2022 22:39:31 to 12-10-2022 22:39:35.

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 1575030 1575030 0 0.00 0 30383 7 0 0
doc_list_biud 1575030 1575030 0 0.00 0 34751 24 0 0
sp_add_invoice_to_stock 5420 5317 103 1.90 35 198167 18582 0 103
sp_cancel_adding_invoice 5682 5682 0 0.00 4 65390 808 0 0
sp_cancel_client_order 1798 1798 0 0.00 4 27108 342 0 0
sp_cancel_customer_reserve 558520 558520 0 0.00 0 34753 53 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 8468 89 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4974 103 0 0
sp_cancel_supplier_invoice 6302 6290 12 0.19 1 15481 780 0 12
sp_cancel_supplier_order 434 434 0 0.00 7 918584 86416 0 0
sp_cancel_write_off 8633 8633 0 0.00 1 17540 121 0 0
sp_client_order 21309 21309 0 0.00 3 30379 404 0 0
sp_customer_reserve 482427 482427 0 0.00 0 74021 188 0 0
sp_fill_shopping_cart 32693 32693 0 0.00 1 19373 40 0 0
sp_fill_shopping_cart_clo_res 482422 482422 0 0.00 0 30133 17 0 0
sp_get_clo_for_invoice 5312 5312 0 0.00 0 54073 303 0 0
sp_kill_cost_storno 15428 15428 0 0.00 0 17521 57 0 0
sp_kill_qstorno_ret_qs2qd 1132397 1132397 0 0.00 0 34194 29 0 0
sp_kill_qty_storno 596860 596860 0 0.00 0 34751 63 0 0
sp_lock_selected_doc 21392 21392 0 0.00 0 8200 6 0 0
sp_make_cost_storno 28833 28833 0 0.00 0 24922 65 0 0
sp_multiply_rows_for_pdistr 28833 28833 0 0.00 0 10774 35 0 0
sp_multiply_rows_for_qdistr 21309 21309 0 0.00 0 12855 175 0 0
sp_pay_from_customer 7287 7287 0 0.00 4 24238 186 0 0
sp_pay_to_supplier 6043 6043 0 0.00 4 17793 156 0 0
sp_payment_common 13330 13330 0 0.00 1 16368 45 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 11377 302 0 0
sp_qd_handle_on_invoice_upd_sts 10994 10994 0 0.00 0 25730 305 0 0
sp_qd_handle_on_reserve_upd_sts 10191 10191 0 0.00 0 18981 65 0 0
sp_reserve_write_off 10191 10191 0 0.00 6 19036 202 0 0
sp_supplier_invoice 5751 5751 0 0.00 6 112218 3441 0 0
sp_supplier_order 5633 5633 0 0.00 4 66898 1320 0 0
srv_aggregate_perf_data 110 110 0 0.00 2648 241988 17943 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1533 135004 41234 0 0
srv_find_qd_qs_mism 1078724 1078724 0 0.00 0 18461 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 30 47978 996 0 0
srv_make_money_saldo 805 805 0 0.00 1 2361 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 166162 355060 256962 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5317 5317 0 0.00 0 19314 152 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 12005 147 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 3606 16 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 7193 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 595 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 7678 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 1490 17 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 25985 1354 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 4177 9 0 0
t$perf-norm:sp_client_order 21309 21309 0 0.00 0 9234 14 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 4831 11 0 0
t$perf-norm:sp_pay_from_customer 7287 7287 0 0.00 0 3627 6 0 0
t$perf-norm:sp_pay_to_supplier 6043 6043 0 0.00 0 7046 10 0 0
t$perf-norm:sp_reserve_write_off 10191 10191 0 0.00 0 7787 11 0 0
t$perf-norm:sp_supplier_invoice 5751 5751 0 0.00 0 5937 17 0 0
t$perf-norm:sp_supplier_order 5633 5633 0 0.00 0 8176 13 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 412 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 58 3 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 1447 21 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 977 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10828 10622 206 1.90 0 6803 17 0 206
v_all_customers 39042 39042 0 0.00 0 32 1 0 0
v_all_suppliers 31296 31296 0 0.00 0 265 1 0 0
v_all_wares 42618 42618 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 3018 15 0 0
v_cancel_client_order 3596 3596 0 0.00 0 26839 13 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1730 6 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 3968 9 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 2866 11 0 0
v_cancel_supplier_invoice 784 760 24 3.06 0 3810 17 0 24
v_cancel_supplier_order 868 868 0 0.00 0 28843 60 0 0
v_cancel_write_off 2044 2044 0 0.00 0 4636 24 0 0
v_max_id_clo_ord 5633 5633 0 0.00 0 5044 3 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2392 16 0 0
v_max_id_ord_sup 5751 5751 0 0.00 0 7113 6 0 0
v_max_non_paid_invoice 1779 1779 0 0.00 0 528 3 0 0
v_max_non_paid_realizn 3825 3825 0 0.00 0 3228 5 0 0
v_min_id_clo_ord 5633 5633 0 0.00 0 2225 6 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 7747 17 0 0
v_min_id_ord_sup 5751 5751 0 0.00 0 5621 8 0 0
v_min_non_paid_invoice 6043 6043 0 0.00 0 16296 27 0 0
v_min_non_paid_realizn 7287 7287 0 0.00 0 11125 44 0 0
v_our_firm 10500 10500 0 0.00 0 15 1 0 0
v_reserve_write_off 20382 20382 0 0.00 0 16434 13 0 0
x_lock_depdocs_on_canc_invoice 11592 11592 0 0.00 0 17824 108 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 21547 372 0 0
x_make_qstorno_1000_1200 5633 5633 0 0.00 1 66833 1246 0 0
x_make_qstorno_1200_2000 5751 5751 0 0.00 3 105605 3293 0 0
x_make_qstorno_2100_3300 482427 482427 0 0.00 0 74019 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 22:39:36 to 12-10-2022 22:39:40.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:35 19703 8192000000 9874856064 10185039872 967120208 10.559 100 79 158 0 994277600 5428288 21729104
20:11:07 4510 8192000000 9931885264 10248519680 1016016992 11.034 100 89 178 0 1046542080 7191504 23333584
20:12:13 9271 8192000000 9963043712 10288087040 1045564624 11.318 100 84 168 1 1075104464 8043136 21496704
20:13:30 15124 8192000000 10010752928 10351554560 1095011552 11.790 100 77 153 0 1122043264 8415664 18616048
20:14:57 9331 8192000000 10078057888 10439593984 1166187408 12.461 100 72 144 0 1192571648 10269088 16115152
20:16:08 4921 8192000000 10122721200 10489417728 1207148752 12.843 100 84 164 0 1238599824 13293760 18157312
20:17:14 2896 8192000000 10160465232 10533736448 1242908016 13.173 100 81 162 1 1276129584 15000496 18221072
20:18:19 4531 8192000000 10176437200 10558447616 1267172624 13.396 100 79 157 0 1296564800 12384944 17007232
20:19:25 1073 8192000000 10213341792 10595741696 1295450752 13.654 100 86 170 0 1328090608 13304496 19335360
20:20:28 9827 8192000000 10210334144 10608508928 1307532992 13.764 100 60 119 1 1328474592 7181856 13759744
20:21:39 7659 8192000000 10197454528 10614767616 1315402640 13.835 100 27 55 0 1328971504 7676288 5892576
20:22:47 10935 8192000000 10204972768 10625544192 1323348496 13.907 100 13 24 0 1331639920 5523872 2767552
20:23:59 8583 8192000000 10221769008 10637189120 1329721760 13.965 100 14 28 2 1337899920 5227472 2950688
20:25:08 39892 8192000000 10226122384 10646765568 1333743920 14.001 100 32 64 0 1346869584 5897472 7228192
20:26:48 8496 8192000000 10225962480 10650505216 1336192592 14.023 100 21 42 1 1346654672 5560704 4901376
20:27:57 8648 8192000000 10207035472 10629554176 1311166992 13.797 99 28 56 0 1324252912 6870592 6215328
20:29:06 13216 8192000000 10167686416 10551824384 1228171200 13.037 94 21 42 0 1238335856 5270144 4894512
20:30:21 15983 8192000000 10159614448 10570387456 1238339136 13.131 95 20 40 0 1247663104 5131056 4192912
20:31:41 6556 8192000000 10128095056 10530439168 1207830736 12.849 96 24 48 0 1220167024 7078208 5258080
20:32:48 7374 8192000000 10141141008 10543468544 1250718144 13.245 100 27 53 0 1263114128 6561424 5834560
20:33:58 65605 8192000000 10174892480 10578853888 1270835920 13.429 99 35 69 2 1283947344 5148544 7962880
20:36:06 8703 8192000000 10191512768 10602188800 1298011184 13.677 100 20 40 0 1308483040 5868576 4603280
20:37:16 11100 8192000000 10202361136 10609950720 1303458144 13.727 100 21 42 0 1313997328 5696944 4842240
20:38:38 14699 8192000000 10235722720 10641997824 1327439024 13.944 100 46 91 0 1344158064 5680512 11038528
20:40:25 94857 8192000000 10250230352 10681389056 1354523504 14.188 100 41 81 0 1375865536 11893152 9448880
20:43:06 22840 8192000000 10268866512 10692964352 1366180592 14.293 100 39 79 0 1387157104 11682576 9293936
20:44:41 4615 8192000000 10303950992 10721185792 1381664112 14.431 100 57 113 2 1412939984 18087840 13188032
20:45:50 2983 8192000000 10316536976 10730360832 1391079040 14.515 100 68 135 1 1425138384 18457104 15602240
20:47:00 1693 8192000000 10330554816 10749120512 1407376512 14.661 100 57 114 0 1440789168 19930128 13482528
20:48:08 5905 8192000000 10323089152 10745995264 1403414656 14.625 100 56 109 0 1439522272 23289680 12817936
20:49:20 3713 8192000000 10325149424 10746019840 1395014656 14.551 99 54 104 1 1435158320 27329600 12814064
20:50:28 3532 8192000000 10350066000 10769649664 1415470336 14.733 100 67 132 2 1457350848 26109824 15770688
20:51:38 4041 8192000000 10355209008 10769784832 1413618032 14.716 100 68 135 0 1460472624 30560848 16293744
20:52:49 4753 8192000000 10376122304 10797113344 1429094720 14.853 100 68 136 0 1483437168 37115312 17227136
20:54:01 1530 8192000000 10379911664 10785050624 1415055616 14.729 100 95 189 0 1476152800 37383792 23713392
20:55:09 1722 8192000000 10343340112 10750267392 1385618352 14.467 99 86 171 4 1445998320 38725600 21654368
20:56:15 10438 8192000000 10341736640 10753146880 1381438800 14.429 99 69 138 0 1436951856 36769856 18743200
20:57:32 3075 8192000000 10313570480 10720309248 1350547840 14.152 98 62 122 1 1404459024 37636480 16274704
20:58:42 23512 8192000000 10270368208 10697568256 1318767760 13.866 97 24 48 0 1350789216 25634064 6387392
21:00:20 22669 8192000000 10284742416 10724003840 1338149232 14.041 99 29 58 1 1384483280 37555248 8778800
21:02:10 27919 8192000000 10271860704 10717696000 1353751200 14.181 100 12 24 0 1377474496 20096944 3626352
21:04:01 1537 8192000000 10289518688 10717122560 1361396592 14.250 100 47 91 0 1388950592 16483792 11070208
21:05:04 53351 8192000000 10272100800 10714710016 1318020576 13.859 97 20 40 0 1344323040 21922656 4379808
21:07:24 17243 8192000000 10251949040 10688045056 1331055184 13.977 99 28 56 0 1346797808 9339312 6403312
21:08:50 53565 8192000000 10286569296 10712743936 1351581344 14.162 99 36 71 1 1369923056 10253840 8087872
21:10:52 52819 8192000000 10284396592 10714763264 1359512080 14.233 100 42 84 0 1381392000 11628592 10251328
21:12:55 59413 8192000000 10263569888 10715987968 1365783664 14.289 100 7 14 0 1376473008 9247920 1441424
21:14:56 36514 8192000000 10280376512 10729185280 1367087936 14.301 99 22 44 0 1383634112 10699648 5846528
21:16:39 71550 8192000000 10276750320 10740510720 1383643936 14.449 100 18 36 0 1398939344 11095392 4200016
21:18:52 22249 8192000000 10275617296 10728607744 1358377584 14.223 98 17 34 0 1370683696 8665536 3640576
21:20:21 50123 8192000000 10295318032 10746089472 1393203376 14.534 100 15 30 0 1408237680 11392064 3642240
21:22:12 56950 8192000000 10288728080 10754289664 1393199552 14.534 100 22 44 0 1410083376 11626080 5257744
21:24:11 25712 8192000000 10286190672 10747228160 1383568112 14.448 99 13 26 0 1397996080 11456496 2971472
21:25:49 69803 8192000000 10295291040 10766028800 1382429424 14.438 99 12 24 0 1402150720 16742240 2979056
21:27:59 38394 8192000000 10302814624 10762285056 1398987344 14.586 100 15 28 0 1410569104 8297248 3284512
21:29:39 36683 8192000000 10308119872 10766802944 1378557904 14.404 98 32 64 0 1400217392 14191680 7467808
21:31:23 17278 8192000000 10314005184 10750697472 1384997248 14.461 99 37 72 0 1400756944 6881952 8877744
21:32:42 46988 8192000000 10299511520 10776969216 1397570432 14.573 100 15 30 0 1419802160 18481040 3750688
21:34:54 39527 8192000000 10301107296 10742292480 1381949968 14.434 100 30 60 0 1398753872 10560960 6242944
21:36:37 26865 8192000000 10296949360 10720460800 1336805904 14.029 97 43 84 3 1354661536 7884896 9970736
21:38:08 41074 8192000000 10293568944 10715967488 1359272752 14.231 99 61 117 2 1381485968 8984208 13229008
21:39:53 30267 8192000000 10272054032 10718294016 1370276672 14.330 100 23 47 0 1386364448 11281808 4805968
21:41:23 29255 8192000000 10265360320 10713001984 1353200512 14.176 99 25 50 0 1366573392 7957168 5415712
21:43:00 58443 8192000000 10243026144 10696278016 1325610960 13.927 98 7 14 0 1338066144 11011600 1443584
21:44:59 76919 8192000000 10259444608 10709475328 1319526624 13.872 97 19 38 0 1338351424 14413392 4411408
21:47:23 76008 8192000000 10264276496 10708692992 1310304000 13.789 96 16 32 0 1333570672 19518960 3747712
21:50:28 71246 8192000000 10262923216 10721030144 1311927488 13.804 97 13 26 0 1343828768 28970752 2930528
21:52:49 30715 8192000000 10262359408 10685693952 1294633264 13.646 96 47 94 1 1314829088 9582624 10613200
21:54:22 48197 8192000000 10272022800 10716360704 1348449568 14.134 100 29 57 0 1372046416 17280336 6316512
21:56:11 59076 8192000000 10273991184 10705911808 1332343504 13.988 98 25 49 3 1352802832 13650448 6808880
21:58:10 105552 8192000000 10248333360 10705829888 1339166368 14.050 99 25 50 0 1355598304 10688384 5743552
22:01:00 64086 8192000000 10255973584 10713251840 1351497936 14.161 99 11 22 0 1364208976 10024880 2686160
22:03:06 69188 8192000000 10264640048 10718642176 1360702528 14.244 99 23 48 0 1375912256 9649568 5560160
22:05:21 64079 8192000000 10282624640 10735669248 1376289280 14.383 100 13 24 1 1390284080 11635632 2359168
22:07:26 33050 8192000000 10289692320 10741575680 1377392640 14.393 99 16 32 0 1389666528 8754432 3519456
22:09:06 72925 8192000000 10299611264 10745933824 1364763984 14.280 97 27 54 0 1382625632 11785344 6076304
22:11:19 51411 8192000000 10291049264 10753609728 1378008672 14.399 99 26 52 0 1396207408 12289600 5909136
22:13:11 89953 8192000000 10309539456 10759065600 1390607008 14.511 100 7 14 0 1415035328 22736960 1691360
22:15:45 56147 8192000000 10314028816 10766225408 1400679728 14.601 100 29 58 0 1418137264 10953424 6504112
22:17:41 79628 8192000000 10297885328 10749714432 1360555968 14.242 97 26 52 0 1377445376 11161280 5728128
22:20:01 135004 8192000000 10298238464 10756640768 1320358000 13.880 94 26 52 0 1343614656 17259712 5996944
22:23:17 44280 8192000000 10257439488 10717282304 1312021072 13.804 95 8 16 0 1327144240 13481152 1642016
22:25:09 43066 8192000000 10265872432 10725945344 1360276608 14.240 99 15 27 1 1370767456 7471696 3019152
22:27:02 30433 8192000000 10257484400 10714943488 1360676688 14.243 99 10 20 0 1372938496 9585184 2676624
22:28:32 73693 8192000000 10221301520 10685943808 1307378960 13.762 95 13 26 0 1320279472 10219904 2680608
22:30:46 50780 8192000000 10281028320 10736721920 1360240976 14.240 99 16 32 0 1373745568 9511808 3992784
22:32:38 64584 8192000000 10287366528 10731122688 1340081616 14.058 97 18 33 1 1355220800 11063072 4076112
22:34:44 31501 8192000000 10269443728 10710953984 1327958320 13.949 97 27 55 0 1342985600 9161728 5865552
22:36:48 9717 8192000000 10275131824 10711273472 1354118864 14.185 100 40 80 1 1373882064 10874976 8888224
22:38:00 41252 8192000000 9234938656 9500254208 383090976 4.467 28 8 16 0 395988032 11195184 1701872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 22:39:42 to 12-10-2022 22:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2022 22:39:59 to 12-10-2022 22:40:01.

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          240296
MON$OLDEST_ACTIVE               244244
MON$OLDEST_SNAPSHOT             244244
MON$NEXT_TRANSACTION            244244
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                       2014528
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
--- end of diff output ---

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

12.10.22 22:40:02. 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-10-12 22:40:04 No severe PSQL-related problems occured
12.10.22 22:40:06. End of report.