Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.2021 19:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15892412 kB
MemAvailable 30919944 kB
Buffers 0 kB
Cached 15748600 kB
SwapCached 1868 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.8
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 2021-08-23 19:37:46
DTS_END                         2021-08-23 19:37:46.9230
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/46716
TRN_ID                          251846
ATT_ID                          748
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: 9658.42 19074 1159011
customer order: creation 180.61 320 21673
customer order: refuse 15.95 356 1914
order to supplier: creation 47.98 1323 5758
order to supplier: removal 3.75 92448 450
invoice (draft): creation 48.53 3117 5824
invoice (draft): removal 55.73 803 6687
invoice accept: apply 43.43 16429 5212
invoice accept: cancel 49.83 797 5979
customer reserve: creation 4006.12 163 480734
customer reserve: removal 4899.91 55 587989
realization accept: apply 87.58 163 10509
realization accept: cancel 84.13 100 10096
payment from customer: creation 61.73 103 7407
payment from customer: removal 5.93 97 712
payment to supplier: creation 50.96 120 6115
payment to supplier: removal 3.34 72 401
service: total inventory turnovers 5.37 904 644
service: total monetary turnovers 6.70 46 804
service: aggregate perf. data 0.83 21363 99
service: refresh index statistics 0.03 242698 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-08-2021 19:38:34 to 23-08-2021 19:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5398
TEST_TIME 2 3351
TEST_TIME 3 4061
TEST_TIME 4 4134
TEST_TIME 5 4941
TEST_TIME 6 5139
TEST_TIME 7 5216
TEST_TIME 8 5417
TEST_TIME 9 5749
TEST_TIME 10 5534
TEST_TIME 11 5783
TEST_TIME 12 5917
TEST_TIME 13 6245
TEST_TIME 14 6376
TEST_TIME 15 6616
TEST_TIME 16 6876
TEST_TIME 17 7158
TEST_TIME 18 7780
TEST_TIME 19 8245
TEST_TIME 20 8386
TEST_TIME 21 8198
TEST_TIME 22 8655
TEST_TIME 23 9103
TEST_TIME 24 8952
TEST_TIME 25 9324
TEST_TIME 26 9631
TEST_TIME 27 9757
TEST_TIME 28 9881
TEST_TIME 29 9776
TEST_TIME 30 9671
TEST_TIME 31 10148
TEST_TIME 32 10891
TEST_TIME 33 10841
TEST_TIME 34 10773
TEST_TIME 35 10884
TEST_TIME 36 10712
TEST_TIME 37 10631
TEST_TIME 38 10761
TEST_TIME 39 10611
TEST_TIME 40 10610
TEST_TIME 41 10651
TEST_TIME 42 10561
TEST_TIME 43 10472
TEST_TIME 44 10259
TEST_TIME 45 10238
TEST_TIME 46 10181
TEST_TIME 47 10271
TEST_TIME 48 10150
TEST_TIME 49 10169
TEST_TIME 50 10082
TEST_TIME 51 10107
TEST_TIME 52 10117
TEST_TIME 53 10064
TEST_TIME 54 10006
TEST_TIME 55 9933
TEST_TIME 56 9870
TEST_TIME 57 9742
TEST_TIME 58 9696
TEST_TIME 59 9742
TEST_TIME 60 9710
TEST_TIME 61 9728
TEST_TIME 62 9714
TEST_TIME 63 9743
TEST_TIME 64 9804
TEST_TIME 65 9868
TEST_TIME 66 9904
TEST_TIME 67 9908
TEST_TIME 68 9917
TEST_TIME 69 9915
TEST_TIME 70 9931
TEST_TIME 71 9992
TEST_TIME 72 9949
TEST_TIME 73 9952
TEST_TIME 74 10058
TEST_TIME 75 9957
TEST_TIME 76 9913
TEST_TIME 77 9928
TEST_TIME 78 9869
TEST_TIME 79 9847
TEST_TIME 80 9809
TEST_TIME 81 9789
TEST_TIME 82 9800
TEST_TIME 83 9753
TEST_TIME 84 9763
TEST_TIME 85 9728
TEST_TIME 86 9673
TEST_TIME 87 9709
TEST_TIME 88 9676
TEST_TIME 89 9626
TEST_TIME 90 9667
TEST_TIME 91 9666
TEST_TIME 92 9678
TEST_TIME 93 9685
TEST_TIME 94 9727
TEST_TIME 95 9728
TEST_TIME 96 9792
TEST_TIME 97 9802
TEST_TIME 98 9766
TEST_TIME 99 9770
TEST_TIME 100 9856
TEST_TIME 101 9871
TEST_TIME 102 9912
TEST_TIME 103 9899
TEST_TIME 104 9814
TEST_TIME 105 9776
TEST_TIME 106 9755
TEST_TIME 107 9716
TEST_TIME 108 9674
TEST_TIME 109 9640
TEST_TIME 110 9580
TEST_TIME 111 9595
TEST_TIME 112 9584
TEST_TIME 113 9586
TEST_TIME 114 9575
TEST_TIME 115 9563
TEST_TIME 116 9612
TEST_TIME 117 9701
TEST_TIME 118 9722
TEST_TIME 119 9686
TEST_TIME 120 9660

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-08-2021 19:38:37 to 23-08-2021 19:38:41.

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 1603995 1603995 0 0.00 0 32787 6 0 0
doc_list_biud 1603994 1603994 0 0.00 0 26056 26 0 0
sp_add_invoice_to_stock 5357 5212 145 2.70 34 140867 16429 0 145
sp_cancel_adding_invoice 5979 5979 0 0.00 5 77199 797 0 0
sp_cancel_client_order 1914 1914 0 0.00 4 16852 357 0 0
sp_cancel_customer_reserve 587989 587989 0 0.00 1 26058 56 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 4398 98 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4478 73 0 0
sp_cancel_supplier_invoice 6704 6687 17 0.25 2 16576 803 0 17
sp_cancel_supplier_order 450 450 0 0.00 6 1030490 92449 0 0
sp_cancel_write_off 10096 10096 0 0.00 1 15102 101 0 0
sp_client_order 21673 21673 0 0.00 3 35710 320 0 0
sp_customer_reserve 480734 480734 0 0.00 0 85535 163 0 0
sp_fill_shopping_cart 33243 33243 0 0.00 1 21555 36 0 0
sp_fill_shopping_cart_clo_res 480728 480728 0 0.00 0 22159 14 0 0
sp_get_clo_for_invoice 5206 5206 0 0.00 0 26888 300 0 0
sp_kill_cost_storno 17188 17188 0 0.00 0 15100 54 0 0
sp_kill_qstorno_ret_qs2qd 1193211 1193211 0 0.00 0 26054 30 0 0
sp_kill_qty_storno 628829 628829 0 0.00 0 26056 66 0 0
sp_lock_selected_doc 21930 21930 0 0.00 0 7229 5 0 0
sp_make_cost_storno 29236 29236 0 0.00 0 11510 49 0 0
sp_multiply_rows_for_pdistr 29236 29236 0 0.00 0 6712 28 0 0
sp_multiply_rows_for_qdistr 21673 21673 0 0.00 0 34344 150 0 0
sp_pay_from_customer 7407 7407 0 0.00 3 11742 103 0 0
sp_pay_to_supplier 6115 6115 0 0.00 4 16747 121 0 0
sp_payment_common 13522 13522 0 0.00 1 5261 23 0 0
sp_qd_handle_on_cancel_clo 1914 1914 0 0.00 0 16842 318 0 0
sp_qd_handle_on_invoice_upd_sts 11184 11184 0 0.00 0 20776 318 0 0
sp_qd_handle_on_reserve_upd_sts 10509 10509 0 0.00 0 15144 50 0 0
sp_reserve_write_off 10509 10509 0 0.00 7 15183 163 0 0
sp_supplier_invoice 5824 5824 0 0.00 8 66215 3118 0 0
sp_supplier_order 5758 5758 0 0.00 5 39735 1323 0 0
srv_aggregate_perf_data 100 99 1 1.00 2970 162424 21364 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2108 150401 44425 0 0
srv_find_qd_qs_mism 1109101 1109101 0 0.00 0 32786 5 0 0
srv_make_invnt_saldo 644 644 0 0.00 34 54312 905 0 0
srv_make_money_saldo 804 804 0 0.00 0 14136 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 55185 527782 242699 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 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 5212 5212 0 0.00 0 6856 140 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 1 3344 80 0 0
t$perf-norm:sp_cancel_client_order 1914 1914 0 0.00 0 5880 13 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 5483 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 649 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 1848 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 4345 32 0 0
t$perf-norm:sp_cancel_supplier_order 450 450 0 0.00 0 19118 1499 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 4066 8 0 0
t$perf-norm:sp_client_order 21673 21673 0 0.00 0 5547 17 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 2450 8 0 0
t$perf-norm:sp_pay_from_customer 7407 7407 0 0.00 0 10051 9 0 0
t$perf-norm:sp_pay_to_supplier 6115 6115 0 0.00 0 2801 7 0 0
t$perf-norm:sp_reserve_write_off 10509 10509 0 0.00 0 4708 11 0 0
t$perf-norm:sp_supplier_invoice 5824 5824 0 0.00 0 6178 16 0 0
t$perf-norm:sp_supplier_order 5758 5758 0 0.00 0 6363 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 116 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 111 2 0 0
t$perf-norm:srv_make_invnt_saldo 644 644 0 0.00 0 560 13 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 1367 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10700 10410 290 2.71 0 4529 18 0 290
v_all_customers 39960 39960 0 0.00 0 21 1 0 0
v_all_suppliers 32158 32158 0 0.00 0 46 1 0 0
v_all_wares 43346 43346 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 2316 10 0 0
v_cancel_client_order 3828 3828 0 0.00 0 4817 9 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2617 11 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 1505 6 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1661 7 0 0
v_cancel_supplier_invoice 964 930 34 3.52 0 2455 17 0 34
v_cancel_supplier_order 900 900 0 0.00 0 997 4 0 0
v_cancel_write_off 2200 2200 0 0.00 0 4093 14 0 0
v_max_id_clo_ord 5757 5757 0 0.00 0 113 1 0 0
v_max_id_clo_res 1087 1087 0 0.00 0 4776 19 0 0
v_max_id_ord_sup 5812 5812 0 0.00 0 4294 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 397 3 0 0
v_max_non_paid_realizn 3777 3777 0 0.00 0 1626 3 0 0
v_min_id_clo_ord 5757 5757 0 0.00 0 21536 9 0 0
v_min_id_clo_res 1087 1087 0 0.00 0 4049 11 0 0
v_min_id_ord_sup 5812 5812 0 0.00 0 3180 4 0 0
v_min_non_paid_invoice 6115 6115 0 0.00 0 4497 14 0 0
v_min_non_paid_realizn 7407 7407 0 0.00 0 3766 14 0 0
v_our_firm 10646 10646 0 0.00 0 12 1 0 0
v_reserve_write_off 21018 21018 0 0.00 0 7096 8 0 0
x_lock_depdocs_on_canc_invoice 12201 12201 0 0.00 0 12780 101 0 0
x_lock_depdocs_on_canc_sup_ord 450 450 0 0.00 0 6961 311 0 0
x_make_qstorno_1000_1200 5758 5758 0 0.00 2 39715 1238 0 0
x_make_qstorno_1200_2000 5824 5824 0 0.00 4 62803 2995 0 0
x_make_qstorno_2100_3300 480734 480734 0 0.00 0 85534 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 19:38:42 to 23-08-2021 19:38:46.

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:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:51 21554 8192000000 10355281872 10668871680 1409237104 14.677 100 88 176 1 1444250864 6061232 28952528
17:10:21 13776 8192000000 10414570448 10739548160 1466667344 15.184 100 88 176 0 1503691248 8394800 28629104
17:11:41 22677 8192000000 10464541312 10812964864 1517447984 15.628 100 73 145 1 1549732960 9004736 23280240
17:13:13 15190 8192000000 10548826832 10907115520 1615560880 16.472 100 83 167 0 1651669968 10818432 25290656
17:14:31 6154 8192000000 10649019200 11021152256 1709490640 17.264 100 77 152 0 1744109152 12784592 21833920
17:15:41 3480 8192000000 10701226928 11081568256 1767018416 17.742 100 90 176 2 1806908640 15366992 24523232
17:16:46 2582 8192000000 10759165936 11145486336 1822344240 18.197 100 86 169 0 1858033408 11234224 24454944
17:17:50 1574 8192000000 10811938576 11198107648 1866623632 18.557 100 91 179 3 1905227232 12230224 26373376
17:18:53 36794 8192000000 10809687584 11216994304 1878619824 18.654 100 56 112 2 1901124608 6083632 16421152
17:20:31 6952 8192000000 10789798464 11222384640 1882093328 18.682 100 25 50 0 1895604144 6178016 7332800
17:21:41 10495 8192000000 10809582928 11239428096 1895461056 18.790 100 21 42 4 1908052832 5770336 6821440
17:22:57 8132 8192000000 10803675472 11240767488 1895492640 18.790 100 7 14 0 1902936416 4956832 2486944
17:24:07 28220 8192000000 10810130000 11255025664 1907622368 18.888 100 30 60 2 1922781088 5298544 9860176
17:25:36 15615 8192000000 10804534128 11248058368 1885985536 18.713 99 15 30 0 1896127488 5572272 4569680
17:26:53 11889 8192000000 10800139760 11243048960 1882491488 18.685 99 22 43 1 1894706848 5720736 6494624
17:28:06 8355 8192000000 10730163648 11160358912 1774607216 17.805 96 19 39 0 1785942336 5361328 5973792
17:29:16 9085 8192000000 10712456784 11121688576 1750583360 17.606 96 31 62 3 1766624432 6277504 9763568
17:30:26 35682 8192000000 10696457616 11114065920 1675926432 16.983 92 21 42 1 1687078896 5102656 6049808
17:32:02 10289 8192000000 10726701536 11150204928 1791804384 17.947 98 27 54 2 1804787760 5843568 7139808
17:33:13 13277 8192000000 10766127152 11189661696 1846780400 18.396 100 23 44 3 1858277520 5038560 6458560
17:34:26 72391 8192000000 10786073600 11214594048 1869648960 18.581 100 23 44 1 1881389552 4840640 6899952
17:36:42 6227 8192000000 10794099248 11230461952 1879220432 18.659 100 27 53 0 1893097888 5947632 7929824
17:37:50 5700 8192000000 10853425568 11259506688 1916760272 18.961 100 57 118 1 1938357552 4595184 17002096
17:39:07 70168 8192000000 10864808336 11304140800 1942801984 19.169 100 36 72 2 1965374320 11858208 10714128
17:41:29 25089 8192000000 10897651136 11321868288 1942105808 19.164 99 41 82 2 1971932496 17388528 12438160
17:42:58 19595 8192000000 10908622832 11343192064 1969091232 19.378 100 54 106 0 2000892656 15828544 15972880
17:44:22 19793 8192000000 10927657376 11361505280 1978494896 19.453 100 42 84 0 2013750640 22701664 12554080
17:45:53 14774 8192000000 10915835664 11356786688 1967121184 19.363 99 46 91 2 2000081632 19178544 13781904
17:47:15 15630 8192000000 10938367280 11374493696 1993059168 19.568 100 49 95 1 2030904896 23318048 14527680
17:48:37 4744 8192000000 10936787888 11364134912 1973817808 19.416 100 60 120 0 2022423840 30161888 18444144
17:49:46 12336 8192000000 10955065456 11387424768 1991096896 19.552 100 57 114 1 2041499792 32614720 17788176
17:51:06 7389 8192000000 10936464352 11370311680 1966627760 19.359 99 55 110 0 2015874128 31054928 18191440
17:52:19 4015 8192000000 10932126384 11349159936 1935798688 19.113 98 71 142 1 1994874752 35425808 23650256
17:53:24 2107 8192000000 10940450240 11351937024 1941738128 19.161 99 80 155 1 2005023984 38362528 24923328
17:54:34 4202 8192000000 10945124752 11349495808 1952884304 19.249 100 86 172 0 2023659552 44073392 26701856
17:55:48 3397 8192000000 10876526000 11306438656 1904389872 18.862 99 48 96 2 1955223008 34754384 16078752
17:57:10 17990 8192000000 10878327360 11319676928 1913752976 18.937 99 31 62 1 1955148944 31561776 9834192
17:58:31 8539 8192000000 10869261632 11291914240 1878934000 18.656 98 55 109 2 1938673056 41702368 18036688
17:59:44 10731 8192000000 10894160848 11329720320 1925387344 19.030 100 46 92 1 1978200336 38067312 14745680
18:01:00 10464 8192000000 10871680800 11305742336 1905011872 18.867 99 41 81 3 1947823840 30204656 12607312
18:02:13 24834 8192000000 10899091984 11352870912 1945912144 19.194 100 30 60 1 1993006048 37033360 10060544
18:03:47 12550 8192000000 10930589312 11393548288 1971837600 19.400 100 30 60 2 2025185776 43125536 10222640
18:05:26 18814 8192000000 10953696336 11404480512 1982610736 19.485 100 36 72 3 2033239168 39094528 11533904
18:06:48 35896 8192000000 10940008512 11393159168 1963343936 19.333 99 23 45 2 2012041648 41247568 7450144
18:08:33 27772 8192000000 10924742096 11386900480 1952553760 19.247 100 14 28 1 2008768240 51129824 5084656
18:10:02 101784 8192000000 10912516784 11368357888 1919632640 18.984 97 9 18 0 1937827424 15927680 2267104
18:13:03 51949 8192000000 10935112048 11369025536 1978615904 19.454 100 38 75 0 1999867440 11507248 9744288
18:14:56 68883 8192000000 10922398736 11381293056 1962992496 19.330 99 12 22 1 1979807424 12874912 3940016
18:17:06 69196 8192000000 10938816160 11393609728 1995899376 19.590 100 19 38 3 2011400560 10394320 5106864
18:19:17 117237 8192000000 10947280656 11409625088 2002013824 19.639 100 12 24 0 2024802288 19535856 3252608
18:22:38 42547 8192000000 10948059744 11403313152 1973530256 19.413 98 35 70 1 1994321920 10996576 9795088
18:24:30 77759 8192000000 10931834128 11398221824 1986068800 19.513 99 25 46 4 2005473360 12516464 6888096
18:26:49 20614 8192000000 10945050192 11403554816 1994382464 19.578 99 28 57 2 2009795648 6696944 8716240
18:28:11 11396 8192000000 10954530224 11394686976 1985547824 19.509 99 72 140 1 2017291296 11173488 20569984
18:29:29 58489 8192000000 10911597152 11380727808 1953827152 19.257 98 30 61 2 1971290592 9420368 8043072
18:31:32 42957 8192000000 10868927968 11349028864 1914085024 18.939 97 11 22 0 1924780848 7554976 3140848
18:33:18 60960 8192000000 10827033168 11300204544 1876313696 18.635 96 13 26 0 1889125648 8876144 3935808
18:35:34 61783 8192000000 10865158512 11362459648 1950281440 19.229 100 24 48 0 1972000720 15001872 6717408
18:37:42 42584 8192000000 10859066752 11332001792 1857858112 18.486 95 9 18 0 1869977936 9773360 2346464
18:39:25 36663 8192000000 10844358512 11306737664 1789442304 17.927 92 20 40 2 1802417808 7703760 5271744
18:41:04 44590 8192000000 10862466368 11318628352 1878933264 18.656 97 25 50 2 1895362592 9511584 6917744
18:42:49 39392 8192000000 10850317872 11319382016 1877822656 18.648 97 14 28 1 1890293872 8542608 3928608
18:44:38 58839 8192000000 10862597120 11332120576 1884389184 18.701 97 17 34 2 1898608000 9289184 4929632
18:47:11 30372 8192000000 10895186784 11359608832 1960328800 19.309 100 31 62 2 1978851824 9694400 8828624
18:48:41 55742 8192000000 10872989840 11342655488 1850716128 18.428 94 13 26 1 1863074960 9019072 3339760
18:50:37 47435 8192000000 10878467264 11362996224 1933386384 19.094 99 16 32 2 1959120224 21092400 4641440
18:52:33 122087 8192000000 10872801488 11353067520 1920965696 18.995 98 21 42 2 1937558224 11312288 5280240
18:55:39 29263 8192000000 10906653280 11374624768 1969244176 19.379 100 39 78 0 1996247056 16390144 10612736
18:57:09 65940 8192000000 10893469984 11378909184 1953766640 19.256 99 19 38 0 1971753824 12717760 5269424
18:59:15 61473 8192000000 10895491328 11383554048 1975120320 19.426 100 30 60 1 1997362400 13686688 8555392
19:02:42 97651 8192000000 10897824352 11389497344 1955904960 19.273 99 16 32 0 1975799536 15091200 4803376
19:05:29 38570 8192000000 10913056688 11382530048 1972289232 19.404 100 17 33 1 1984666768 8043664 4333872
19:07:19 29420 8192000000 10921791552 11399258112 1989671696 19.541 100 30 60 0 2008010336 9980096 8358544
19:08:52 47523 8192000000 10900692432 11369877504 1932880848 19.090 97 24 48 0 1947762512 8483664 6398000
19:10:40 76233 8192000000 10929714784 11414396928 1980535504 19.469 99 11 22 1 1994611392 10831824 3244064
19:13:05 84745 8192000000 10915643424 11397263360 1918745392 18.977 96 16 32 3 1937983056 14593872 4643792
19:15:30 67452 8192000000 10905199456 11384688640 1954658256 19.264 99 13 26 1 1972068048 13781136 3628656
19:17:51 80000 8192000000 10861768992 11336069120 1831110256 18.268 93 5 9 1 1847511696 15464064 937376
19:20:11 150401 8192000000 10898688640 11375886336 1884165712 18.699 95 12 24 0 1908599024 20863344 3569968
19:23:43 22316 8192000000 10875495376 11363606528 1955747184 19.272 100 9 18 0 1966608480 7438352 3422944
19:25:25 64020 8192000000 10855511472 11341058048 1886910912 18.721 97 9 18 0 1895600880 6200672 2489296
19:27:34 46446 8192000000 10875515360 11369603072 1958567456 19.295 100 14 28 0 1977969904 14857056 4545392
19:29:33 75891 8192000000 10849806208 11334422528 1845389792 18.385 95 13 26 1 1867564464 17795904 4378768
19:31:54 30793 8192000000 10866180096 11331497984 1913214944 18.932 99 30 60 1 1938320480 16258352 8847184
19:33:31 68018 8192000000 10838104368 11324760064 1854012720 18.455 96 19 38 0 1878330624 18692912 5624992
19:35:53 67194 8192000000 10875284960 11330162688 1899157376 18.820 98 21 39 2 1915899776 11298448 5443952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-08-2021 19:38:48 to 23-08-2021 19:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 145 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-08-2021 19:39:03 to 23-08-2021 19:39:05.

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          250975
MON$OLDEST_ACTIVE               252970
MON$OLDEST_SNAPSHOT             252970
MON$NEXT_TRANSACTION            252970
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                       2062368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:347: 
:348: mscs-test-161.budzdorov.ru    Mon Aug 23 19:37:49 2021
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:350: 
:351: 
:352: mscs-test-161.budzdorov.ru    Mon Aug 23 19:37:53 2021
:353:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:354: 
:355: 
:356: mscs-test-161.budzdorov.ru    Mon Aug 23 19:37:54 2021
:357:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:358: 
:359: 
:360: mscs-test-161.budzdorov.ru    Mon Aug 23 19:37:56 2021
:361:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:362: 
--- end of diff output ---

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

23.08.21 19:39:06. 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
2021-08-23 19:39:09 No severe PSQL-related problems occured
23.08.21 19:39:10. End of report.