Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.03.2022 19: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 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 16587420 kB
MemAvailable 31629980 kB
Buffers 0 kB
Cached 15153688 kB
SwapCached 2188 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-03-30 19:38:19
DTS_END                         2022-03-30 19:38:19.7120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35882
TRN_ID                          251786
ATT_ID                          690
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: 9676.30 19969 1161156
customer order: creation 185.31 373 22237
customer order: refuse 15.52 331 1862
order to supplier: creation 48.18 1278 5782
order to supplier: removal 3.50 110039 420
invoice (draft): creation 49.94 3526 5993
invoice (draft): removal 52.55 783 6306
invoice accept: apply 45.27 16960 5432
invoice accept: cancel 47.43 880 5692
customer reserve: creation 4264.18 164 511702
customer reserve: removal 4649.60 67 557952
realization accept: apply 89.33 166 10719
realization accept: cancel 85.13 115 10215
payment from customer: creation 64.32 115 7718
payment from customer: removal 6.03 73 723
payment to supplier: creation 53.19 123 6383
payment to supplier: removal 3.58 62 429
service: total inventory turnovers 5.30 1165 636
service: total monetary turnovers 7.03 28 843
service: aggregate perf. data 0.90 16763 108
service: refresh index statistics 0.03 246359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-03-2022 19:40:00 to 30-03-2022 19:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5729
TEST_TIME 2 5712
TEST_TIME 3 5177
TEST_TIME 4 5657
TEST_TIME 5 5116
TEST_TIME 6 5147
TEST_TIME 7 5214
TEST_TIME 8 5125
TEST_TIME 9 6020
TEST_TIME 10 6229
TEST_TIME 11 6333
TEST_TIME 12 6439
TEST_TIME 13 7066
TEST_TIME 14 7152
TEST_TIME 15 7382
TEST_TIME 16 7470
TEST_TIME 17 7793
TEST_TIME 18 8098
TEST_TIME 19 8236
TEST_TIME 20 8410
TEST_TIME 21 8445
TEST_TIME 22 8367
TEST_TIME 23 8332
TEST_TIME 24 8543
TEST_TIME 25 9118
TEST_TIME 26 8927
TEST_TIME 27 8988
TEST_TIME 28 9605
TEST_TIME 29 9660
TEST_TIME 30 10276
TEST_TIME 31 11660
TEST_TIME 32 11492
TEST_TIME 33 11251
TEST_TIME 34 11288
TEST_TIME 35 11323
TEST_TIME 36 11035
TEST_TIME 37 10941
TEST_TIME 38 10870
TEST_TIME 39 10860
TEST_TIME 40 10790
TEST_TIME 41 10786
TEST_TIME 42 10655
TEST_TIME 43 10458
TEST_TIME 44 10447
TEST_TIME 45 10339
TEST_TIME 46 10401
TEST_TIME 47 10430
TEST_TIME 48 10440
TEST_TIME 49 10386
TEST_TIME 50 10382
TEST_TIME 51 10238
TEST_TIME 52 10050
TEST_TIME 53 10084
TEST_TIME 54 9992
TEST_TIME 55 9945
TEST_TIME 56 9845
TEST_TIME 57 9887
TEST_TIME 58 9826
TEST_TIME 59 9796
TEST_TIME 60 9909
TEST_TIME 61 9932
TEST_TIME 62 9921
TEST_TIME 63 10004
TEST_TIME 64 10029
TEST_TIME 65 10041
TEST_TIME 66 9998
TEST_TIME 67 9969
TEST_TIME 68 10035
TEST_TIME 69 10056
TEST_TIME 70 10113
TEST_TIME 71 10077
TEST_TIME 72 10048
TEST_TIME 73 10001
TEST_TIME 74 9896
TEST_TIME 75 9884
TEST_TIME 76 9897
TEST_TIME 77 9840
TEST_TIME 78 9808
TEST_TIME 79 9761
TEST_TIME 80 9687
TEST_TIME 81 9626
TEST_TIME 82 9716
TEST_TIME 83 9736
TEST_TIME 84 9758
TEST_TIME 85 9783
TEST_TIME 86 9753
TEST_TIME 87 9840
TEST_TIME 88 9786
TEST_TIME 89 9877
TEST_TIME 90 9857
TEST_TIME 91 9899
TEST_TIME 92 9873
TEST_TIME 93 9969
TEST_TIME 94 9950
TEST_TIME 95 9952
TEST_TIME 96 9912
TEST_TIME 97 9865
TEST_TIME 98 9843
TEST_TIME 99 9822
TEST_TIME 100 9805
TEST_TIME 101 9769
TEST_TIME 102 9758
TEST_TIME 103 9723
TEST_TIME 104 9668
TEST_TIME 105 9682
TEST_TIME 106 9650
TEST_TIME 107 9637
TEST_TIME 108 9722
TEST_TIME 109 9726
TEST_TIME 110 9705
TEST_TIME 111 9838
TEST_TIME 112 9787
TEST_TIME 113 9786
TEST_TIME 114 9818
TEST_TIME 115 9813
TEST_TIME 116 9812
TEST_TIME 117 9872
TEST_TIME 118 9806
TEST_TIME 119 9753
TEST_TIME 120 9682

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-03-2022 19:40:04 to 30-03-2022 19:40:07.

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 1632618 1632618 0 0.00 0 14453 6 0 0
doc_list_biud 1632615 1632615 0 0.00 0 23974 28 0 0
sp_add_invoice_to_stock 5586 5432 154 2.75 19 216544 16961 0 154
sp_cancel_adding_invoice 5692 5692 0 0.00 5 77576 881 0 0
sp_cancel_client_order 1862 1862 0 0.00 5 24005 331 0 0
sp_cancel_customer_reserve 557952 557952 0 0.00 1 23188 67 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 3939 74 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 4120 62 0 0
sp_cancel_supplier_invoice 6320 6306 14 0.22 2 16597 783 0 14
sp_cancel_supplier_order 420 420 0 0.00 8 1044109 110039 0 0
sp_cancel_write_off 10215 10215 0 0.00 1 11077 115 0 0
sp_client_order 22237 22237 0 0.00 3 27076 373 0 0
sp_customer_reserve 511702 511702 0 0.00 0 143986 165 0 0
sp_fill_shopping_cart 34006 34006 0 0.00 1 10410 36 0 0
sp_fill_shopping_cart_clo_res 511689 511689 0 0.00 0 14265 12 0 0
sp_get_clo_for_invoice 5416 5416 0 0.00 0 26217 281 0 0
sp_kill_cost_storno 17059 17059 0 0.00 0 10938 58 0 0
sp_kill_qstorno_ret_qs2qd 1132845 1132845 0 0.00 0 23184 36 0 0
sp_kill_qty_storno 598581 598581 0 0.00 0 23974 77 0 0
sp_lock_selected_doc 22231 22231 0 0.00 0 7544 4 0 0
sp_make_cost_storno 30235 30235 0 0.00 0 14397 54 0 0
sp_multiply_rows_for_pdistr 30234 30234 0 0.00 0 10732 28 0 0
sp_multiply_rows_for_qdistr 22237 22237 0 0.00 0 24972 153 0 0
sp_pay_from_customer 7718 7718 0 0.00 4 14956 116 0 0
sp_pay_to_supplier 6383 6383 0 0.00 4 12789 123 0 0
sp_payment_common 14100 14100 0 0.00 1 14945 31 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 23973 310 0 0
sp_qd_handle_on_invoice_upd_sts 11107 11107 0 0.00 0 16398 347 0 0
sp_qd_handle_on_reserve_upd_sts 10719 10719 0 0.00 0 12245 54 0 0
sp_reserve_write_off 10719 10719 0 0.00 6 12389 166 0 0
sp_supplier_invoice 5993 5993 0 0.00 6 86313 3526 0 0
sp_supplier_order 5782 5782 0 0.00 5 29429 1279 0 0
srv_aggregate_perf_data 109 108 1 0.91 2419 169528 16764 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1108 140722 43055 0 0
srv_find_qd_qs_mism 1107979 1107979 0 0.00 0 9990 5 0 0
srv_make_invnt_saldo 636 636 0 0.00 20 138592 1165 0 0
srv_make_money_saldo 843 843 0 0.00 0 977 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 165916 336698 246360 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 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 5432 5432 0 0.00 0 11034 156 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3457 105 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 3519 17 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 1191 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 1232 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 646 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 2390 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 38280 1766 0 0
t$perf-norm:sp_cancel_write_off 1085 1085 0 0.00 0 5864 13 0 0
t$perf-norm:sp_client_order 22237 22237 0 0.00 0 10002 17 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1789 7 0 0
t$perf-norm:sp_pay_from_customer 7718 7718 0 0.00 0 6509 7 0 0
t$perf-norm:sp_pay_to_supplier 6383 6383 0 0.00 0 8347 9 0 0
t$perf-norm:sp_reserve_write_off 10719 10719 0 0.00 0 9625 11 0 0
t$perf-norm:sp_supplier_invoice 5993 5993 0 0.00 0 9538 23 0 0
t$perf-norm:sp_supplier_order 5782 5782 0 0.00 0 3765 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 430 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 85 2 0 0
t$perf-norm:srv_make_invnt_saldo 636 636 0 0.00 0 1352 21 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1246 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 11138 10830 308 2.76 0 4857 17 0 308
v_all_customers 40814 40814 0 0.00 0 14 1 0 0
v_all_suppliers 33110 33110 0 0.00 0 20 1 0 0
v_all_wares 44470 44470 0 0.00 0 153 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 444 7 0 0
v_cancel_client_order 3724 3724 0 0.00 0 1547 4 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1110 4 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 2297 7 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 4113 9 0 0
v_cancel_supplier_invoice 860 832 28 3.25 0 693 12 0 28
v_cancel_supplier_order 840 840 0 0.00 0 1522 6 0 0
v_cancel_write_off 2170 2170 0 0.00 0 2196 13 0 0
v_max_id_clo_ord 5778 5778 0 0.00 0 337 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 3076 18 0 0
v_max_id_ord_sup 5993 5993 0 0.00 0 5318 4 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 598 3 0 0
v_max_non_paid_realizn 4060 4060 0 0.00 0 4514 6 0 0
v_min_id_clo_ord 5778 5778 0 0.00 0 3531 9 0 0
v_min_id_clo_res 995 995 0 0.00 0 3379 5 0 0
v_min_id_ord_sup 5993 5993 0 0.00 0 4405 7 0 0
v_min_non_paid_invoice 6382 6382 0 0.00 0 10138 25 0 0
v_min_non_paid_realizn 7718 7718 0 0.00 0 14339 22 0 0
v_our_firm 10972 10972 0 0.00 0 21 1 0 0
v_reserve_write_off 21434 21434 0 0.00 0 8260 5 0 0
x_lock_depdocs_on_canc_invoice 11582 11582 0 0.00 0 11614 126 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 4086 336 0 0
x_make_qstorno_1000_1200 5782 5782 0 0.00 1 29374 1198 0 0
x_make_qstorno_1200_2000 5993 5993 0 0.00 3 86279 3403 0 0
x_make_qstorno_2100_3300 511702 511702 0 0.00 0 143986 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-03-2022 19:40:08 to 30-03-2022 19:40:12.

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:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:24 26002 8192000000 9893966512 10198945792 971140192 10.598 100 91 182 0 1001362576 5704032 24518352
17:10:57 13510 8192000000 9936393104 10253078528 1022450848 11.096 100 92 184 0 1053794656 7803456 23540352
17:12:22 16887 8192000000 9973642608 10303041536 1053947584 11.399 100 80 160 0 1083480160 9194464 20338112
17:13:42 9704 8192000000 10029086320 10374037504 1114883920 11.979 100 76 152 2 1142938688 10253648 17801120
17:14:56 7442 8192000000 10082119968 10440314880 1166763360 12.467 100 81 162 0 1197705296 12428928 18513008
17:16:07 5998 8192000000 10123515152 10489016320 1217408448 12.938 100 83 163 0 1248481600 12908416 18164736
17:17:16 4044 8192000000 10165029712 10536669184 1247475792 13.215 100 88 174 0 1279864240 12984176 19404272
17:18:23 2619 8192000000 10188374912 10568318976 1272372672 13.443 100 85 169 0 1303782320 13063376 18346272
17:19:27 1961 8192000000 10205304480 10585255936 1287952544 13.586 100 93 186 2 1322494512 13892176 20649792
17:20:32 6268 8192000000 10187287648 10594492416 1294307040 13.643 100 36 72 2 1315939920 13864016 7768864
17:21:41 3506 8192000000 10192038448 10597318656 1301119280 13.705 100 40 80 2 1317838800 7837424 8882096
17:22:46 9341 8192000000 10193254784 10607083520 1310537712 13.791 100 15 29 1 1320055936 5727904 3790320
17:23:57 55206 8192000000 10215339200 10633400320 1323511488 13.908 100 22 44 0 1334477552 5384000 5582064
17:25:58 4963 8192000000 10225632032 10642681856 1332578800 13.990 100 35 69 0 1347328752 7339888 7410064
17:27:04 5247 8192000000 10236026192 10650456064 1334027792 14.004 100 22 44 0 1349964064 11021728 4914544
17:28:09 8459 8192000000 10213304800 10631528448 1323902192 13.912 100 14 28 0 1332209600 5062768 3244640
17:29:18 23033 8192000000 10199458144 10593345536 1280061488 13.514 98 64 129 0 1302655776 6785536 15808752
17:30:42 5469 8192000000 10152994688 10555105280 1247408320 13.214 98 36 76 1 1261079248 5171344 8499584
17:31:53 49018 8192000000 10109797424 10502782976 1193431392 12.715 96 38 77 2 1208472816 6045328 8996096
17:33:44 7720 8192000000 10148594512 10551021568 1260396688 13.334 100 24 48 4 1271249248 4974496 5878064
17:34:56 9659 8192000000 10178074560 10588467200 1287684880 13.583 100 26 52 0 1299090848 5084064 6321904
17:36:07 76160 8192000000 10189344640 10605924352 1299888048 13.694 100 32 65 0 1312592944 5288912 7415984
17:38:25 22836 8192000000 10226645952 10637103104 1326893120 13.939 100 34 68 1 1341602736 6430160 8279456
17:39:54 14175 8192000000 10242625120 10656710656 1341427312 14.070 100 45 90 0 1360225824 8239296 10559216
17:41:11 22482 8192000000 10265323728 10678419456 1350247728 14.150 100 50 100 0 1372662512 10164928 12249856
17:42:40 22917 8192000000 10272734496 10698670080 1367736224 14.307 100 39 75 0 1388346064 11195904 9413936
17:44:09 9046 8192000000 10300498816 10722537472 1383192544 14.445 100 66 131 1 1414194576 15246864 15755168
17:45:25 10225 8192000000 10301292480 10723303424 1387511488 14.484 100 54 107 1 1417803248 17070160 13221600
17:46:41 17582 8192000000 10319999680 10739941376 1395292816 14.553 100 60 119 0 1432854288 23636352 13925120
17:48:05 18373 8192000000 10327052288 10744074240 1395445232 14.554 100 71 140 0 1434503488 22966848 16091408
17:49:31 10561 8192000000 10326282528 10744184832 1372282032 14.347 98 61 122 0 1417007472 29569504 15155936
17:50:47 7909 8192000000 10349150880 10764775424 1397731696 14.575 100 75 150 0 1455427088 38548560 19146832
17:52:00 6971 8192000000 10351628304 10772234240 1404801712 14.638 100 67 134 2 1458806320 37224048 16780560
17:53:14 10698 8192000000 10322312896 10742730752 1367570032 14.305 98 58 116 0 1420736064 37982720 15183312
17:54:32 6231 8192000000 10348676496 10762149888 1382739504 14.441 99 70 139 0 1443621232 43550016 17331712
17:55:47 3288 8192000000 10352737184 10770055168 1392941008 14.532 100 74 148 1 1458036336 46066000 19029328
17:57:01 1794 8192000000 10363446736 10773057536 1384162224 14.454 99 86 173 0 1459976960 53976432 21838304
17:58:05 11310 8192000000 10333045968 10758995968 1370875840 14.335 99 36 72 1 1434828896 54265984 9687072
17:59:24 1107 8192000000 10352064784 10760761344 1378282304 14.401 100 69 135 1 1450146320 54715376 17148640
18:00:28 6512 8192000000 10355749408 10771087360 1363977584 14.273 98 53 106 0 1442047760 63560816 14509360
18:01:44 2708 8192000000 10394828624 10805239808 1405701248 14.646 100 73 146 1 1501277392 75598688 19977456
18:02:49 8145 8192000000 10385519424 10797465600 1395672288 14.556 100 57 114 1 1486175872 75149344 15354240
18:04:05 10839 8192000000 10386367696 10818748416 1406980496 14.657 100 37 74 0 1489139664 71314000 10845168
18:06:10 11623 8192000000 10384680208 10823254016 1400493616 14.599 99 36 72 1 1489516960 78409856 10613488
18:07:24 16337 8192000000 10365280672 10824433664 1380967296 14.425 99 20 40 0 1457401664 69892480 6541888
18:09:41 94802 8192000000 10299475328 10737389568 1344349408 14.097 97 13 27 0 1359020512 11664240 3006864
18:12:19 64622 8192000000 10290898512 10737852416 1354847856 14.191 98 8 16 0 1365636144 8823120 1965168
18:14:36 140720 8192000000 10299273040 10753003520 1391740496 14.521 100 26 52 0 1406628256 8338064 6549696
18:17:58 108763 8192000000 10309085184 10767040512 1392282432 14.526 99 17 34 2 1412067200 15870240 3914528
18:20:54 118290 8192000000 10304576928 10756440064 1363274016 14.267 97 39 78 1 1383014880 10649968 9090896
18:24:30 13014 8192000000 10323742256 10786103296 1405228400 14.642 100 28 56 1 1428388160 16699856 6459904
18:25:46 29392 8192000000 10321571472 10801750016 1407924288 14.665 100 9 18 0 1435054096 24760416 2369392
18:27:19 17239 8192000000 10310189728 10786357248 1410358912 14.687 100 5 10 0 1424593184 13034208 1200064
18:28:37 58269 8192000000 10316179520 10781212672 1411664096 14.699 100 12 24 0 1430394784 16009936 2720752
18:30:35 109798 8192000000 10295742528 10767077376 1384038880 14.453 98 14 28 3 1405797872 18145344 3613648
18:33:25 53580 8192000000 10305612976 10776399872 1382195680 14.436 98 7 14 0 1399486928 15160704 2130544
18:35:20 41328 8192000000 10287933744 10749419520 1340358048 14.061 96 10 21 0 1354198336 11372960 2467328
18:37:07 42692 8192000000 10292269600 10751868928 1379821408 14.415 99 22 43 1 1395560352 10627184 5111760
18:38:56 44291 8192000000 10288319472 10749345792 1360176976 14.239 98 16 32 0 1371605968 8115248 3313744
18:40:41 63763 8192000000 10282673232 10737315840 1345669072 14.108 97 15 30 1 1360653392 11782368 3201952
18:42:49 61516 8192000000 10265271616 10718212096 1306190704 13.751 95 12 24 0 1320168544 11085824 2892016
18:44:53 42486 8192000000 10285096688 10728714240 1366848624 14.299 100 26 52 0 1388955200 16598528 5508048
18:46:43 28780 8192000000 10260992928 10698637312 1323637312 13.910 98 27 53 2 1341366128 10771712 6957104
18:48:15 63731 8192000000 10264769888 10722607104 1338686016 14.046 99 9 18 1 1355915120 15543504 1685600
18:50:19 85626 8192000000 10246904032 10695360512 1331089216 13.977 98 24 48 1 1345352800 8774512 5489072
18:52:45 44548 8192000000 10272357216 10718937088 1365153120 14.284 100 32 64 0 1384412912 12073280 7186512
18:54:31 43164 8192000000 10245674640 10701619200 1324576736 13.918 97 15 30 0 1334457600 6346944 3533920
18:56:16 26726 8192000000 10246358256 10701750272 1332441952 13.989 98 22 44 0 1346066416 8697232 4927232
18:57:46 76479 8192000000 10260577552 10721611776 1365325024 14.285 100 14 28 0 1379626272 10795472 3505776
19:00:08 57737 8192000000 10277793312 10741170176 1361546304 14.251 99 13 26 2 1375271072 10411568 3313200
19:02:08 108870 8192000000 10280281648 10747551744 1373626320 14.360 99 22 44 0 1389831824 11248240 4957264
19:04:59 77539 8192000000 10292886944 10755928064 1374641568 14.369 99 13 27 1 1386960000 9415680 2902752
19:07:20 76717 8192000000 10294906800 10747011072 1348357120 14.133 96 19 38 1 1361829904 9337104 4135680
19:09:45 55902 8192000000 10296884384 10740895744 1339231680 14.050 95 31 63 1 1355075072 8830400 7012992
19:11:41 56354 8192000000 10303326480 10750517248 1379090336 14.408 99 12 25 0 1392481536 10984640 2406560
19:13:39 52550 8192000000 10306806304 10756853760 1371051248 14.336 98 35 70 0 1390039040 11582848 7404944
19:16:04 42703 8192000000 10283681808 10733481984 1360631744 14.243 98 29 58 0 1375054160 7845248 6577168
19:17:54 38687 8192000000 10296034208 10744520704 1371725120 14.343 99 21 40 3 1383116128 6064016 5326992
19:19:40 75277 8192000000 10272633808 10740486144 1353901088 14.183 97 15 30 0 1366600992 9188752 3511152
19:21:56 69800 8192000000 10284200896 10744057856 1361925632 14.255 98 19 38 0 1374574128 8017744 4630752
19:24:10 41117 8192000000 10279328016 10735824896 1355710016 14.199 98 29 57 1 1373051664 10987616 6354032
19:25:59 64604 8192000000 10271299840 10715394048 1325197232 13.924 97 20 40 0 1341476976 11524048 4755696
19:28:05 34334 8192000000 10280725232 10727624704 1347175904 14.122 98 37 74 1 1366389776 10429824 8784048
19:29:46 53197 8192000000 10284746400 10724687872 1352011568 14.166 99 40 79 0 1368651824 7677264 8962992
19:31:43 44604 8192000000 10275856848 10730123264 1355777152 14.199 99 25 49 0 1371095712 9695680 5622880
19:33:33 38455 8192000000 10262433216 10707726336 1301648944 13.710 96 34 68 2 1318932112 9917952 7365216
19:35:15 110789 8192000000 10234188080 10684096512 1300602720 13.701 97 14 27 0 1324484128 20976224 2905184
19:38:09 15014 8192000000 9024810672 9236967424 197392992 2.352 15 1 2 0 200782000 3346656 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-03-2022 19:40:14 to 30-03-2022 19:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 154 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-03-2022 19:40:30 to 30-03-2022 19:40:32.

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          252249
MON$OLDEST_ACTIVE               252250
MON$OLDEST_SNAPSHOT             252250
MON$NEXT_TRANSACTION            252250
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                       2047072
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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'

30.03.22 19:40:33. 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-03-30 19:40:35 No severe PSQL-related problems occured
30.03.22 19:40:37. End of report.