Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.08.2021 16: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 15974280 kB
MemAvailable 30981124 kB
Buffers 0 kB
Cached 15720824 kB
SwapCached 3644 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-22 16:37:45
DTS_END                         2021-08-22 16:37:45.8400
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/57046
TRN_ID                          248082
ATT_ID                          708
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: 9309.49 21495 1117139
customer order: creation 180.43 342 21651
customer order: refuse 15.86 388 1903
order to supplier: creation 47.62 1402 5714
order to supplier: removal 3.34 107554 401
invoice (draft): creation 48.38 3374 5806
invoice (draft): removal 51.98 755 6237
invoice accept: apply 43.27 18610 5192
invoice accept: cancel 46.72 944 5606
customer reserve: creation 3944.86 187 473383
customer reserve: removal 4619.91 62 554389
realization accept: apply 87.71 197 10525
realization accept: cancel 83.69 117 10043
payment from customer: creation 62.47 128 7496
payment from customer: removal 6.05 83 726
payment to supplier: creation 51.51 150 6181
payment to supplier: removal 3.47 71 416
service: total inventory turnovers 4.58 1182 549
service: total monetary turnovers 6.68 34 802
service: aggregate perf. data 0.96 17659 115
service: refresh index statistics 0.03 276670 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-08-2021 16:39:17 to 22-08-2021 16:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5903
TEST_TIME 2 5751
TEST_TIME 3 5995
TEST_TIME 4 6544
TEST_TIME 5 5920
TEST_TIME 6 5588
TEST_TIME 7 5889
TEST_TIME 8 5837
TEST_TIME 9 6497
TEST_TIME 10 6363
TEST_TIME 11 6170
TEST_TIME 12 6183
TEST_TIME 13 6328
TEST_TIME 14 6643
TEST_TIME 15 6789
TEST_TIME 16 6961
TEST_TIME 17 7186
TEST_TIME 18 7353
TEST_TIME 19 7818
TEST_TIME 20 8133
TEST_TIME 21 8252
TEST_TIME 22 8175
TEST_TIME 23 8315
TEST_TIME 24 8300
TEST_TIME 25 8395
TEST_TIME 26 8350
TEST_TIME 27 8706
TEST_TIME 28 9054
TEST_TIME 29 9197
TEST_TIME 30 9721
TEST_TIME 31 9797
TEST_TIME 32 10028
TEST_TIME 33 10184
TEST_TIME 34 10347
TEST_TIME 35 10466
TEST_TIME 36 10393
TEST_TIME 37 10270
TEST_TIME 38 10045
TEST_TIME 39 9885
TEST_TIME 40 9920
TEST_TIME 41 10090
TEST_TIME 42 10356
TEST_TIME 43 10260
TEST_TIME 44 10165
TEST_TIME 45 10105
TEST_TIME 46 10091
TEST_TIME 47 10101
TEST_TIME 48 9955
TEST_TIME 49 9798
TEST_TIME 50 9805
TEST_TIME 51 9771
TEST_TIME 52 9655
TEST_TIME 53 9690
TEST_TIME 54 9640
TEST_TIME 55 9653
TEST_TIME 56 9580
TEST_TIME 57 9572
TEST_TIME 58 9517
TEST_TIME 59 9505
TEST_TIME 60 9446
TEST_TIME 61 9447
TEST_TIME 62 9570
TEST_TIME 63 9579
TEST_TIME 64 9532
TEST_TIME 65 9606
TEST_TIME 66 9642
TEST_TIME 67 9609
TEST_TIME 68 9640
TEST_TIME 69 9570
TEST_TIME 70 9563
TEST_TIME 71 9698
TEST_TIME 72 9689
TEST_TIME 73 9689
TEST_TIME 74 9648
TEST_TIME 75 9691
TEST_TIME 76 9708
TEST_TIME 77 9676
TEST_TIME 78 9604
TEST_TIME 79 9552
TEST_TIME 80 9535
TEST_TIME 81 9539
TEST_TIME 82 9517
TEST_TIME 83 9517
TEST_TIME 84 9515
TEST_TIME 85 9481
TEST_TIME 86 9435
TEST_TIME 87 9408
TEST_TIME 88 9383
TEST_TIME 89 9419
TEST_TIME 90 9435
TEST_TIME 91 9444
TEST_TIME 92 9450
TEST_TIME 93 9469
TEST_TIME 94 9488
TEST_TIME 95 9482
TEST_TIME 96 9510
TEST_TIME 97 9529
TEST_TIME 98 9555
TEST_TIME 99 9513
TEST_TIME 100 9587
TEST_TIME 101 9585
TEST_TIME 102 9627
TEST_TIME 103 9736
TEST_TIME 104 9673
TEST_TIME 105 9605
TEST_TIME 106 9599
TEST_TIME 107 9548
TEST_TIME 108 9555
TEST_TIME 109 9507
TEST_TIME 110 9455
TEST_TIME 111 9451
TEST_TIME 112 9469
TEST_TIME 113 9475
TEST_TIME 114 9432
TEST_TIME 115 9397
TEST_TIME 116 9342
TEST_TIME 117 9350
TEST_TIME 118 9345
TEST_TIME 119 9333
TEST_TIME 120 9316

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-08-2021 16:39:20 to 22-08-2021 16:39:24.

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 1555962 1555962 0 0.00 0 34837 7 0 0
doc_list_biud 1555962 1555962 0 0.00 0 31467 28 0 0
sp_add_invoice_to_stock 5305 5192 113 2.13 41 156687 18610 0 113
sp_cancel_adding_invoice 5606 5606 0 0.00 4 78184 944 0 0
sp_cancel_client_order 1903 1903 0 0.00 4 14870 389 0 0
sp_cancel_customer_reserve 554389 554389 0 0.00 1 24115 63 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 6812 83 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 2297 72 0 0
sp_cancel_supplier_invoice 6243 6237 6 0.09 1 17249 756 0 6
sp_cancel_supplier_order 401 401 0 0.00 8 1087429 107555 0 0
sp_cancel_write_off 10043 10043 0 0.00 1 31520 118 0 0
sp_client_order 21651 21651 0 0.00 3 15541 343 0 0
sp_customer_reserve 473383 473383 0 0.00 0 99989 187 0 0
sp_fill_shopping_cart 33166 33166 0 0.00 1 10115 38 0 0
sp_fill_shopping_cart_clo_res 473354 473354 0 0.00 0 17927 12 0 0
sp_get_clo_for_invoice 5163 5163 0 0.00 0 17539 244 0 0
sp_kill_cost_storno 16791 16791 0 0.00 0 16882 64 0 0
sp_kill_qstorno_ret_qs2qd 1125459 1125459 0 0.00 0 31465 33 0 0
sp_kill_qty_storno 594267 594267 0 0.00 0 31467 72 0 0
sp_lock_selected_doc 21806 21806 0 0.00 0 8908 5 0 0
sp_make_cost_storno 29365 29365 0 0.00 0 30591 60 0 0
sp_multiply_rows_for_pdistr 29365 29365 0 0.00 0 17333 38 0 0
sp_multiply_rows_for_qdistr 21651 21651 0 0.00 0 15533 140 0 0
sp_pay_from_customer 7496 7496 0 0.00 4 16794 128 0 0
sp_pay_to_supplier 6181 6181 0 0.00 5 30604 150 0 0
sp_payment_common 13677 13677 0 0.00 1 16547 45 0 0
sp_qd_handle_on_cancel_clo 1903 1903 0 0.00 0 14861 362 0 0
sp_qd_handle_on_invoice_upd_sts 10769 10769 0 0.00 0 27189 355 0 0
sp_qd_handle_on_reserve_upd_sts 10525 10525 0 0.00 0 15736 62 0 0
sp_reserve_write_off 10525 10525 0 0.00 7 17225 197 0 0
sp_supplier_invoice 5806 5806 0 0.00 6 77878 3375 0 0
sp_supplier_order 5714 5714 0 0.00 6 21611 1403 0 0
srv_aggregate_perf_data 116 115 1 0.86 2666 193427 17660 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 933 163177 45895 0 0
srv_find_qd_qs_mism 1067710 1067710 0 0.00 0 34836 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 27 41884 1183 0 0
srv_make_money_saldo 802 802 0 0.00 0 1137 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 51839 408959 276671 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5192 5192 0 0.00 0 7246 171 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 1 6281 128 0 0
t$perf-norm:sp_cancel_client_order 1903 1903 0 0.00 0 1839 14 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1436 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 265 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 5198 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 458 458 0 0.00 0 840 14 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 24684 1692 0 0
t$perf-norm:sp_cancel_write_off 1089 1089 0 0.00 0 3963 13 0 0
t$perf-norm:sp_client_order 21651 21651 0 0.00 0 14076 20 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 1477 7 0 0
t$perf-norm:sp_pay_from_customer 7496 7496 0 0.00 0 6920 10 0 0
t$perf-norm:sp_pay_to_supplier 6181 6181 0 0.00 0 7794 11 0 0
t$perf-norm:sp_reserve_write_off 10525 10525 0 0.00 0 6053 12 0 0
t$perf-norm:sp_supplier_invoice 5806 5806 0 0.00 0 9290 25 0 0
t$perf-norm:sp_supplier_order 5714 5714 0 0.00 0 6667 19 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 477 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 42 1 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 1923 33 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 3783 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 30 11 0 0
v_add_invoice_to_stock 10548 10322 226 2.14 0 7697 19 0 226
v_all_customers 39114 39114 0 0.00 0 91 1 0 0
v_all_suppliers 31896 31896 0 0.00 0 17 1 0 0
v_all_wares 43302 43302 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 107 6 0 0
v_cancel_client_order 3806 3806 0 0.00 0 4727 6 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 6629 11 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 6618 12 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 1666 6 0 0
v_cancel_supplier_invoice 928 916 12 1.29 0 1107 15 0 12
v_cancel_supplier_order 802 802 0 0.00 0 554 6 0 0
v_cancel_write_off 2178 2178 0 0.00 0 3888 18 0 0
v_max_id_clo_ord 5714 5714 0 0.00 0 668 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1320 8 0 0
v_max_id_ord_sup 5801 5801 0 0.00 0 7331 4 0 0
v_max_non_paid_invoice 1748 1748 0 0.00 0 570 3 0 0
v_max_non_paid_realizn 4129 4129 0 0.00 0 2367 5 0 0
v_min_id_clo_ord 5714 5714 0 0.00 0 4638 9 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 10772 22 0 0
v_min_id_ord_sup 5801 5801 0 0.00 0 5078 9 0 0
v_min_non_paid_invoice 6181 6181 0 0.00 0 14567 33 0 0
v_min_non_paid_realizn 7496 7496 0 0.00 0 16541 29 0 0
v_our_firm 10922 10922 0 0.00 0 25 1 0 0
v_reserve_write_off 21050 21050 0 0.00 0 6626 6 0 0
x_lock_depdocs_on_canc_invoice 11385 11385 0 0.00 0 11502 124 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 16284 417 0 0
x_make_qstorno_1000_1200 5714 5714 0 0.00 1 21436 1306 0 0
x_make_qstorno_1200_2000 5806 5806 0 0.00 3 77351 3250 0 0
x_make_qstorno_2100_3300 473383 473383 0 0.00 0 99987 173 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-08-2021 16:39:24 to 22-08-2021 16:39:29.

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
14:07:49 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:51 38372 8192000000 10387540144 10709635072 1427710992 14.841 100 84 168 0 1461434240 6140752 27582496
14:10:34 23205 8192000000 10450553504 10783297536 1502708416 15.500 100 92 184 1 1541050672 9013520 29328736
14:12:02 15095 8192000000 10519242592 10863280128 1572056480 16.100 100 82 164 1 1607261536 10510848 24694208
14:13:22 17656 8192000000 10601530544 10960596992 1651692352 16.779 100 80 160 1 1687651456 12025104 23934000
14:14:46 8671 8192000000 10654975376 11026477056 1711472944 17.281 100 85 170 1 1749940512 14080656 24386912
14:16:03 4067 8192000000 10693231888 11071639552 1759828400 17.683 100 82 164 3 1795737504 12989920 22919184
14:17:10 3739 8192000000 10752965856 11132403712 1811375152 18.107 100 92 184 2 1850546752 13423264 25748336
14:18:16 1445 8192000000 10795715264 11179364352 1852519200 18.443 100 95 190 1 1893125904 13773296 26833408
14:19:19 1554 8192000000 10818428912 11209355264 1874268608 18.619 100 84 168 0 1909247904 10809904 24169392
14:20:21 6130 8192000000 10775441744 11201863680 1873367776 18.612 100 18 35 1 1884501040 6311920 4821344
14:21:28 8516 8192000000 10786908208 11216252928 1882433488 18.685 100 22 44 2 1894465184 6305952 5725744
14:22:37 47802 8192000000 10808427488 11240251392 1894638624 18.783 100 47 95 0 1918482640 9263024 14580992
14:24:26 7570 8192000000 10812163072 11249090560 1901188704 18.836 100 19 38 1 1913416064 6428080 5799280
14:25:35 9555 8192000000 10825461952 11258265600 1907892992 18.890 100 20 40 4 1919447376 5962960 5591424
14:26:48 58480 8192000000 10806283328 11232374784 1838813616 18.331 96 33 66 0 1854254496 5430592 10010288
14:28:51 7258 8192000000 10750475680 11179720704 1841751968 18.355 100 27 54 4 1854894608 5476048 7666592
14:29:59 6977 8192000000 10724097424 11138138112 1748439296 17.589 96 26 52 2 1761859328 4913216 8506816
14:31:09 69190 8192000000 10693315104 11116150784 1723918560 17.385 96 31 60 1 1737415200 4549408 8947232
14:33:20 7288 8192000000 10748497264 11157729280 1802736896 18.036 99 36 73 1 1820440672 7390880 10312896
14:34:30 9516 8192000000 10770590272 11192995840 1848456576 18.410 100 26 52 3 1865336320 9283184 7596560
14:35:40 76080 8192000000 10780902704 11217874944 1876015472 18.633 100 36 73 0 1892339504 5103536 11220496
14:37:58 13007 8192000000 10855495232 11268124672 1916374976 18.958 100 71 145 1 1946539552 8841136 21323440
14:39:16 4686 8192000000 10863084512 11285254144 1931571456 19.079 100 56 112 0 1958953504 11159024 16223024
14:40:25 45434 8192000000 10868621184 11311255552 1945262160 19.189 100 44 86 3 1966362096 8457808 12642128
14:42:15 51978 8192000000 10899781296 11339976704 1962710960 19.328 100 40 80 0 1983664640 9468656 11485024
14:44:10 7337 8192000000 10930907552 11363897344 1988256000 19.530 100 50 99 2 2022475632 19401872 14817760
14:45:22 5782 8192000000 10936167584 11368841216 1993955664 19.575 100 58 115 1 2031898624 21400784 16542176
14:46:36 4582 8192000000 10938037440 11361095680 1971661616 19.399 99 54 108 2 2006650720 18844704 16144400
14:47:43 32968 8192000000 10897919056 11341242368 1936756992 19.121 98 29 58 2 1963690288 18181856 8751440
14:49:31 10383 8192000000 10927163040 11365588992 1982898992 19.488 100 43 85 1 2020504176 23655344 13949840
14:50:48 1882 8192000000 10945044352 11359678464 1967301696 19.364 99 77 153 1 2018316144 27617808 23396640
14:51:53 10695 8192000000 10903991072 11352993792 1947194304 19.204 99 25 50 1 1989296016 34058048 8043664
14:53:10 5794 8192000000 10908575472 11320684544 1921932272 19.002 99 77 154 0 1981822432 36808608 23081552
14:54:18 4954 8192000000 10928536272 11345346560 1927207040 19.045 98 72 144 1 1996199072 46631728 22360304
14:55:28 932 8192000000 10946153024 11355484160 1954841440 19.265 100 81 161 1 2027642432 47905328 24895664
14:56:33 2674 8192000000 10926747152 11330457600 1939185152 19.140 100 71 142 1 2000083104 38693248 22204704
14:57:40 1204 8192000000 10944541456 11350220800 1959839296 19.305 100 88 173 2 2020880944 34861104 26180544
14:58:46 10099 8192000000 10918927008 11349544960 1954762016 19.264 100 45 90 0 2006775392 37813728 14199648
15:00:08 2191 8192000000 10950227216 11369140224 1970838528 19.392 100 75 149 1 2036677968 41534448 24304992
15:01:25 6306 8192000000 10946744048 11371859968 1967898384 19.369 100 49 98 2 2027620160 43498144 16223632
15:02:39 9543 8192000000 10959459024 11391680512 1981803392 19.479 100 45 90 2 2043120144 46510224 14806528
15:03:59 11696 8192000000 10985797712 11428581376 2004526800 19.658 100 48 95 1 2075965200 55743216 15695184
15:05:21 16263 8192000000 10964211248 11411374080 1994332960 19.578 100 41 81 1 2051606656 43310592 13963104
15:06:42 23504 8192000000 10940744464 11394457600 1956938576 19.282 99 26 52 1 2010864784 44769744 9156464
15:08:08 30651 8192000000 10935757504 11385360384 1977267072 19.443 100 16 32 1 2014119456 31641088 5211296
15:09:40 34820 8192000000 10931654752 11380502528 1989760336 19.542 100 34 68 0 2017929600 17807200 10362064
15:11:16 52480 8192000000 10909411584 11384078336 1978773520 19.455 100 11 22 1 1995087040 13081792 3231728
15:13:56 17393 8192000000 10938285872 11382796288 1982639552 19.486 99 43 88 1 2002699024 8008128 12051344
15:15:14 78780 8192000000 10943872432 11396173824 1969230592 19.379 98 30 58 0 1998833888 20237600 9365696
15:17:48 65836 8192000000 10927337680 11388637184 1929149360 19.060 97 21 42 2 1967832592 32020096 6663136
15:20:09 35052 8192000000 10912169552 11372621824 1980673504 19.470 100 23 45 0 1994583328 7406512 6503312
15:21:54 35296 8192000000 10898639856 11358806016 1941920512 19.162 98 12 23 3 1952946928 7838192 3188224
15:23:41 93269 8192000000 10904033888 11366371328 1939848368 19.146 97 14 27 2 1954216384 10527968 3840048
15:26:28 57328 8192000000 10926356032 11388207104 1990014672 19.544 100 40 80 1 2012920976 10934640 11971664
15:28:34 152775 8192000000 10882061072 11345571840 1834178624 18.293 92 31 61 2 1850673872 7353728 9141520
15:32:09 26412 8192000000 10903501008 11352473600 1942319232 19.165 99 28 56 0 1962198304 11999456 7879616
15:33:38 163176 8192000000 10868851856 11340017664 1820070976 18.178 92 15 30 0 1841637952 17295824 4271152
15:37:22 68050 8192000000 10853377792 11328430080 1895720928 18.792 98 10 20 0 1919435056 20485120 3229008
15:39:52 68860 8192000000 10835303632 11294683136 1816121552 18.146 94 7 14 0 1827745968 9778496 1845920
15:42:02 57202 8192000000 10852745408 11320578048 1883466112 18.693 97 20 40 1 1898644112 9340736 5837264
15:44:08 37186 8192000000 10889665680 11330506752 1922046144 19.003 99 39 78 1 1940957728 7973216 10938368
15:45:46 53926 8192000000 10860573088 11315654656 1860766128 18.509 96 12 24 0 1872552032 8330112 3455792
15:47:43 50105 8192000000 10871553840 11335761920 1918991984 18.979 99 29 58 0 1941441632 14312688 8136960
15:49:39 87788 8192000000 10881902816 11322302464 1820671984 18.183 93 23 46 1 1835424208 8028608 6723616
15:52:10 72892 8192000000 10897357968 11365724160 1905590288 18.871 98 22 44 1 1929656656 17911360 6155008
15:54:23 122595 8192000000 10875412800 11348971520 1943722224 19.176 99 23 46 1 1962021312 11587904 6711184
15:57:26 68135 8192000000 10902106944 11383222272 1974896032 19.424 100 28 56 0 1996571984 14104448 7571504
15:59:38 129021 8192000000 10906902192 11391737856 1957605504 19.287 99 22 42 0 1982414544 18560736 6248304
16:03:11 56334 8192000000 10876023760 11347722240 1927208240 19.045 98 15 30 2 1945682048 14313056 4160752
16:05:08 43699 8192000000 10892130608 11368144896 1945541296 19.191 99 8 16 2 1962563392 14607392 2414704
16:07:05 80234 8192000000 10895450528 11365126144 1922831824 19.010 97 10 20 2 1940997472 15754544 2411104
16:09:37 66508 8192000000 10918022368 11393961984 1973580624 19.414 99 17 34 0 1990960048 12972880 4406544
16:11:49 58956 8192000000 10943714784 11412738048 1988400688 19.531 99 17 34 2 2004207120 11006864 4799568
16:13:52 30282 8192000000 10915650816 11383078912 1963363072 19.333 98 25 49 0 1984114336 13724768 7026496
16:15:23 30562 8192000000 10942759792 11411177472 1987084080 19.521 99 25 50 1 2005081856 10791952 7205824
16:16:55 62038 8192000000 10918390768 11408543744 1959871232 19.305 98 17 34 2 1980868864 15952160 5045472
16:19:42 73955 8192000000 10908503248 11402280960 1921982768 19.003 97 5 10 0 1949940912 26515600 1442544
16:21:56 129753 8192000000 10900178048 11370057728 1927411040 19.046 98 19 38 1 1945870688 13883616 4576032
16:25:10 4140 8192000000 10895895088 11367378944 1948138960 19.212 99 21 42 3 1968399520 13981232 6279328
16:26:42 45898 8192000000 10845924704 11315920896 1873906352 18.616 96 8 16 0 1887246336 11089776 2250208
16:28:28 80984 8192000000 10875366416 11358040064 1934451824 19.102 99 17 32 2 1955742448 15927248 5363376
16:30:58 66559 8192000000 10847044272 11323183104 1871055568 18.593 96 11 22 1 1881377360 7120768 3201024
16:33:06 71223 8192000000 10844471088 11311030272 1855495648 18.467 96 33 67 1 1879308800 14566128 9247024
16:35:17 70094 8192000000 10837591584 11329679360 1896553984 18.799 99 9 18 1 1913753440 14273280 2926176
16:37:27 23048 8192000000 8931726688 9101545472 102832912 1.239 6 2 4 0 110295952 7106512 356528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-08-2021 16:39:30 to 22-08-2021 16:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 113 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-08-2021 16:39:46 to 22-08-2021 16:39:48.

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          248423
MON$OLDEST_ACTIVE               248424
MON$OLDEST_SNAPSHOT             248424
MON$NEXT_TRANSACTION            248424
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 ---
--- end of diff output ---

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

22.08.21 16:39:49. 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-22 16:39:51 No severe PSQL-related problems occured
22.08.21 16:39:53. End of report.