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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16119460 kB
MemAvailable 30909748 kB
Buffers 0 kB
Cached 15591172 kB
SwapCached 340 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-09-05 16:37:49
DTS_END                         2021-09-05 16:37:49.0330
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/52480
TRN_ID                          239453
ATT_ID                          623
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: 9192.13 23462 1103055
customer order: creation 164.29 344 19715
customer order: refuse 13.93 326 1671
order to supplier: creation 44.47 1225 5336
order to supplier: removal 3.49 99977 419
invoice (draft): creation 44.66 3489 5359
invoice (draft): removal 53.58 716 6430
invoice accept: apply 40.48 18416 4858
invoice accept: cancel 48.02 728 5762
customer reserve: creation 3752.58 181 450310
customer reserve: removal 4743.02 58 569163
realization accept: apply 79.00 198 9480
realization accept: cancel 79.10 117 9492
payment from customer: creation 57.03 134 6844
payment from customer: removal 5.33 73 639
payment to supplier: creation 47.78 152 5733
payment to supplier: removal 3.14 113 377
service: total inventory turnovers 4.48 1138 538
service: total monetary turnovers 6.75 31 810
service: aggregate perf. data 0.96 16907 115
service: refresh index statistics 0.03 324921 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-09-2021 16:39:42 to 05-09-2021 16:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2664
TEST_TIME 2 4803
TEST_TIME 3 3991
TEST_TIME 4 3676
TEST_TIME 5 3643
TEST_TIME 6 3988
TEST_TIME 7 4416
TEST_TIME 8 4565
TEST_TIME 9 4836
TEST_TIME 10 5179
TEST_TIME 11 5448
TEST_TIME 12 5865
TEST_TIME 13 5846
TEST_TIME 14 5902
TEST_TIME 15 6217
TEST_TIME 16 6364
TEST_TIME 17 6703
TEST_TIME 18 7059
TEST_TIME 19 7972
TEST_TIME 20 7913
TEST_TIME 21 7984
TEST_TIME 22 8517
TEST_TIME 23 8977
TEST_TIME 24 9081
TEST_TIME 25 9477
TEST_TIME 26 9700
TEST_TIME 27 10267
TEST_TIME 28 10877
TEST_TIME 29 10925
TEST_TIME 30 10599
TEST_TIME 31 10633
TEST_TIME 32 10474
TEST_TIME 33 10390
TEST_TIME 34 10265
TEST_TIME 35 10185
TEST_TIME 36 10011
TEST_TIME 37 9960
TEST_TIME 38 10161
TEST_TIME 39 10010
TEST_TIME 40 9891
TEST_TIME 41 9947
TEST_TIME 42 9900
TEST_TIME 43 9883
TEST_TIME 44 9707
TEST_TIME 45 9605
TEST_TIME 46 9467
TEST_TIME 47 9342
TEST_TIME 48 9446
TEST_TIME 49 9573
TEST_TIME 50 9567
TEST_TIME 51 9505
TEST_TIME 52 9535
TEST_TIME 53 9575
TEST_TIME 54 9592
TEST_TIME 55 9532
TEST_TIME 56 9524
TEST_TIME 57 9520
TEST_TIME 58 9601
TEST_TIME 59 9580
TEST_TIME 60 9610
TEST_TIME 61 9657
TEST_TIME 62 9688
TEST_TIME 63 9686
TEST_TIME 64 9702
TEST_TIME 65 9643
TEST_TIME 66 9584
TEST_TIME 67 9504
TEST_TIME 68 9462
TEST_TIME 69 9453
TEST_TIME 70 9450
TEST_TIME 71 9385
TEST_TIME 72 9374
TEST_TIME 73 9281
TEST_TIME 74 9295
TEST_TIME 75 9265
TEST_TIME 76 9270
TEST_TIME 77 9192
TEST_TIME 78 9152
TEST_TIME 79 9247
TEST_TIME 80 9245
TEST_TIME 81 9213
TEST_TIME 82 9263
TEST_TIME 83 9248
TEST_TIME 84 9288
TEST_TIME 85 9314
TEST_TIME 86 9316
TEST_TIME 87 9290
TEST_TIME 88 9408
TEST_TIME 89 9458
TEST_TIME 90 9405
TEST_TIME 91 9400
TEST_TIME 92 9359
TEST_TIME 93 9355
TEST_TIME 94 9365
TEST_TIME 95 9325
TEST_TIME 96 9287
TEST_TIME 97 9232
TEST_TIME 98 9209
TEST_TIME 99 9263
TEST_TIME 100 9291
TEST_TIME 101 9238
TEST_TIME 102 9260
TEST_TIME 103 9352
TEST_TIME 104 9318
TEST_TIME 105 9365
TEST_TIME 106 9338
TEST_TIME 107 9343
TEST_TIME 108 9336
TEST_TIME 109 9371
TEST_TIME 110 9397
TEST_TIME 111 9398
TEST_TIME 112 9325
TEST_TIME 113 9307
TEST_TIME 114 9276
TEST_TIME 115 9272
TEST_TIME 116 9215
TEST_TIME 117 9206
TEST_TIME 118 9253
TEST_TIME 119 9205
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-09-2021 16:39:46 to 05-09-2021 16:39:49.

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 1519978 1519978 0 0.00 0 25744 6 0 0
doc_list_biud 1519977 1519977 0 0.00 0 52640 27 0 0
sp_add_invoice_to_stock 4968 4858 110 2.21 37 193560 18416 0 110
sp_cancel_adding_invoice 5762 5762 0 0.00 3 48533 728 0 0
sp_cancel_client_order 1671 1671 0 0.00 4 12060 327 0 0
sp_cancel_customer_reserve 569163 569163 0 0.00 1 38804 59 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 2 2633 73 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 2 6734 114 0 0
sp_cancel_supplier_invoice 6438 6430 8 0.12 1 52644 717 0 8
sp_cancel_supplier_order 419 419 0 0.00 9 1117983 99978 0 0
sp_cancel_write_off 9492 9492 0 0.00 1 16219 117 0 0
sp_client_order 19715 19715 0 0.00 3 34643 344 0 0
sp_customer_reserve 450310 450310 0 0.00 0 167537 181 0 0
sp_fill_shopping_cart 30394 30394 0 0.00 1 13369 38 0 0
sp_fill_shopping_cart_clo_res 450304 450304 0 0.00 0 16147 14 0 0
sp_get_clo_for_invoice 4852 4852 0 0.00 0 26091 327 0 0
sp_kill_cost_storno 16270 16270 0 0.00 0 11471 56 0 0
sp_kill_qstorno_ret_qs2qd 1154667 1154667 0 0.00 0 52638 31 0 0
sp_kill_qty_storno 607267 607267 0 0.00 0 52639 68 0 0
sp_lock_selected_doc 19922 19922 0 0.00 0 6053 4 0 0
sp_make_cost_storno 26908 26908 0 0.00 0 10709 60 0 0
sp_multiply_rows_for_pdistr 26907 26907 0 0.00 0 8944 29 0 0
sp_multiply_rows_for_qdistr 19715 19715 0 0.00 0 34632 163 0 0
sp_pay_from_customer 6844 6844 0 0.00 4 24562 134 0 0
sp_pay_to_supplier 5733 5733 0 0.00 5 17025 153 0 0
sp_payment_common 12577 12577 0 0.00 1 12772 45 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 6766 293 0 0
sp_qd_handle_on_invoice_upd_sts 10612 10612 0 0.00 0 36899 340 0 0
sp_qd_handle_on_reserve_upd_sts 9480 9480 0 0.00 0 30058 63 0 0
sp_reserve_write_off 9480 9480 0 0.00 6 30768 199 0 0
sp_supplier_invoice 5359 5359 0 0.00 6 93109 3490 0 0
sp_supplier_order 5336 5336 0 0.00 5 42191 1226 0 0
srv_aggregate_perf_data 116 115 1 0.86 3047 137618 16907 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2504 157276 49682 0 0
srv_find_qd_qs_mism 1056836 1056836 0 0.00 0 25743 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 36 56808 1138 0 0
srv_make_money_saldo 810 810 0 0.00 1 6857 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 126870 443351 324921 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 4858 4858 0 0.00 0 8522 196 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 5388 123 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 3303 18 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1674 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 4179 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2487 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 670 21 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 19449 1598 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2802 9 0 0
t$perf-norm:sp_client_order 19715 19715 0 0.00 0 6212 20 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 5316 15 0 0
t$perf-norm:sp_pay_from_customer 6844 6844 0 0.00 0 8123 13 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 5660 14 0 0
t$perf-norm:sp_reserve_write_off 9480 9480 0 0.00 0 9773 12 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 3274 16 0 0
t$perf-norm:sp_supplier_order 5336 5336 0 0.00 0 7765 21 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 294 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 168 3 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 1273 23 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 1972 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9920 9700 220 2.21 0 11274 20 0 220
v_all_customers 36186 36186 0 0.00 0 82 1 0 0
v_all_suppliers 29832 29832 0 0.00 0 13 1 0 0
v_all_wares 39430 39430 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 2346 9 0 0
v_cancel_client_order 3342 3342 0 0.00 0 11166 10 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 1611 8 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 7306 11 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3410 10 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 625 13 0 16
v_cancel_supplier_order 838 838 0 0.00 0 2577 7 0 0
v_cancel_write_off 2032 2032 0 0.00 0 7862 24 0 0
v_max_id_clo_ord 5332 5332 0 0.00 0 1124 1 0 0
v_max_id_clo_res 942 942 0 0.00 0 1774 10 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 4233 6 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 1751 3 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 8885 8 0 0
v_min_id_clo_ord 5332 5332 0 0.00 0 2157 5 0 0
v_min_id_clo_res 942 942 0 0.00 0 2609 7 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 6772 5 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 8684 33 0 0
v_min_non_paid_realizn 6844 6844 0 0.00 0 12751 31 0 0
v_our_firm 9698 9698 0 0.00 0 20 1 0 0
v_reserve_write_off 18960 18960 0 0.00 0 19019 8 0 0
x_lock_depdocs_on_canc_invoice 11782 11782 0 0.00 0 12900 106 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 22099 404 0 0
x_make_qstorno_1000_1200 5336 5336 0 0.00 2 42173 1146 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 3 91834 3355 0 0
x_make_qstorno_2100_3300 450310 450310 0 0.00 0 167532 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-09-2021 16:39:50 to 05-09-2021 16:39:54.

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:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 12372 8192000000 10363603808 10676518912 1415000112 14.728 100 89 178 0 1449698752 5378224 29320416
14:10:09 21406 8192000000 10417572960 10743934976 1474328352 15.252 100 90 180 1 1511325888 7912864 29084672
14:11:37 13310 8192000000 10465616640 10801393664 1516937552 15.624 100 84 168 1 1552245936 8842656 26465728
14:12:53 18917 8192000000 10542462912 10897072128 1597560896 16.319 100 78 156 1 1631360016 10113152 23685968
14:14:15 10668 8192000000 10636793472 11000483840 1689996480 17.101 100 77 154 3 1724571904 11500736 23074688
14:15:29 4412 8192000000 10691750224 11070885888 1751165536 17.611 100 74 146 3 1783830416 12389280 20275600
14:16:37 3245 8192000000 10732497888 11114754048 1796533408 17.985 100 76 151 1 1832099216 14643600 20922208
14:17:42 2859 8192000000 10789408368 11175706624 1849835856 18.421 100 87 174 1 1887977920 13171008 24971056
14:18:47 1251 8192000000 10819946736 11209064448 1876471680 18.637 100 94 188 1 1917636592 13721904 27443008
14:19:49 8579 8192000000 10776528112 11202871296 1872790800 18.607 100 25 50 2 1886809264 6888384 7130080
14:21:05 60699 8192000000 10794969616 11232575488 1885576976 18.710 100 23 47 1 1898827728 4605472 8645280
14:23:07 3482 8192000000 10822773888 11247403008 1904602336 18.863 100 52 102 7 1926386720 5716928 16067456
14:24:12 7717 8192000000 10816607792 11253088256 1907386912 18.886 100 24 48 2 1920576512 6115024 7074576
14:25:24 55796 8192000000 10848495088 11260952576 1879068352 18.658 98 59 117 0 1902605968 5877376 17660240
14:27:20 4401 8192000000 10807261056 11233046528 1888222160 18.731 100 32 64 2 1903582144 5813424 9546560
14:28:24 7235 8192000000 10744192448 11171373056 1822738944 18.200 99 23 46 2 1836230592 6078640 7413008
14:29:32 47208 8192000000 10704996032 11117817856 1755083312 17.644 97 37 71 3 1769592272 3944352 10564608
14:31:28 6469 8192000000 10722656512 11139674112 1791092432 17.941 99 31 61 0 1810856000 10737440 9026128
14:32:35 7741 8192000000 10743874128 11164782592 1813734304 18.126 99 33 66 3 1829683728 6118976 9830448
14:33:46 71337 8192000000 10765142240 11199070208 1853063840 18.447 100 20 38 1 1864327440 5189024 6074576
14:36:01 7530 8192000000 10797820992 11225493504 1874413696 18.620 100 19 38 1 1885301008 5632256 5255056
14:37:10 8341 8192000000 10824489040 11244986368 1899398832 18.821 100 25 50 3 1911527152 4671040 7457280
14:38:22 21035 8192000000 10837402944 11257847808 1906910544 18.882 100 34 67 1 1923955600 7600672 9444384
14:39:45 56163 8192000000 10861061376 11295428608 1936397696 19.118 100 32 64 1 1954260192 8660800 9201696
14:41:47 18180 8192000000 10888287184 11321933824 1956182768 19.276 100 30 60 0 1978596944 12814544 9599632
14:43:11 18686 8192000000 10912779680 11345334272 1968832272 19.376 100 43 86 0 1999718480 18080576 12805632
14:44:32 6441 8192000000 10932255984 11359846400 1983393440 19.492 100 56 112 3 2020270736 20988816 15888480
14:45:46 3016 8192000000 10940793776 11360821248 1986318656 19.515 100 80 160 0 2032543776 22086672 24138448
14:46:54 20921 8192000000 10920257728 11359449088 1977267072 19.443 100 56 106 1 2016727904 23413824 16047008
14:48:23 7694 8192000000 10955730176 11390173184 2003089440 19.647 100 54 108 1 2044301488 24898944 16313104
14:49:34 2503 8192000000 10957737616 11370803200 1973771840 19.415 99 80 156 1 2025355120 28290080 23293200
14:50:40 3347 8192000000 10953685536 11366567936 1978864576 19.456 100 78 156 0 2034039776 31735952 23439248
14:51:49 4333 8192000000 10958928240 11370217472 1984652128 19.502 100 77 154 1 2040875408 32655952 23567328
14:52:57 4261 8192000000 10950158960 11359641600 1970434064 19.389 100 88 174 1 2032522672 35221264 26867344
14:54:06 4509 8192000000 10943197856 11347423232 1955713696 19.272 100 87 170 1 2023976256 40507536 27755024
14:55:15 5275 8192000000 10925859280 11349463040 1935525824 19.111 99 60 120 2 1998958096 43716416 19715856
14:56:25 7010 8192000000 10922181952 11355729920 1922820816 19.009 98 58 116 0 1991464256 49709552 18933888
14:57:43 5874 8192000000 10944700160 11366703104 1966271536 19.356 100 63 124 1 2024555184 39378464 18905184
14:58:52 7830 8192000000 10932059904 11371683840 1961368512 19.317 100 50 97 0 2022524640 44831600 16324528
15:00:07 23280 8192000000 10943649152 11392626688 1975616976 19.430 100 32 64 2 2024348800 37683664 11048160
15:01:33 27069 8192000000 10882365584 11332612096 1883736160 18.695 97 30 60 0 1931631200 37339616 10555424
15:03:01 27706 8192000000 10891743984 11337682944 1926308560 19.037 100 20 40 2 1966741776 33636416 6796800
15:04:31 34901 8192000000 10874247664 11338854400 1901113632 18.835 98 20 39 5 1935945568 27941776 6890160
15:06:07 96613 8192000000 10879122240 11328540672 1910530384 18.911 98 17 34 2 1930539520 14507552 5501584
15:08:50 59983 8192000000 10899436000 11341447168 1943462528 19.174 99 27 54 1 1960426736 9595488 7368720
15:10:51 157274 8192000000 10902778896 11340185600 1858474592 18.491 94 27 53 1 1875411584 9782144 7154848
15:14:31 24318 8192000000 10911805184 11368988672 1974321120 19.420 100 29 59 0 1995016608 12316704 8378784
15:15:56 16224 8192000000 10912361456 11393675264 1980760640 19.471 100 16 34 1 2007817008 21592304 5464064
15:17:13 94505 8192000000 10913054848 11377946624 1970430240 19.389 99 22 45 1 1993779136 16638336 6710560
15:19:49 89127 8192000000 10896462064 11375185920 1971228608 19.395 99 18 36 1 1986247296 9756976 5261712
15:22:19 31713 8192000000 10932256064 11401039872 2006223824 19.672 100 34 67 0 2031606736 15852336 9530576
15:23:51 132050 8192000000 10938682208 11433422848 2017992480 19.764 100 25 50 0 2049709760 23808480 7908800
15:27:20 77588 8192000000 10935213024 11413065728 2009098176 19.694 100 6 12 1 2022293872 11715680 1480016
15:29:48 58522 8192000000 10922993424 11408031744 1974616800 19.422 98 12 24 0 1991038064 13051360 3369904
15:32:06 78116 8192000000 10897279408 11355324416 1834644064 18.297 92 28 56 1 1851909008 9616704 7648240
15:34:24 99273 8192000000 10890210224 11377213440 1904912256 18.866 96 15 30 0 1922905600 13766304 4227040
15:37:04 40951 8192000000 10925095504 11379023872 1983039360 19.489 100 42 83 3 2005860960 11120768 11700832
15:38:45 147101 8192000000 10890047984 11351429120 1796410688 17.984 90 7 14 0 1812104240 14146000 1547552
15:42:13 101142 8192000000 10870415136 11335307264 1928017184 19.051 99 14 28 0 1943842768 12344912 3480672
15:45:00 62715 8192000000 10842848880 11308580864 1868860064 18.575 95 20 39 1 1885783280 11555376 5367840
15:47:14 24105 8192000000 10848995936 11341815808 1917567184 18.967 99 2 4 0 1932502752 14077808 857760
15:48:38 61793 8192000000 10880117424 11354333184 1962713744 19.328 100 17 34 0 1976718256 9157120 4847392
15:50:42 76033 8192000000 10863229424 11339792384 1899454496 18.822 97 21 40 2 1915637824 10446880 5736448
15:53:03 20072 8192000000 10900852128 11363508224 1963723184 19.336 100 22 44 2 1982301344 12229856 6348304
15:54:41 66392 8192000000 10871659776 11347963904 1873280032 18.611 95 22 44 0 1892018112 12415280 6322800
15:56:53 52672 8192000000 10876776176 11346563072 1898318816 18.813 97 21 42 0 1912219680 8338272 5562592
15:58:54 33433 8192000000 10883942528 11343790080 1922542000 19.007 98 26 53 0 1939382224 9608608 7231616
16:00:32 18006 8192000000 10894822848 11356798976 1944652992 19.184 99 31 62 1 1963826816 10409552 8764272
16:01:57 82225 8192000000 10887254912 11361386496 1919664224 18.984 98 21 40 0 1938971792 14133328 5174240
16:04:25 98055 8192000000 10897984560 11390984192 1951717056 19.240 99 15 30 0 1972083072 16231584 4134432
16:07:07 74836 8192000000 10938637792 11404595200 1973598368 19.414 99 19 38 1 1998189280 19483744 5107168
16:09:25 95803 8192000000 10910223232 11389943808 1956769040 19.280 98 22 44 0 1981413936 17827680 6817216
16:12:05 51864 8192000000 10905432080 11390414848 1993042160 19.568 100 18 35 1 2004279936 6730064 4507712
16:14:04 49825 8192000000 10927445360 11401949184 1965741648 19.352 98 15 30 0 1982387472 12517040 4128784
16:15:54 52328 8192000000 10941637248 11420479488 1988241744 19.530 99 18 35 0 2006881424 13558512 5081168
16:17:49 43718 8192000000 10951877680 11418685440 1995038784 19.584 99 31 62 3 2017568736 13492896 9037056
16:19:37 61916 8192000000 10907892800 11386900480 1956979808 19.282 97 16 32 1 1975471632 13495136 4996688
16:21:41 26079 8192000000 10936356896 11395145728 1968475888 19.373 98 43 86 5 1990211888 9709840 12026160
16:23:09 56732 8192000000 10895545696 11356119040 1835534640 18.304 92 21 42 1 1850007360 8839248 5633472
16:25:07 43516 8192000000 10886461648 11345440768 1877839984 18.648 96 21 41 3 1895771920 11342032 6589904
16:26:54 57308 8192000000 10915990560 11398430720 1971558240 19.398 100 12 24 1 1996108336 21093120 3456976
16:29:08 78460 8192000000 10907961904 11373654016 1950099216 19.227 98 28 56 3 1966780160 9114880 7566064
16:31:36 93731 8192000000 10904292240 11394502656 1949947648 19.226 98 8 16 0 1964068336 11791936 2328752
16:34:32 63565 8192000000 10895316320 11394048000 1966618384 19.359 99 11 21 1 1984955664 15133792 3203488
16:36:43 70146 8192000000 9454356640 9768628224 613098112 6.962 31 8 16 1 619260480 3923056 2239312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-09-2021 16:39:56 to 05-09-2021 16:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-09-2021 16:40:12 to 05-09-2021 16:40:13.

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          239779
MON$OLDEST_ACTIVE               239780
MON$OLDEST_SNAPSHOT             239780
MON$NEXT_TRANSACTION            239780
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                       2039072
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:206: 
:207: mscs-test-161.budzdorov.ru    Sun Sep  5 16:38:10 2021
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:209: 
--- end of diff output ---

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

05.09.21 16:40:14. 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-09-05 16:40:17 No severe PSQL-related problems occured
05.09.21 16:40:18. End of report.