Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.05.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16338028 kB
MemAvailable 31407360 kB
Buffers 0 kB
Cached 15402364 kB
SwapCached 2464 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-28 01:38:15
DTS_END                         2022-05-28 01:38:15.2500
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/35180
TRN_ID                          245105
ATT_ID                          657
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: 9314.27 24165 1117712
customer order: creation 174.11 347 20893
customer order: refuse 15.08 324 1809
order to supplier: creation 46.34 1260 5561
order to supplier: removal 3.62 97921 434
invoice (draft): creation 47.87 3335 5744
invoice (draft): removal 53.92 834 6470
invoice accept: apply 42.19 17531 5063
invoice accept: cancel 47.90 803 5748
customer reserve: creation 3882.70 173 465924
customer reserve: removal 4689.93 59 562792
realization accept: apply 85.88 190 10306
realization accept: cancel 89.28 107 10714
payment from customer: creation 62.64 114 7517
payment from customer: removal 6.38 61 765
payment to supplier: creation 50.54 138 6065
payment to supplier: removal 3.62 102 434
service: total inventory turnovers 4.55 1201 546
service: total monetary turnovers 6.95 30 834
service: aggregate perf. data 0.74 19498 89
service: refresh index statistics 0.03 339268 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-05-2022 01:39:45 to 28-05-2022 01:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3076
TEST_TIME 2 2365
TEST_TIME 3 2956
TEST_TIME 4 3785
TEST_TIME 5 3734
TEST_TIME 6 4557
TEST_TIME 7 4513
TEST_TIME 8 4493
TEST_TIME 9 4578
TEST_TIME 10 4992
TEST_TIME 11 5906
TEST_TIME 12 6221
TEST_TIME 13 6184
TEST_TIME 14 6137
TEST_TIME 15 6142
TEST_TIME 16 6922
TEST_TIME 17 7186
TEST_TIME 18 7341
TEST_TIME 19 7581
TEST_TIME 20 7588
TEST_TIME 21 7994
TEST_TIME 22 8359
TEST_TIME 23 9314
TEST_TIME 24 9318
TEST_TIME 25 9337
TEST_TIME 26 9573
TEST_TIME 27 9561
TEST_TIME 28 10842
TEST_TIME 29 10880
TEST_TIME 30 10878
TEST_TIME 31 10671
TEST_TIME 32 10677
TEST_TIME 33 10602
TEST_TIME 34 10470
TEST_TIME 35 10424
TEST_TIME 36 10477
TEST_TIME 37 10364
TEST_TIME 38 10126
TEST_TIME 39 10205
TEST_TIME 40 10127
TEST_TIME 41 9950
TEST_TIME 42 9942
TEST_TIME 43 9968
TEST_TIME 44 9916
TEST_TIME 45 9867
TEST_TIME 46 10089
TEST_TIME 47 9945
TEST_TIME 48 9819
TEST_TIME 49 9800
TEST_TIME 50 9637
TEST_TIME 51 9668
TEST_TIME 52 9889
TEST_TIME 53 9914
TEST_TIME 54 9986
TEST_TIME 55 9969
TEST_TIME 56 9899
TEST_TIME 57 10034
TEST_TIME 58 9990
TEST_TIME 59 9983
TEST_TIME 60 10072
TEST_TIME 61 10026
TEST_TIME 62 10161
TEST_TIME 63 10090
TEST_TIME 64 9989
TEST_TIME 65 9933
TEST_TIME 66 9878
TEST_TIME 67 9802
TEST_TIME 68 9786
TEST_TIME 69 9758
TEST_TIME 70 9704
TEST_TIME 71 9680
TEST_TIME 72 9617
TEST_TIME 73 9530
TEST_TIME 74 9511
TEST_TIME 75 9554
TEST_TIME 76 9531
TEST_TIME 77 9515
TEST_TIME 78 9507
TEST_TIME 79 9515
TEST_TIME 80 9575
TEST_TIME 81 9568
TEST_TIME 82 9598
TEST_TIME 83 9628
TEST_TIME 84 9603
TEST_TIME 85 9626
TEST_TIME 86 9669
TEST_TIME 87 9686
TEST_TIME 88 9828
TEST_TIME 89 9779
TEST_TIME 90 9694
TEST_TIME 91 9669
TEST_TIME 92 9599
TEST_TIME 93 9625
TEST_TIME 94 9618
TEST_TIME 95 9612
TEST_TIME 96 9569
TEST_TIME 97 9547
TEST_TIME 98 9523
TEST_TIME 99 9548
TEST_TIME 100 9508
TEST_TIME 101 9518
TEST_TIME 102 9497
TEST_TIME 103 9597
TEST_TIME 104 9552
TEST_TIME 105 9612
TEST_TIME 106 9582
TEST_TIME 107 9594
TEST_TIME 108 9605
TEST_TIME 109 9583
TEST_TIME 110 9615
TEST_TIME 111 9541
TEST_TIME 112 9535
TEST_TIME 113 9485
TEST_TIME 114 9460
TEST_TIME 115 9439
TEST_TIME 116 9395
TEST_TIME 117 9411
TEST_TIME 118 9374
TEST_TIME 119 9381
TEST_TIME 120 9316

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-05-2022 01:39:49 to 28-05-2022 01:39:52.

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 1548317 1548317 0 0.00 0 26616 6 0 0
doc_list_biud 1548300 1548300 0 0.00 0 45731 28 0 0
sp_add_invoice_to_stock 5147 5063 84 1.63 27 191637 17532 0 84
sp_cancel_adding_invoice 5748 5748 0 0.00 4 51920 804 0 0
sp_cancel_client_order 1809 1809 0 0.00 4 6943 325 0 0
sp_cancel_customer_reserve 562792 562792 0 0.00 1 27874 59 0 0
sp_cancel_pay_from_customer 765 765 0 0.00 3 3149 62 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 2 10627 103 0 0
sp_cancel_supplier_invoice 6477 6470 7 0.10 1 28307 834 0 7
sp_cancel_supplier_order 434 434 0 0.00 8 991526 97921 0 0
sp_cancel_write_off 10714 10714 0 0.00 1 16325 108 0 0
sp_client_order 20893 20893 0 0.00 3 19635 348 0 0
sp_customer_reserve 465924 465924 0 0.00 0 44598 174 0 0
sp_fill_shopping_cart 32198 32198 0 0.00 1 14645 41 0 0
sp_fill_shopping_cart_clo_res 465923 465923 0 0.00 0 26054 15 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 27844 303 0 0
sp_kill_cost_storno 17661 17661 0 0.00 0 9718 52 0 0
sp_kill_qstorno_ret_qs2qd 1143202 1143202 0 0.00 0 28282 33 0 0
sp_kill_qty_storno 603328 603328 0 0.00 0 45731 70 0 0
sp_lock_selected_doc 21393 21393 0 0.00 0 10196 6 0 0
sp_make_cost_storno 28950 28950 0 0.00 0 26341 63 0 0
sp_multiply_rows_for_pdistr 28945 28945 0 0.00 0 9617 29 0 0
sp_multiply_rows_for_qdistr 20893 20893 0 0.00 0 11531 161 0 0
sp_pay_from_customer 7517 7517 0 0.00 4 20691 114 0 0
sp_pay_to_supplier 6065 6065 0 0.00 4 26628 138 0 0
sp_payment_common 13572 13572 0 0.00 2 10925 24 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 6938 299 0 0
sp_qd_handle_on_invoice_upd_sts 10809 10809 0 0.00 0 45729 339 0 0
sp_qd_handle_on_reserve_upd_sts 10300 10300 0 0.00 0 14567 67 0 0
sp_reserve_write_off 10306 10306 0 0.00 6 21749 191 0 0
sp_supplier_invoice 5744 5744 0 0.00 6 90424 3336 0 0
sp_supplier_order 5561 5561 0 0.00 4 37443 1261 0 0
srv_aggregate_perf_data 90 89 1 1.11 2979 146380 19498 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2097 138605 46428 0 0
srv_find_qd_qs_mism 1068429 1068429 0 0.00 0 19358 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 36 47475 1202 0 0
srv_make_money_saldo 834 834 0 0.00 1 3265 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 231363 544194 339268 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5063 5063 0 0.00 0 24986 176 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 5502 110 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 4596 18 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 4024 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 765 765 0 0.00 0 1780 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 978 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 790 15 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 24676 1669 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 2582 10 0 0
t$perf-norm:sp_client_order 20893 20893 0 0.00 0 10156 18 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 5852 11 0 0
t$perf-norm:sp_pay_from_customer 7517 7517 0 0.00 0 10204 14 0 0
t$perf-norm:sp_pay_to_supplier 6065 6065 0 0.00 0 12427 17 0 0
t$perf-norm:sp_reserve_write_off 10306 10306 0 0.00 0 9919 11 0 0
t$perf-norm:sp_supplier_invoice 5744 5744 0 0.00 0 2784 14 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 6488 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 746 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 116 2 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 5245 49 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 1037 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10286 10118 168 1.63 0 6162 18 0 168
v_all_customers 37934 37934 0 0.00 0 74 1 0 0
v_all_suppliers 31178 31178 0 0.00 0 17 1 0 0
v_all_wares 41786 41786 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1800 8 0 0
v_cancel_client_order 3618 3618 0 0.00 0 4174 8 0 0
v_cancel_customer_prepayment 1530 1530 0 0.00 0 3126 7 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 825 3 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 3103 9 0 0
v_cancel_supplier_invoice 820 806 14 1.70 0 1540 15 0 14
v_cancel_supplier_order 868 868 0 0.00 0 1395 6 0 0
v_cancel_write_off 2116 2116 0 0.00 0 3099 15 0 0
v_max_id_clo_ord 5561 5561 0 0.00 0 479 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 6816 20 0 0
v_max_id_ord_sup 5744 5744 0 0.00 0 8654 3 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 146 2 0 0
v_max_non_paid_realizn 4061 4061 0 0.00 0 7910 7 0 0
v_min_id_clo_ord 5561 5561 0 0.00 0 3272 5 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 3438 6 0 0
v_min_id_ord_sup 5744 5744 0 0.00 0 10258 17 0 0
v_min_non_paid_invoice 6062 6062 0 0.00 0 9605 12 0 0
v_min_non_paid_realizn 7510 7510 0 0.00 0 9155 17 0 0
v_our_firm 10750 10750 0 0.00 0 26 1 0 0
v_reserve_write_off 20600 20600 0 0.00 0 4916 7 0 0
x_lock_depdocs_on_canc_invoice 11815 11815 0 0.00 0 13118 112 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 5293 336 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 1 37361 1177 0 0
x_make_qstorno_1200_2000 5744 5744 0 0.00 2 90392 3185 0 0
x_make_qstorno_2100_3300 465924 465924 0 0.00 0 42214 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-05-2022 01:39:53 to 28-05-2022 01:39:57.

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:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:20 29762 8192000000 9908043536 10218135552 985529664 10.738 100 87 174 0 1014902832 6557280 22815888
23:10:56 23395 8192000000 9951634976 10273886208 1033727776 11.204 100 90 180 0 1065864672 9121024 23015872
23:12:28 11978 8192000000 9988086464 10317037568 1066826240 11.522 100 90 180 0 1099741168 10580656 22334272
23:13:47 14379 8192000000 10034219872 10382381056 1121205248 12.038 100 86 172 0 1153606416 11471936 20929232
23:15:06 7662 8192000000 10079615312 10437042176 1166847120 12.467 100 82 163 1 1198604688 12409568 19348000
23:16:16 4345 8192000000 10124393072 10494386176 1211102224 12.879 100 81 161 0 1242401376 13588224 17710928
23:17:22 2736 8192000000 10164412096 10539581440 1250188752 13.240 100 82 164 3 1282023696 13397264 18437680
23:18:26 1769 8192000000 10208275056 10588680192 1290731568 13.611 100 87 173 1 1323549344 14027056 18790720
23:19:29 1457 8192000000 10219404560 10605924352 1306545392 13.755 100 90 180 2 1340213792 13252544 20415856
23:20:31 22289 8192000000 10202812608 10613329920 1309117936 13.778 100 41 83 3 1326226112 7771168 9337008
23:21:54 9276 8192000000 10213220080 10624507904 1316037552 13.841 100 22 42 2 1328086688 6040880 6008256
23:23:04 8032 8192000000 10215326640 10632052736 1323690112 13.910 100 15 30 1 1337099024 9937728 3471184
23:24:13 14404 8192000000 10220122624 10638098432 1331925088 13.985 100 18 36 0 1342306320 6522352 3858880
23:25:28 16274 8192000000 10253457328 10653941760 1335922368 14.021 100 71 143 3 1359294416 6660992 16711056
23:26:44 2983 8192000000 10241256640 10654351360 1337387808 14.034 100 33 66 1 1355287152 9480048 8419296
23:27:50 4297 8192000000 10211900176 10622447616 1290647936 13.610 97 22 44 0 1302150816 6208656 5294224
23:28:55 46815 8192000000 10185818608 10597322752 1221211392 12.973 93 31 62 0 1237920944 9899184 6810368
23:30:42 4578 8192000000 10165687920 10565791744 1263238880 13.360 99 34 69 2 1277834256 6803280 7792096
23:31:47 5445 8192000000 10131405344 10534170624 1226945760 13.026 98 27 52 2 1238831712 5759968 6125984
23:32:53 10056 8192000000 10154743584 10557480960 1261340704 13.342 100 25 50 1 1273221296 5360416 6520176
23:34:07 34843 8192000000 10154342288 10567274496 1248111664 13.221 98 19 38 0 1257926288 4733392 5081232
23:35:43 7690 8192000000 10192337696 10598862848 1285880528 13.567 100 34 68 1 1308432384 14374624 8177232
23:36:55 8049 8192000000 10189178464 10601578496 1292191632 13.624 100 27 54 1 1304466304 5927152 6347520
23:38:06 36094 8192000000 10229138368 10634268672 1316252256 13.843 100 25 51 1 1328414768 4705312 7457200
23:39:53 138605 8192000000 10252363552 10681573376 1347339392 14.124 100 35 70 1 1369623456 14423088 7860976
23:43:17 11395 8192000000 10279208224 10699149312 1363945728 14.273 100 57 114 1 1394275200 17445088 12884384
23:44:32 45923 8192000000 10287287984 10721628160 1378497296 14.403 100 30 59 2 1407667984 21794384 7376304
23:46:31 16021 8192000000 10311760048 10728480768 1381485088 14.430 100 50 100 0 1418169136 24704784 11979264
23:47:55 11513 8192000000 10316555744 10736107520 1375835616 14.379 99 52 104 0 1417668016 29118416 12713984
23:49:09 9817 8192000000 10305095712 10717995008 1364407104 14.277 99 72 144 4 1407370848 24844736 18119008
23:50:24 6834 8192000000 10332303712 10746384384 1388590144 14.493 100 69 138 1 1440031840 34400976 17040720
23:51:34 2800 8192000000 10321279632 10732769280 1378150192 14.400 100 62 121 2 1428554016 34719584 15684240
23:52:39 16850 8192000000 10338415936 10754883584 1386723824 14.477 99 56 112 0 1440731968 39358112 14650032
23:54:02 2096 8192000000 10381042416 10781048832 1402728464 14.619 100 87 171 0 1477707104 52741616 22237024
23:55:08 4490 8192000000 10337850832 10747113472 1369117056 14.319 99 64 128 1 1435222960 49760272 16345632
23:56:19 6381 8192000000 10361083680 10774093824 1393238352 14.535 100 55 109 1 1462609968 54991152 14380464
23:57:29 6387 8192000000 10341704112 10759360512 1381464576 14.430 100 55 110 1 1452570288 56261632 14844080
23:58:40 6617 8192000000 10328089392 10740244480 1354275760 14.186 100 55 108 2 1432536256 63534176 14726320
23:59:50 12843 8192000000 10324437664 10738929664 1342703024 14.082 99 52 104 3 1419206672 62221472 14282176
00:01:15 12729 8192000000 10319603840 10744016896 1358421984 14.223 100 39 79 2 1425665360 56524112 10719264
00:02:32 24108 8192000000 10318597264 10755706880 1377767504 14.397 100 21 42 1 1423306112 39764752 5773856
00:04:24 33014 8192000000 10307187600 10751725568 1361989488 14.255 100 24 48 1 1418577280 49538704 7049088
00:06:08 37597 8192000000 10294866880 10752110592 1360686224 14.244 100 18 36 0 1412775584 46510528 5578832
00:07:51 44225 8192000000 10279615152 10730696704 1360278096 14.240 99 10 19 0 1372749408 10294976 2176336
00:09:48 69660 8192000000 10282604560 10730774528 1365818720 14.290 99 29 58 0 1381460208 9266704 6374784
00:12:09 59943 8192000000 10307793392 10738266112 1356123072 14.203 98 24 47 1 1373896736 12739808 5033856
00:14:13 37971 8192000000 10304982928 10754846720 1375839984 14.379 99 24 49 1 1395381360 13971232 5570144
00:16:05 130808 8192000000 10320070336 10763755520 1396337424 14.562 100 26 52 1 1414259680 11547824 6374432
00:19:19 30817 8192000000 10297027968 10749419520 1382565232 14.439 99 28 56 2 1397892192 8808880 6518080
00:20:58 17057 8192000000 10322107616 10778599424 1404950352 14.639 100 22 44 1 1422162736 11687280 5525104
00:22:20 40843 8192000000 10337717888 10789064704 1400000992 14.595 99 40 81 0 1430513936 21318448 9194496
00:24:17 67688 8192000000 10325630640 10811691008 1413219104 14.713 100 9 18 1 1438707664 23162432 2326128
00:26:25 58843 8192000000 10325404528 10782134272 1378345984 14.402 97 17 34 0 1401576288 18469568 4760736
00:28:25 87552 8192000000 10300792336 10765340672 1342826640 14.083 96 20 39 0 1367251280 19967600 4457040
00:31:06 108969 8192000000 10295560112 10748846080 1320404160 13.880 95 18 36 0 1339532896 14997488 4131248
00:33:59 36806 8192000000 10302180736 10754392064 1375760864 14.379 99 26 52 0 1392636192 11039824 5835504
00:35:41 74237 8192000000 10286930416 10736422912 1339954048 14.057 97 12 24 1 1357136160 14719504 2462608
00:38:01 63017 8192000000 10264517264 10719232000 1297218816 13.670 94 17 34 0 1311018336 10163952 3635568
00:40:04 79530 8192000000 10249110560 10698051584 1304937680 13.740 97 13 26 0 1323643136 16191680 2513776
00:42:25 56664 8192000000 10264683648 10692083712 1323381248 13.907 98 43 86 2 1346488512 13937200 9170064
00:44:53 38367 8192000000 10275071328 10711748608 1354739200 14.190 100 47 94 4 1376665456 10439136 11487120
00:46:33 41123 8192000000 10229658384 10689429504 1309754688 13.784 97 15 30 1 1322777632 9014272 4008672
00:49:09 64435 8192000000 10246168848 10702487552 1313385456 13.817 97 9 17 0 1325365744 9727392 2252896
00:51:17 90713 8192000000 10243152576 10696994816 1329050768 13.959 99 31 61 0 1350045552 13984832 7009952
00:54:15 49864 8192000000 10247521072 10701254656 1344417456 14.097 99 19 38 0 1355666064 6907472 4341136
00:56:05 37956 8192000000 10245541504 10684112896 1293730496 13.638 96 32 65 0 1308495792 7516992 7248304
00:57:44 84491 8192000000 10264095712 10715963392 1340305440 14.060 99 6 12 0 1352038432 10317888 1415104
01:00:23 55080 8192000000 10278450560 10737274880 1368311360 14.312 100 9 18 0 1380660048 10317840 2030848
01:02:22 24856 8192000000 10297579168 10745610240 1376083248 14.382 100 36 72 1 1397184592 13217872 7883472
01:03:50 19907 8192000000 10300859824 10751119360 1374597344 14.368 99 26 52 1 1393866000 13738928 5529728
01:05:16 52001 8192000000 10302375456 10762854400 1374696944 14.369 99 19 38 2 1400856752 21814816 4344992
01:07:37 70938 8192000000 10291637472 10747981824 1374466752 14.367 99 31 61 2 1390199184 9142048 6590384
01:10:06 57797 8192000000 10301943184 10763784192 1398904400 14.585 100 18 35 1 1413548976 10696736 3947840
01:12:04 60570 8192000000 10291241872 10765242368 1379759344 14.414 99 10 20 0 1394835520 12947360 2128816
01:14:13 63766 8192000000 10281757264 10752204800 1377078736 14.390 99 13 26 0 1389433280 9308304 3046240
01:16:17 52911 8192000000 10293178496 10754973696 1362840864 14.263 98 14 28 0 1374788288 8870864 3076560
01:18:11 26926 8192000000 10290989760 10746507264 1374894064 14.371 99 17 33 0 1385719760 7218528 3607168
01:19:38 42070 8192000000 10299936800 10752655360 1356065920 14.202 97 32 64 3 1373335056 9577120 7692016
01:21:21 36422 8192000000 10280214000 10751045632 1367269536 14.303 99 11 23 0 1380571488 10901648 2400304
01:23:08 64881 8192000000 10249641216 10709114880 1313447536 13.817 96 20 40 0 1334995056 17095728 4451792
01:25:13 23783 8192000000 10277577744 10720542720 1336086816 14.022 98 33 66 1 1354635776 11368592 7180368
01:26:48 51697 8192000000 10285945792 10724876288 1338148016 14.041 98 26 51 2 1351623024 7804224 5670784
01:28:41 105055 8192000000 10239878208 10689593344 1308190352 13.770 96 18 35 2 1326758000 14318752 4248896
01:31:41 63773 8192000000 10264169504 10718285824 1351556640 14.161 100 14 28 0 1369222544 14204112 3461792
01:34:43 60538 8192000000 10248412016 10707771392 1304686304 13.738 96 7 14 0 1315781952 9644720 1450928
01:36:48 78237 8192000000 10253264944 10727972864 1346293248 14.114 99 8 16 0 1359934672 11847344 1794080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 84 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-05-2022 01:40:15 to 28-05-2022 01:40:17.

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          244965
MON$OLDEST_ACTIVE               245594
MON$OLDEST_SNAPSHOT             245594
MON$NEXT_TRANSACTION            245594
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                       2044960
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.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat May 28 01:38:16 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat May 28 01:38:18 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat May 28 01:38:18 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat May 28 01:38:18 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sat May 28 01:38:18 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Sat May 28 01:38:18 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

28.05.22 01:40:18. 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-28 01:40:20 No severe PSQL-related problems occured
28.05.22 01:40:22. End of report.