Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2021 01: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 16160756 kB
MemAvailable 30693112 kB
Buffers 0 kB
Cached 15517288 kB
SwapCached 4784 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-10-06 01:37:49
DTS_END                         2021-10-06 01:37:49.6810
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/47306
TRN_ID                          236847
ATT_ID                          701
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: 9165.70 21590 1099884
customer order: creation 170.18 359 20421
customer order: refuse 14.21 347 1705
order to supplier: creation 45.66 1370 5479
order to supplier: removal 3.33 98848 400
invoice (draft): creation 45.03 3341 5404
invoice (draft): removal 52.35 740 6282
invoice accept: apply 41.79 18043 5015
invoice accept: cancel 47.39 845 5687
customer reserve: creation 3806.55 181 456786
customer reserve: removal 4640.88 56 556905
realization accept: apply 83.09 180 9971
realization accept: cancel 86.40 104 10368
payment from customer: creation 58.93 113 7071
payment from customer: removal 5.73 70 687
payment to supplier: creation 48.28 126 5793
payment to supplier: removal 3.47 72 416
service: total inventory turnovers 4.75 1071 570
service: total monetary turnovers 6.89 24 827
service: aggregate perf. data 0.78 19856 93
service: refresh index statistics 0.03 286057 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2021 01:38:32 to 06-10-2021 01:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3344
TEST_TIME 2 3936
TEST_TIME 3 3500
TEST_TIME 4 4615
TEST_TIME 5 5643
TEST_TIME 6 6319
TEST_TIME 7 6561
TEST_TIME 8 6318
TEST_TIME 9 6182
TEST_TIME 10 6216
TEST_TIME 11 6312
TEST_TIME 12 6715
TEST_TIME 13 6876
TEST_TIME 14 7130
TEST_TIME 15 7116
TEST_TIME 16 6922
TEST_TIME 17 6949
TEST_TIME 18 7004
TEST_TIME 19 7420
TEST_TIME 20 7621
TEST_TIME 21 7960
TEST_TIME 22 8432
TEST_TIME 23 8653
TEST_TIME 24 8875
TEST_TIME 25 8668
TEST_TIME 26 8802
TEST_TIME 27 8956
TEST_TIME 28 9437
TEST_TIME 29 9459
TEST_TIME 30 9542
TEST_TIME 31 9403
TEST_TIME 32 9465
TEST_TIME 33 9363
TEST_TIME 34 9795
TEST_TIME 35 9798
TEST_TIME 36 9974
TEST_TIME 37 9894
TEST_TIME 38 9878
TEST_TIME 39 9766
TEST_TIME 40 9644
TEST_TIME 41 9534
TEST_TIME 42 9349
TEST_TIME 43 9307
TEST_TIME 44 9265
TEST_TIME 45 9250
TEST_TIME 46 9176
TEST_TIME 47 9051
TEST_TIME 48 9011
TEST_TIME 49 8915
TEST_TIME 50 8888
TEST_TIME 51 8816
TEST_TIME 52 9015
TEST_TIME 53 9007
TEST_TIME 54 8966
TEST_TIME 55 8912
TEST_TIME 56 8779
TEST_TIME 57 8797
TEST_TIME 58 8763
TEST_TIME 59 8696
TEST_TIME 60 8764
TEST_TIME 61 8837
TEST_TIME 62 8826
TEST_TIME 63 8858
TEST_TIME 64 8875
TEST_TIME 65 8969
TEST_TIME 66 8961
TEST_TIME 67 9092
TEST_TIME 68 9090
TEST_TIME 69 9029
TEST_TIME 70 9077
TEST_TIME 71 9116
TEST_TIME 72 9111
TEST_TIME 73 9265
TEST_TIME 74 9266
TEST_TIME 75 9152
TEST_TIME 76 9124
TEST_TIME 77 9117
TEST_TIME 78 9070
TEST_TIME 79 9044
TEST_TIME 80 9047
TEST_TIME 81 9053
TEST_TIME 82 9034
TEST_TIME 83 8990
TEST_TIME 84 8921
TEST_TIME 85 9053
TEST_TIME 86 9002
TEST_TIME 87 9044
TEST_TIME 88 9070
TEST_TIME 89 9066
TEST_TIME 90 9104
TEST_TIME 91 9104
TEST_TIME 92 9143
TEST_TIME 93 9178
TEST_TIME 94 9187
TEST_TIME 95 9208
TEST_TIME 96 9233
TEST_TIME 97 9226
TEST_TIME 98 9194
TEST_TIME 99 9194
TEST_TIME 100 9205
TEST_TIME 101 9168
TEST_TIME 102 9124
TEST_TIME 103 9105
TEST_TIME 104 9124
TEST_TIME 105 9096
TEST_TIME 106 9079
TEST_TIME 107 9046
TEST_TIME 108 9028
TEST_TIME 109 9065
TEST_TIME 110 9059
TEST_TIME 111 9048
TEST_TIME 112 9035
TEST_TIME 113 9020
TEST_TIME 114 9076
TEST_TIME 115 9070
TEST_TIME 116 9059
TEST_TIME 117 9162
TEST_TIME 118 9127
TEST_TIME 119 9158
TEST_TIME 120 9170

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-10-2021 01:38:35 to 06-10-2021 01:38:39.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1521662 1521662 0 0.00 0 15665 6 0 0
doc_list_biud 1521660 1521660 0 0.00 0 48096 26 0 0
sp_add_invoice_to_stock 5124 5015 109 2.12 33 183073 18044 0 109
sp_cancel_adding_invoice 5687 5687 0 0.00 5 56430 846 0 0
sp_cancel_client_order 1705 1705 0 0.00 5 7876 348 0 0
sp_cancel_customer_reserve 556905 556905 0 0.00 1 48098 56 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 7179 71 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 2959 73 0 0
sp_cancel_supplier_invoice 6291 6282 9 0.14 1 16042 740 0 9
sp_cancel_supplier_order 400 400 0 0.00 9 857065 98849 0 0
sp_cancel_write_off 10368 10368 0 0.00 1 13900 104 0 0
sp_client_order 20421 20421 0 0.00 4 24251 360 0 0
sp_customer_reserve 456786 456786 0 0.00 0 79777 182 0 0
sp_fill_shopping_cart 31301 31301 0 0.00 1 16459 37 0 0
sp_fill_shopping_cart_clo_res 456777 456777 0 0.00 0 11733 14 0 0
sp_get_clo_for_invoice 5004 5004 0 0.00 0 28537 298 0 0
sp_kill_cost_storno 17158 17158 0 0.00 0 9184 51 0 0
sp_kill_qstorno_ret_qs2qd 1130860 1130860 0 0.00 0 48094 30 0 0
sp_kill_qty_storno 596322 596322 0 0.00 0 48096 66 0 0
sp_lock_selected_doc 20689 20689 0 0.00 0 11140 6 0 0
sp_make_cost_storno 27839 27839 0 0.00 0 10409 56 0 0
sp_multiply_rows_for_pdistr 27839 27839 0 0.00 0 15660 28 0 0
sp_multiply_rows_for_qdistr 20421 20421 0 0.00 0 13355 161 0 0
sp_pay_from_customer 7071 7071 0 0.00 4 15673 114 0 0
sp_pay_to_supplier 5793 5793 0 0.00 4 12322 126 0 0
sp_payment_common 12864 12864 0 0.00 1 10938 26 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 7869 317 0 0
sp_qd_handle_on_invoice_upd_sts 10691 10691 0 0.00 0 31438 340 0 0
sp_qd_handle_on_reserve_upd_sts 9971 9971 0 0.00 0 16656 65 0 0
sp_reserve_write_off 9971 9971 0 0.00 6 16675 181 0 0
sp_supplier_invoice 5404 5404 0 0.00 6 90057 3341 0 0
sp_supplier_order 5479 5479 0 0.00 6 47486 1371 0 0
srv_aggregate_perf_data 94 93 1 1.06 3120 251577 19856 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1583 145638 46282 0 0
srv_find_qd_qs_mism 1052012 1052012 0 0.00 0 10195 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 51 68961 1072 0 0
srv_make_money_saldo 827 827 0 0.00 1 2287 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 130161 535566 286058 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5015 5015 0 0.00 0 8548 175 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 5746 89 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 19371 35 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 1437 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2696 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1013 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 5641 43 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 33009 1811 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 2752 10 0 0
t$perf-norm:sp_client_order 20421 20421 0 0.00 0 8064 17 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 1927 13 0 0
t$perf-norm:sp_pay_from_customer 7071 7071 0 0.00 0 5558 10 0 0
t$perf-norm:sp_pay_to_supplier 5793 5793 0 0.00 0 6718 9 0 0
t$perf-norm:sp_reserve_write_off 9971 9971 0 0.00 0 19436 13 0 0
t$perf-norm:sp_supplier_invoice 5404 5404 0 0.00 0 6539 20 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 4966 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 477 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 1131 19 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 2206 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10222 10004 218 2.13 0 7057 18 0 218
v_all_customers 37230 37230 0 0.00 0 61 1 0 0
v_all_suppliers 29980 29980 0 0.00 0 34 1 0 0
v_all_wares 40840 40840 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 4761 13 0 0
v_cancel_client_order 3410 3410 0 0.00 0 4307 7 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2487 5 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2993 7 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2481 8 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 2634 21 0 18
v_cancel_supplier_order 800 800 0 0.00 0 5203 17 0 0
v_cancel_write_off 1934 1934 0 0.00 0 1590 14 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 4102 2 0 0
v_max_id_clo_res 996 996 0 0.00 0 1282 9 0 0
v_max_id_ord_sup 5402 5402 0 0.00 0 3727 3 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 2419 4 0 0
v_max_non_paid_realizn 3824 3824 0 0.00 0 2985 4 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 5209 7 0 0
v_min_id_clo_res 996 996 0 0.00 0 2761 11 0 0
v_min_id_ord_sup 5402 5402 0 0.00 0 6269 8 0 0
v_min_non_paid_invoice 5793 5793 0 0.00 0 5281 14 0 0
v_min_non_paid_realizn 7071 7071 0 0.00 0 5003 13 0 0
v_our_firm 10104 10104 0 0.00 0 11 1 0 0
v_reserve_write_off 19942 19942 0 0.00 0 4353 7 0 0
x_lock_depdocs_on_canc_invoice 11551 11551 0 0.00 0 15334 117 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 8505 367 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 2 47477 1287 0 0
x_make_qstorno_1200_2000 5404 5404 0 0.00 3 90035 3218 0 0
x_make_qstorno_2100_3300 456786 456786 0 0.00 0 79776 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2021 01:38:40 to 06-10-2021 01:38:44.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:53 179 8192000000 8877066880 8905728000 38716032 0.470 4 4 6 1 39071696 93968 261696
23:08:54 32890 8192000000 10361431936 10684690432 1423594944 14.805 100 85 170 0 1457329248 6272896 27461408
23:10:32 30855 8192000000 10456720768 10786840576 1499115952 15.468 100 94 188 1 1538379520 9350096 29913472
23:12:12 22840 8192000000 10509377392 10855989248 1558246480 15.981 100 89 176 1 1596763392 11243552 27273360
23:13:50 14073 8192000000 10592268144 10956931072 1658323824 16.835 100 80 158 1 1693997824 12412528 23261472
23:15:07 5551 8192000000 10655439168 11029594112 1715611872 17.316 100 76 152 1 1749813392 12680000 21521520
23:16:15 4871 8192000000 10718179504 11102908416 1780819968 17.856 100 83 166 1 1818013472 13785872 23407632
23:17:22 3030 8192000000 10773917392 11160502272 1830061920 18.260 100 81 162 1 1866613424 13749504 22802000
23:18:28 1548 8192000000 10805465776 11194159104 1863958960 18.535 100 93 188 2 1903837696 13043136 26835600
23:19:31 30824 8192000000 10807711104 11214270464 1882427744 18.685 100 64 128 0 1908395904 7311600 18656560
23:21:03 5812 8192000000 10803708256 11223429120 1889746224 18.744 100 39 78 2 1907947696 7244784 10956688
23:22:10 11757 8192000000 10800828704 11232755712 1893562272 18.774 100 19 38 3 1904493776 5779808 5151696
23:23:24 7213 8192000000 10818933728 11252088832 1905904528 18.874 100 19 38 0 1917681952 6335472 5441952
23:24:40 76344 8192000000 10857431392 11261689856 1914035104 18.939 100 46 91 1 1931794368 4550496 13208768
23:26:56 6899 8192000000 10836837008 11270524928 1917799920 18.969 100 26 52 0 1930614192 5390608 7423664
23:28:07 6093 8192000000 10813180160 11253014528 1905851872 18.873 100 24 48 1 1919578448 6869040 6857536
23:29:14 7204 8192000000 10758293536 11180838912 1833230768 18.286 99 31 62 4 1849010048 5020464 10758816
23:30:26 7799 8192000000 10713257072 11133878272 1732638720 17.457 95 18 36 0 1742828432 5178448 5011264
23:31:38 18134 8192000000 10678819456 11096338432 1684113056 17.052 94 16 32 1 1693602032 5340032 4148944
23:32:57 8974 8192000000 10712265056 11119677440 1758062112 17.668 98 32 64 2 1772945824 6371920 8511792
23:34:10 8474 8192000000 10754638448 11170725888 1822865712 18.201 99 22 44 1 1834619024 4868464 6884848
23:35:20 56056 8192000000 10775537392 11203231744 1862287984 18.522 100 56 112 1 1885545664 6914032 16343648
23:37:19 5418 8192000000 10813884752 11234844672 1890729824 18.752 100 29 55 0 1908470864 9798448 7942592
23:38:27 12622 8192000000 10839405024 11263647744 1916871184 18.962 100 52 105 1 1943710512 11609344 15229984
23:39:45 12295 8192000000 10860472544 11288625152 1932076080 19.083 100 45 89 2 1953677120 8958512 12642528
23:41:02 37280 8192000000 10889325184 11305308160 1944533456 19.183 100 51 100 2 1970388064 11042432 14812176
23:42:50 2307 8192000000 10909357264 11324026880 1958486688 19.294 100 66 131 1 1990767312 13387184 18893440
23:43:57 10944 8192000000 10916698352 11339354112 1970793728 19.392 100 61 122 1 2004853216 16512976 17546512
23:45:13 5609 8192000000 10904691392 11346649088 1975441424 19.429 100 34 68 2 1997769200 12379232 9948544
23:46:24 17710 8192000000 10934265488 11371393024 1995508480 19.587 100 50 100 0 2026620320 16573568 14538272
23:47:50 7129 8192000000 10950050288 11378741248 1996969776 19.599 100 51 102 0 2031376064 19011264 15395024
23:49:05 3919 8192000000 10939181824 11370917888 1984288816 19.499 99 70 141 3 2029448096 24370624 20788656
23:50:11 1582 8192000000 10942808752 11360919552 1976304096 19.435 99 79 154 3 2024889072 24967488 23617488
23:51:18 3638 8192000000 10928652576 11347935232 1969623776 19.382 100 77 154 1 2016338320 23836192 22878352
23:52:30 15080 8192000000 10905087760 11328884736 1909398144 18.902 97 56 112 3 1950261968 23784768 17079056
23:54:10 12385 8192000000 10916796464 11352047616 1959056144 19.299 100 34 68 1 2000659600 30642096 10961360
23:55:26 6383 8192000000 10947529456 11375849472 1976200928 19.435 100 57 114 0 2028485872 34792224 17492720
23:56:40 2104 8192000000 10956266592 11374014464 1974633536 19.422 100 84 168 1 2041545856 40950608 25961712
23:57:50 11094 8192000000 10889047328 11332603904 1919168624 18.980 99 43 86 0 1973900496 40614080 14117792
23:59:13 16168 8192000000 10874021984 11313139712 1862741280 18.525 96 29 57 2 1913192064 40538800 9911984
00:00:41 19439 8192000000 10871165216 11313291264 1873895248 18.616 97 24 48 0 1916257328 34659536 7702544
00:02:03 17395 8192000000 10883226640 11323617280 1928480672 19.055 100 28 56 1 1964368704 26963072 8924960
00:03:24 31597 8192000000 10907725952 11354292224 1940501536 19.151 100 28 56 1 1993755280 43498720 9755024
00:04:58 14771 8192000000 10902963248 11346022400 1933072960 19.091 100 32 64 2 1985366224 41022720 11270544
00:06:20 27081 8192000000 10913348320 11349835776 1933774096 19.097 99 35 70 2 1981488848 36559712 11155040
00:07:49 20919 8192000000 10911644832 11348922368 1919647840 18.984 98 39 76 3 1962087856 30583312 11856704
00:09:13 62906 8192000000 10884835216 11337834496 1921174880 18.996 99 24 48 2 1963655376 34116992 8363504
00:11:47 55492 8192000000 10850229184 11301834752 1889255232 18.740 98 19 38 0 1917982384 22796592 5930560
00:13:45 49507 8192000000 10877090928 11323768832 1941450368 19.158 100 24 48 0 1957339392 9510400 6378624
00:17:21 25107 8192000000 10874914768 11318345728 1940714176 19.152 99 41 81 1 1958147616 6289712 11143728
00:18:59 94870 8192000000 10891395488 11348594688 1961400528 19.317 100 40 78 0 1984789712 12155376 11233808
00:21:40 100284 8192000000 10883432656 11355623424 1950015440 19.227 99 17 34 0 1969325024 14697424 4612160
00:24:28 28661 8192000000 10883423360 11334537216 1903678928 18.856 96 26 52 0 1921702144 10738624 7284592
00:26:07 57269 8192000000 10928115392 11386028032 1986127872 19.513 100 19 38 2 2006933152 15126128 5679152
00:28:05 45025 8192000000 10930718752 11391156224 1979753376 19.463 99 30 60 0 2010505472 22000832 8751264
00:29:51 54781 8192000000 10905060832 11381526528 1968278656 19.372 99 9 18 0 1989231088 18214320 2738112
00:31:46 39497 8192000000 10886843856 11349786624 1956372096 19.277 99 27 54 1 1971101504 7263536 7465872
00:33:28 85343 8192000000 10876090656 11342729216 1914499408 18.943 97 9 18 1 1924117216 7356656 2261152
00:35:57 71749 8192000000 10890223376 11365646336 1938922864 19.138 98 11 22 0 1954022288 12037824 3061600
00:39:15 54515 8192000000 10888229536 11353710592 1934216496 19.101 98 13 26 1 1946446800 8926576 3303728
00:41:11 65217 8192000000 10892905952 11377205248 1939935024 19.146 98 11 22 1 1959718656 16854320 2929312
00:43:22 30458 8192000000 10890239840 11341570048 1900674416 18.832 96 33 66 0 1922081712 11264992 10142304
00:44:57 145638 8192000000 10845131344 11299917824 1717270992 17.329 88 11 22 1 1733744112 13507920 2965200
00:48:23 81172 8192000000 10863647952 11320393728 1864336048 18.538 96 25 50 2 1888450240 17196096 6918096
00:50:51 82851 8192000000 10831576032 11293208576 1865183136 18.545 97 11 21 1 1880351856 12098432 3070288
00:53:15 57560 8192000000 10844502576 11302842368 1867796832 18.566 97 19 39 0 1885525232 12225360 5503040
00:55:15 45745 8192000000 10836608368 11287326720 1876049664 18.633 97 41 82 1 1900061680 12024080 11987936
00:57:14 58980 8192000000 10842874016 11316768768 1903160128 18.852 99 12 24 0 1924766416 18406832 3199456
00:59:27 51942 8192000000 10861769616 11326857216 1939522064 19.143 100 10 20 0 1954029456 11467728 3039664
01:01:19 34348 8192000000 10869234528 11342774272 1944972544 19.186 100 10 20 1 1962797840 14541408 3283888
01:03:09 46327 8192000000 10867153248 11327332352 1923731968 19.017 99 22 44 0 1939943968 10053360 6158640
01:04:57 86063 8192000000 10869062688 11335544832 1874510448 18.621 96 12 24 0 1886843616 9177104 3156064
01:07:27 101676 8192000000 10885065632 11344216064 1903390656 18.854 97 10 20 0 1918906496 12965680 2550160
01:10:14 80328 8192000000 10891000192 11349200896 1915504880 18.951 97 20 40 0 1937014480 16346032 5163568
01:12:36 29083 8192000000 10905348352 11355004928 1954164816 19.260 99 36 71 0 1974925488 10816256 9944416
01:14:06 30184 8192000000 10942984400 11378417664 1979193872 19.458 100 38 76 1 2003799360 14901712 9703776
01:15:37 31244 8192000000 10951747808 11394875392 1977672688 19.446 99 45 90 0 2002438032 12325328 12440016
01:17:11 135633 8192000000 10912960752 11376619520 1924914144 19.026 97 22 44 1 1941356512 10416864 6025504
01:20:28 80547 8192000000 10912247248 11393720320 1993688048 19.573 100 17 34 0 2014835104 16178576 4968480
01:22:52 63570 8192000000 10892020480 11364700160 1913163600 18.932 96 11 22 0 1927076672 10743824 3169248
01:25:07 103847 8192000000 10872370112 11360665600 1910194640 18.908 97 8 16 1 1928190896 15487040 2509216
01:27:51 31100 8192000000 10880652400 11345526784 1928096240 19.052 98 28 56 1 1944877968 8999200 7782528
01:29:41 84715 8192000000 10872914080 11339636736 1874611760 18.622 95 19 38 0 1892363472 11960320 5791392
01:32:07 63647 8192000000 10910204160 11371839488 1956831472 19.281 99 25 50 0 1978133856 14055648 7246736
01:34:14 56038 8192000000 10906082560 11372691456 1939407744 19.142 98 12 24 2 1956357040 13404848 3544448
01:36:14 103299 8192000000 10337605808 10753425408 1413832448 14.718 71 13 24 0 1428018880 11588528 2597904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-10-2021 01:38:46 to 06-10-2021 01:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 109 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2021 01:39:01 to 06-10-2021 01:39:03.

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          235855
MON$OLDEST_ACTIVE               237506
MON$OLDEST_SNAPSHOT             237506
MON$NEXT_TRANSACTION            237506
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                       2009088
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:285: 
:286: mscs-test-161.budzdorov.ru    Wed Oct  6 01:38:04 2021
:287:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:288: 
--- end of diff output ---

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

06.10.21 01:39:04. 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-10-06 01:39:07 No severe PSQL-related problems occured
06.10.21 01:39:08. End of report.