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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16462916 kB
MemAvailable 31348160 kB
Buffers 0 kB
Cached 15223332 kB
SwapCached 2464 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-27 19:38:18
DTS_END                         2022-05-27 19:38:18.3060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(420:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59686
TRN_ID                          237475
ATT_ID                          692
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: 9206.43 29401 1104772
customer order: creation 167.96 355 20155
customer order: refuse 14.50 372 1740
order to supplier: creation 45.38 1331 5445
order to supplier: removal 3.51 101906 421
invoice (draft): creation 46.34 3842 5561
invoice (draft): removal 54.16 809 6499
invoice accept: apply 41.20 19065 4944
invoice accept: cancel 47.69 832 5723
customer reserve: creation 3806.23 189 456747
customer reserve: removal 4686.76 59 562411
realization accept: apply 83.04 181 9965
realization accept: cancel 80.89 125 9707
payment from customer: creation 59.18 105 7102
payment from customer: removal 5.86 63 703
payment to supplier: creation 47.77 126 5732
payment to supplier: removal 3.61 72 433
service: total inventory turnovers 4.79 984 575
service: total monetary turnovers 6.76 50 811
service: aggregate perf. data 0.78 22308 94
service: refresh index statistics 0.03 435248 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-05-2022 19:39:12 to 27-05-2022 19:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5130
TEST_TIME 2 4404
TEST_TIME 3 5114
TEST_TIME 4 5096
TEST_TIME 5 4890
TEST_TIME 6 5005
TEST_TIME 7 5765
TEST_TIME 8 5920
TEST_TIME 9 5930
TEST_TIME 10 6132
TEST_TIME 11 6176
TEST_TIME 12 6279
TEST_TIME 13 6395
TEST_TIME 14 6786
TEST_TIME 15 7359
TEST_TIME 16 7661
TEST_TIME 17 8064
TEST_TIME 18 8321
TEST_TIME 19 8607
TEST_TIME 20 9010
TEST_TIME 21 8806
TEST_TIME 22 9003
TEST_TIME 23 9440
TEST_TIME 24 9261
TEST_TIME 25 9614
TEST_TIME 26 10816
TEST_TIME 27 11070
TEST_TIME 28 10888
TEST_TIME 29 10611
TEST_TIME 30 10841
TEST_TIME 31 10578
TEST_TIME 32 10354
TEST_TIME 33 10325
TEST_TIME 34 10174
TEST_TIME 35 10139
TEST_TIME 36 9976
TEST_TIME 37 9917
TEST_TIME 38 9908
TEST_TIME 39 9703
TEST_TIME 40 9663
TEST_TIME 41 9768
TEST_TIME 42 9650
TEST_TIME 43 9598
TEST_TIME 44 9639
TEST_TIME 45 9569
TEST_TIME 46 9507
TEST_TIME 47 9577
TEST_TIME 48 9781
TEST_TIME 49 9771
TEST_TIME 50 9772
TEST_TIME 51 9733
TEST_TIME 52 9679
TEST_TIME 53 9707
TEST_TIME 54 9705
TEST_TIME 55 9671
TEST_TIME 56 9512
TEST_TIME 57 9592
TEST_TIME 58 9485
TEST_TIME 59 9550
TEST_TIME 60 9522
TEST_TIME 61 9538
TEST_TIME 62 9598
TEST_TIME 63 9514
TEST_TIME 64 9447
TEST_TIME 65 9377
TEST_TIME 66 9364
TEST_TIME 67 9320
TEST_TIME 68 9410
TEST_TIME 69 9458
TEST_TIME 70 9408
TEST_TIME 71 9446
TEST_TIME 72 9554
TEST_TIME 73 9499
TEST_TIME 74 9536
TEST_TIME 75 9560
TEST_TIME 76 9526
TEST_TIME 77 9538
TEST_TIME 78 9640
TEST_TIME 79 9675
TEST_TIME 80 9696
TEST_TIME 81 9579
TEST_TIME 82 9562
TEST_TIME 83 9483
TEST_TIME 84 9471
TEST_TIME 85 9435
TEST_TIME 86 9407
TEST_TIME 87 9344
TEST_TIME 88 9332
TEST_TIME 89 9249
TEST_TIME 90 9319
TEST_TIME 91 9333
TEST_TIME 92 9318
TEST_TIME 93 9370
TEST_TIME 94 9368
TEST_TIME 95 9374
TEST_TIME 96 9429
TEST_TIME 97 9417
TEST_TIME 98 9423
TEST_TIME 99 9402
TEST_TIME 100 9483
TEST_TIME 101 9512
TEST_TIME 102 9490
TEST_TIME 103 9470
TEST_TIME 104 9549
TEST_TIME 105 9472
TEST_TIME 106 9400
TEST_TIME 107 9359
TEST_TIME 108 9352
TEST_TIME 109 9319
TEST_TIME 110 9279
TEST_TIME 111 9262
TEST_TIME 112 9251
TEST_TIME 113 9204
TEST_TIME 114 9233
TEST_TIME 115 9258
TEST_TIME 116 9237
TEST_TIME 117 9254
TEST_TIME 118 9254
TEST_TIME 119 9268
TEST_TIME 120 9210

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 19:39:16 to 27-05-2022 19:39:19.

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 1528401 1528401 0 0.00 0 16983 7 0 0
doc_list_biud 1528401 1528401 0 0.00 0 26558 28 0 0
sp_add_invoice_to_stock 5018 4944 74 1.47 32 190328 19065 0 74
sp_cancel_adding_invoice 5723 5723 0 0.00 5 73347 832 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 8268 373 0 0
sp_cancel_customer_reserve 562411 562411 0 0.00 1 26561 60 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 2762 64 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2594 72 0 0
sp_cancel_supplier_invoice 6505 6499 6 0.09 2 15333 809 0 6
sp_cancel_supplier_order 421 421 0 0.00 7 1097303 101906 0 0
sp_cancel_write_off 9707 9707 0 0.00 1 9798 126 0 0
sp_client_order 20155 20155 0 0.00 3 13722 356 0 0
sp_customer_reserve 456747 456747 0 0.00 0 68221 189 0 0
sp_fill_shopping_cart 31134 31134 0 0.00 1 16386 45 0 0
sp_fill_shopping_cart_clo_res 456736 456736 0 0.00 0 13636 14 0 0
sp_get_clo_for_invoice 4933 4933 0 0.00 0 22566 318 0 0
sp_kill_cost_storno 16566 16566 0 0.00 0 14229 60 0 0
sp_kill_qstorno_ret_qs2qd 1141449 1141449 0 0.00 0 26554 32 0 0
sp_kill_qty_storno 601399 601399 0 0.00 0 26557 70 0 0
sp_lock_selected_doc 20729 20729 0 0.00 0 10005 4 0 0
sp_make_cost_storno 27732 27732 0 0.00 0 14477 50 0 0
sp_multiply_rows_for_pdistr 27732 27732 0 0.00 0 11399 30 0 0
sp_multiply_rows_for_qdistr 20155 20155 0 0.00 0 10216 145 0 0
sp_pay_from_customer 7102 7102 0 0.00 4 16638 105 0 0
sp_pay_to_supplier 5732 5732 0 0.00 5 14138 127 0 0
sp_payment_common 12834 12834 0 0.00 2 16619 30 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 8262 343 0 0
sp_qd_handle_on_invoice_upd_sts 10656 10656 0 0.00 0 20603 339 0 0
sp_qd_handle_on_reserve_upd_sts 9965 9965 0 0.00 0 10862 64 0 0
sp_reserve_write_off 9965 9965 0 0.00 6 16989 181 0 0
sp_supplier_invoice 5561 5561 0 0.00 6 113470 3842 0 0
sp_supplier_order 5445 5445 0 0.00 5 26789 1332 0 0
srv_aggregate_perf_data 95 94 1 1.05 3165 509869 22309 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1481 150717 49248 0 0
srv_find_qd_qs_mism 1057932 1057932 0 0.00 0 11002 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 7 24480 984 0 0
srv_make_money_saldo 811 811 0 0.00 1 10396 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 241419 640009 435249 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 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 4944 4944 0 0.00 0 24460 195 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 10033 121 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 1882 11 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1699 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1595 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1261 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1593 16 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 23595 1553 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 1979 6 0 0
t$perf-norm:sp_client_order 20155 20155 0 0.00 0 11218 17 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 977 5 0 0
t$perf-norm:sp_pay_from_customer 7102 7102 0 0.00 0 10137 12 0 0
t$perf-norm:sp_pay_to_supplier 5732 5732 0 0.00 0 5986 12 0 0
t$perf-norm:sp_reserve_write_off 9965 9965 0 0.00 0 6788 11 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 6889 15 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 11192 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 631 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 447 10 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1198 26 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 724 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10014 9866 148 1.47 0 5417 14 0 148
v_all_customers 37084 37084 0 0.00 0 29 1 0 0
v_all_suppliers 30110 30110 0 0.00 0 22 1 0 0
v_all_wares 40310 40310 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1662 8 0 0
v_cancel_client_order 3480 3480 0 0.00 0 2809 5 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 2734 6 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 282 3 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 288 3 0 0
v_cancel_supplier_invoice 848 836 12 1.41 0 1635 12 0 12
v_cancel_supplier_order 842 842 0 0.00 0 1356 8 0 0
v_cancel_write_off 2068 2068 0 0.00 0 2359 16 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 5173 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1015 8 0 0
v_max_id_ord_sup 5534 5534 0 0.00 0 7844 8 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 747 3 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 3403 4 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 5887 8 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 3007 7 0 0
v_min_id_ord_sup 5534 5534 0 0.00 0 16375 12 0 0
v_min_non_paid_invoice 5732 5732 0 0.00 0 14091 22 0 0
v_min_non_paid_realizn 7102 7102 0 0.00 0 16614 25 0 0
v_our_firm 10018 10018 0 0.00 0 20 1 0 0
v_reserve_write_off 19930 19930 0 0.00 0 5365 5 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 19703 124 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 29831 382 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 1 26766 1251 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 3 113353 3664 0 0
x_make_qstorno_2100_3300 456747 456747 0 0.00 0 68221 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 19:39:20 to 27-05-2022 19:39:24.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:22 13 8192000000 8799600608 8811282432 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:22 19955 8192000000 9906273712 10214297600 985296272 10.736 100 91 182 1 1015423632 6050544 24076816
17:10:49 30984 8192000000 9947463904 10268520448 1027769824 11.147 100 90 180 0 1059778288 8586976 23421488
17:12:27 20705 8192000000 9986314832 10318020608 1066809952 11.522 100 82 164 0 1096989536 10011232 20168352
17:13:56 15384 8192000000 10043802736 10387873792 1113539344 11.966 100 84 168 0 1144928816 11248944 20140528
17:15:17 8056 8192000000 10083513280 10441617408 1170354352 12.500 100 84 169 0 1202740144 12460752 19925040
17:16:35 3542 8192000000 10131837728 10503311360 1220257056 12.964 100 85 169 0 1251656944 12734160 18665728
17:17:41 4561 8192000000 10165188080 10545233920 1254227360 13.277 100 75 150 0 1283403440 12988640 16187440
17:18:47 2178 8192000000 10205052672 10587602944 1287846704 13.585 100 92 182 2 1322091536 13386832 20858000
17:19:50 13730 8192000000 10216535360 10607509504 1303179248 13.724 100 64 128 0 1328931456 10708256 15043952
17:21:04 4102 8192000000 10200720400 10608828416 1310867184 13.794 100 36 72 1 1327152736 8375568 7909984
17:22:09 12648 8192000000 10203620240 10622164992 1323136432 13.905 100 14 28 1 1331669584 5459920 3073232
17:23:24 59408 8192000000 10220344384 10639572992 1328691744 13.955 100 35 70 1 1341937296 5488448 7757104
17:25:25 5330 8192000000 10235474768 10650963968 1338706672 14.046 100 34 68 0 1352895072 5936432 8251968
17:26:34 9949 8192000000 10231283200 10655035392 1345632320 14.108 100 13 26 0 1353791248 5634480 2524448
17:27:47 13911 8192000000 10215642704 10634932224 1308787792 13.775 98 21 41 3 1318789424 5294464 4707168
17:29:01 28408 8192000000 10223054400 10623967232 1296038384 13.659 98 63 129 1 1316907856 5965488 14903984
17:30:31 3836 8192000000 10157601536 10557890560 1258149120 13.313 99 44 88 2 1275626064 7468192 10008752
17:31:35 5333 8192000000 10144977536 10537160704 1250120976 13.239 100 33 66 0 1264454992 5464496 8869520
17:32:43 35963 8192000000 10137869872 10522079232 1203624448 12.810 96 32 63 3 1215217264 4531776 7061040
17:34:19 3527 8192000000 10171613696 10570674176 1266071216 13.386 100 40 78 0 1282912816 5707536 11134064
17:35:29 12322 8192000000 10186188880 10597507072 1293532256 13.636 100 34 68 3 1306919184 5928640 7458288
17:36:42 10010 8192000000 10192821680 10604818432 1299811120 13.694 100 24 48 0 1311388432 6128608 5448704
17:37:52 76549 8192000000 10232075344 10642145280 1322033968 13.895 100 42 84 1 1344372096 12015824 10322304
17:40:12 26881 8192000000 10260838096 10677022720 1346654384 14.117 100 42 84 1 1366830816 10757648 9418784
17:41:44 35703 8192000000 10276041632 10700169216 1372012848 14.345 100 44 88 1 1397530176 15292752 10224576
17:43:40 7712 8192000000 10306097616 10728337408 1388103840 14.489 100 47 94 2 1423526288 23510960 11911488
17:44:56 11992 8192000000 10311400864 10734538752 1394470928 14.546 100 55 111 2 1429477008 21724960 13281120
17:46:17 6771 8192000000 10329048400 10755252224 1405086944 14.640 100 48 96 1 1439408656 22872816 11448896
17:47:29 13292 8192000000 10333718560 10758287360 1406110992 14.649 100 65 128 0 1449223952 27681776 15431184
17:48:48 3325 8192000000 10362718496 10779267072 1419724320 14.770 100 76 150 1 1468942432 31241920 17976192
17:49:57 5552 8192000000 10367940848 10788581376 1428293408 14.846 100 62 124 0 1480903424 36787824 15822192
17:51:07 1480 8192000000 10378816352 10794541056 1427706368 14.841 100 82 162 0 1489489072 41398960 20383744
17:52:13 3327 8192000000 10345616464 10762190848 1388492800 14.492 99 68 134 0 1446105648 40324736 17288112
17:53:22 4708 8192000000 10367910048 10787024896 1411547456 14.698 100 87 174 0 1480145776 45760160 22838160
17:54:31 4701 8192000000 10372251216 10782769152 1405151392 14.641 100 76 148 2 1472147168 48338080 18657696
17:55:41 4522 8192000000 10346288512 10761822208 1390974560 14.515 100 67 134 0 1451245760 43495824 16775376
17:56:50 1586 8192000000 10355329968 10760863744 1389802672 14.504 100 88 175 1 1456036576 44383664 21850240
17:57:59 2329 8192000000 10342305536 10751377408 1377607200 14.395 100 83 163 2 1437206704 39181760 20417744
17:59:08 12977 8192000000 10305982192 10732507136 1361847424 14.254 99 36 72 1 1406890544 35521616 9521504
18:00:34 6688 8192000000 10344248432 10753204224 1387426480 14.483 100 51 101 4 1439289200 38708576 13154144
18:01:46 16161 8192000000 10315533712 10743566336 1372718064 14.351 99 29 58 3 1417813952 37346128 7749760
18:03:05 15934 8192000000 10317182304 10749308928 1360195472 14.239 100 40 80 2 1427767344 55631760 11940112
18:04:23 30629 8192000000 10281926832 10725576704 1338362576 14.043 99 18 36 0 1383563984 39641856 5559552
18:05:59 106725 8192000000 10284194224 10724372480 1352165936 14.167 99 37 72 0 1375484128 14401168 8917024
18:08:47 54220 8192000000 10267579520 10736590848 1343634352 14.090 99 5 10 0 1365290992 20520960 1135680
18:10:43 26666 8192000000 10293378528 10736676864 1378725616 14.405 100 40 79 2 1402799856 15995632 8078608
18:12:45 67897 8192000000 10279109088 10737606656 1377262240 14.392 100 24 46 0 1392328288 10101728 4964320
18:15:22 22617 8192000000 10281018736 10736033792 1376436912 14.385 99 10 20 0 1386745280 8065232 2243136
18:16:45 116023 8192000000 10295139200 10750533632 1375272960 14.374 99 18 36 1 1392060592 12964224 3823408
18:19:44 60359 8192000000 10300847232 10739810304 1347133936 14.122 97 43 86 0 1367005840 9918992 9952912
18:21:45 67088 8192000000 10295789280 10750681088 1379290240 14.410 99 13 26 1 1392291216 9973056 3027920
18:24:03 30677 8192000000 10312852016 10759913472 1387335680 14.482 99 29 58 1 1407996784 14015920 6645184
18:25:41 150717 8192000000 10317610080 10776993792 1396316080 14.562 99 7 14 0 1420361808 22451120 1594608
18:29:31 55225 8192000000 10315802720 10762092544 1381383504 14.429 98 22 44 1 1395395024 8999776 5011744
18:31:28 40353 8192000000 10333220176 10796048384 1415814880 14.736 100 14 28 0 1432093040 12810400 3467760
18:33:09 143704 8192000000 10331487200 10789826560 1396017680 14.560 98 29 58 1 1424329584 21130240 7181664
18:36:35 62996 8192000000 10300791376 10753691648 1361051376 14.247 97 26 51 0 1387703664 20814464 5837824
18:38:43 87371 8192000000 10268604432 10733174784 1314926496 13.831 95 14 28 2 1335397792 17187600 3283696
18:41:11 35645 8192000000 10263419408 10713677824 1336739904 14.028 98 8 16 0 1348720400 10341760 1638736
18:42:52 35924 8192000000 10247069488 10704220160 1334426624 14.007 98 10 20 1 1343069456 6326640 2316192
18:44:29 65625 8192000000 10272344160 10734854144 1355624544 14.198 99 8 16 0 1369868160 12712096 1531520
18:46:35 43800 8192000000 10269974480 10727653376 1331598208 13.982 97 14 28 0 1351035312 15919632 3517472
18:48:24 27487 8192000000 10283777520 10726998016 1363624528 14.270 100 44 88 1 1386165440 12259072 10281840
18:50:00 89871 8192000000 10270526352 10719125504 1294614800 13.646 94 24 48 0 1310576608 10392640 5569168
18:52:32 61858 8192000000 10254706496 10696077312 1293024064 13.632 95 23 46 0 1307568000 9462512 5081424
18:54:36 19442 8192000000 10277161376 10708295680 1333576928 13.999 98 38 76 1 1351822800 10071248 8174624
18:55:56 70668 8192000000 10280426736 10737115136 1343348976 14.088 98 25 50 0 1371069360 22594976 5125408
18:58:07 127085 8192000000 10267235280 10714021888 1345084928 14.103 98 17 35 0 1364755584 15540352 4130304
19:01:43 24939 8192000000 10287374016 10728144896 1368848112 14.317 100 46 92 1 1391748624 12493968 10406544
19:03:32 57306 8192000000 10248461360 10707304448 1328393744 13.953 97 5 10 1 1343058368 13439216 1225408
19:05:40 101469 8192000000 10274999808 10727464960 1359356720 14.232 99 14 28 1 1378956752 16091296 3508736
19:08:27 50648 8192000000 10268252944 10730999808 1371365904 14.339 100 16 32 1 1393344896 17884176 4094816
19:10:20 65249 8192000000 10276994496 10741309440 1366689856 14.297 99 22 43 0 1384032112 12747936 4594320
19:12:27 81987 8192000000 10279840400 10735955968 1329813680 13.965 96 14 28 0 1341540032 8989648 2736704
19:14:49 43476 8192000000 10273184480 10722340864 1331210176 13.978 96 25 50 0 1345801952 9203568 5388208
19:16:40 37316 8192000000 10308465616 10748608512 1386229776 14.472 100 34 68 3 1405401712 11003152 8168784
19:18:25 100457 8192000000 10282470144 10741092352 1339919872 14.057 97 12 24 0 1359284608 16939248 2425488
19:21:06 29650 8192000000 10324798480 10763657216 1395893440 14.558 100 40 81 1 1418910192 13306656 9710096
19:22:39 134754 8192000000 10309077488 10763370496 1387231360 14.481 99 12 24 1 1399965904 10425616 2308928
19:25:54 78061 8192000000 10302646080 10773573632 1396605680 14.565 99 16 32 0 1409897712 9390784 3901248
19:28:12 62106 8192000000 10297779248 10765389824 1380000336 14.417 98 10 20 0 1393083392 10496560 2586496
19:30:18 75031 8192000000 10304724720 10772733952 1381118592 14.427 98 13 26 2 1401906608 17353984 3434032
19:32:58 69398 8192000000 10270752256 10737790976 1310280816 13.789 94 15 30 0 1324545504 11179296 3085392
19:35:15 59496 8192000000 10262121136 10696880128 1286908000 13.576 94 34 67 1 1305802928 10882464 8012464
19:37:15 68302 8192000000 9123689344 9366011904 285946304 3.372 21 2 4 0 288526560 2495184 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-05-2022 19:39:26 to 27-05-2022 19:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 74 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 27-05-2022 19:39:42 to 27-05-2022 19:39:44.

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          236924
MON$OLDEST_ACTIVE               237809
MON$OLDEST_SNAPSHOT             237809
MON$NEXT_TRANSACTION            237809
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                       2023040
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

27.05.22 19:39:45. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-27 19:39:47 No severe PSQL-related problems occured
27.05.22 19:39:48. End of report.