Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.04.2022 22: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 16792952 kB
MemAvailable 31487480 kB
Buffers 0 kB
Cached 14890064 kB
SwapCached 2688 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-04-12 22:38:13
DTS_END                         2022-04-12 22:38:13.8890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54514
TRN_ID                          236874
ATT_ID                          661
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: 9019.16 25157 1082299
customer order: creation 169.42 367 20330
customer order: refuse 14.58 371 1750
order to supplier: creation 45.77 1289 5492
order to supplier: removal 3.43 91126 412
invoice (draft): creation 45.88 3292 5506
invoice (draft): removal 51.95 689 6234
invoice accept: apply 41.29 18567 4955
invoice accept: cancel 46.38 845 5565
customer reserve: creation 3762.94 185 451553
customer reserve: removal 4541.20 54 544944
realization accept: apply 83.04 194 9965
realization accept: cancel 84.96 112 10195
payment from customer: creation 57.83 138 6940
payment from customer: removal 5.83 101 699
payment to supplier: creation 48.61 138 5833
payment to supplier: removal 3.59 111 431
service: total inventory turnovers 4.88 1086 586
service: total monetary turnovers 6.59 55 791
service: aggregate perf. data 0.95 14444 114
service: refresh index statistics 0.03 369985 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-04-2022 22:39:29 to 12-04-2022 22:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5478
TEST_TIME 2 4793
TEST_TIME 3 5605
TEST_TIME 4 4817
TEST_TIME 5 4301
TEST_TIME 6 4846
TEST_TIME 7 4795
TEST_TIME 8 4959
TEST_TIME 9 5137
TEST_TIME 10 5509
TEST_TIME 11 6281
TEST_TIME 12 6465
TEST_TIME 13 6775
TEST_TIME 14 6979
TEST_TIME 15 7056
TEST_TIME 16 7230
TEST_TIME 17 7500
TEST_TIME 18 7973
TEST_TIME 19 8180
TEST_TIME 20 8582
TEST_TIME 21 9129
TEST_TIME 22 9450
TEST_TIME 23 9565
TEST_TIME 24 9745
TEST_TIME 25 10151
TEST_TIME 26 10090
TEST_TIME 27 9962
TEST_TIME 28 10116
TEST_TIME 29 10113
TEST_TIME 30 10033
TEST_TIME 31 9888
TEST_TIME 32 9807
TEST_TIME 33 9818
TEST_TIME 34 9696
TEST_TIME 35 9601
TEST_TIME 36 9532
TEST_TIME 37 9457
TEST_TIME 38 9325
TEST_TIME 39 9161
TEST_TIME 40 9089
TEST_TIME 41 9117
TEST_TIME 42 9057
TEST_TIME 43 9141
TEST_TIME 44 9054
TEST_TIME 45 8988
TEST_TIME 46 8982
TEST_TIME 47 9021
TEST_TIME 48 9024
TEST_TIME 49 9031
TEST_TIME 50 8964
TEST_TIME 51 9024
TEST_TIME 52 9023
TEST_TIME 53 9011
TEST_TIME 54 9125
TEST_TIME 55 9168
TEST_TIME 56 9160
TEST_TIME 57 9198
TEST_TIME 58 9329
TEST_TIME 59 9379
TEST_TIME 60 9364
TEST_TIME 61 9389
TEST_TIME 62 9289
TEST_TIME 63 9219
TEST_TIME 64 9157
TEST_TIME 65 9156
TEST_TIME 66 9142
TEST_TIME 67 9078
TEST_TIME 68 8995
TEST_TIME 69 9118
TEST_TIME 70 9054
TEST_TIME 71 9164
TEST_TIME 72 9157
TEST_TIME 73 9142
TEST_TIME 74 9162
TEST_TIME 75 9175
TEST_TIME 76 9204
TEST_TIME 77 9153
TEST_TIME 78 9272
TEST_TIME 79 9220
TEST_TIME 80 9249
TEST_TIME 81 9319
TEST_TIME 82 9376
TEST_TIME 83 9280
TEST_TIME 84 9190
TEST_TIME 85 9155
TEST_TIME 86 9157
TEST_TIME 87 9112
TEST_TIME 88 9068
TEST_TIME 89 9049
TEST_TIME 90 9015
TEST_TIME 91 8982
TEST_TIME 92 9012
TEST_TIME 93 9007
TEST_TIME 94 9030
TEST_TIME 95 9121
TEST_TIME 96 9106
TEST_TIME 97 9073
TEST_TIME 98 9128
TEST_TIME 99 9147
TEST_TIME 100 9105
TEST_TIME 101 9194
TEST_TIME 102 9253
TEST_TIME 103 9272
TEST_TIME 104 9276
TEST_TIME 105 9274
TEST_TIME 106 9216
TEST_TIME 107 9166
TEST_TIME 108 9105
TEST_TIME 109 9120
TEST_TIME 110 9124
TEST_TIME 111 9152
TEST_TIME 112 9142
TEST_TIME 113 9157
TEST_TIME 114 9126
TEST_TIME 115 9078
TEST_TIME 116 9078
TEST_TIME 117 9048
TEST_TIME 118 9031
TEST_TIME 119 9076
TEST_TIME 120 9021

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-04-2022 22:39:33 to 12-04-2022 22:39:36.

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 1500386 1500386 0 0.00 0 18988 6 0 0
doc_list_biud 1500384 1500384 0 0.00 0 36571 25 0 0
sp_add_invoice_to_stock 5032 4955 77 1.53 29 210429 18567 0 77
sp_cancel_adding_invoice 5565 5565 0 0.00 4 103809 845 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 8424 371 0 0
sp_cancel_customer_reserve 544944 544944 0 0.00 1 34965 54 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 5842 102 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 2 5865 111 0 0
sp_cancel_supplier_invoice 6238 6234 4 0.06 1 13204 689 0 4
sp_cancel_supplier_order 412 412 0 0.00 5 1000469 91127 0 0
sp_cancel_write_off 10195 10195 0 0.00 1 18315 113 0 0
sp_client_order 20330 20330 0 0.00 3 16918 367 0 0
sp_customer_reserve 451553 451553 0 0.00 0 58444 185 0 0
sp_fill_shopping_cart 31323 31323 0 0.00 1 9768 40 0 0
sp_fill_shopping_cart_clo_res 451547 451547 0 0.00 0 19423 16 0 0
sp_get_clo_for_invoice 4949 4949 0 0.00 0 32311 358 0 0
sp_kill_cost_storno 16890 16890 0 0.00 0 6996 56 0 0
sp_kill_qstorno_ret_qs2qd 1106729 1106729 0 0.00 0 34853 29 0 0
sp_kill_qty_storno 584013 584013 0 0.00 0 36570 65 0 0
sp_lock_selected_doc 20595 20595 0 0.00 0 7436 8 0 0
sp_make_cost_storno 27686 27686 0 0.00 0 15015 63 0 0
sp_multiply_rows_for_pdistr 27685 27685 0 0.00 0 7159 31 0 0
sp_multiply_rows_for_qdistr 20329 20329 0 0.00 0 16910 152 0 0
sp_pay_from_customer 6940 6940 0 0.00 4 17229 138 0 0
sp_pay_to_supplier 5833 5833 0 0.00 4 13844 138 0 0
sp_payment_common 12772 12772 0 0.00 1 13720 31 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 8367 342 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 0 36570 365 0 0
sp_qd_handle_on_reserve_upd_sts 9965 9965 0 0.00 0 9600 58 0 0
sp_reserve_write_off 9965 9965 0 0.00 7 15745 195 0 0
sp_supplier_invoice 5506 5506 0 0.00 6 67476 3293 0 0
sp_supplier_order 5492 5492 0 0.00 5 30000 1290 0 0
srv_aggregate_perf_data 115 114 1 0.86 2577 130656 14445 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1216 117787 46641 0 0
srv_find_qd_qs_mism 1035253 1035253 0 0.00 0 18980 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 42 39547 1086 0 0
srv_make_money_saldo 791 791 0 0.00 1 24273 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 193883 474227 369986 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 4955 4955 0 0.00 0 10291 163 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 5922 116 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 7599 17 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 499 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 959 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 2039 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3277 20 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 23032 1570 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 828 6 0 0
t$perf-norm:sp_client_order 20330 20330 0 0.00 0 5237 16 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 1783 7 0 0
t$perf-norm:sp_pay_from_customer 6940 6940 0 0.00 0 7148 8 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 8356 8 0 0
t$perf-norm:sp_reserve_write_off 9965 9965 0 0.00 0 6735 12 0 0
t$perf-norm:sp_supplier_invoice 5506 5506 0 0.00 0 6545 19 0 0
t$perf-norm:sp_supplier_order 5492 5492 0 0.00 0 3684 18 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 3873 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1158 22 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 3465 39 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 1750 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10046 9892 154 1.53 0 7774 19 0 154
v_all_customers 36918 36918 0 0.00 0 18 1 0 0
v_all_suppliers 30276 30276 0 0.00 0 75 1 0 0
v_all_wares 40658 40658 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2424 8 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4695 5 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3517 7 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 2886 11 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 3197 8 0 0
v_cancel_supplier_invoice 780 772 8 1.02 0 1200 17 0 8
v_cancel_supplier_order 824 824 0 0.00 0 1511 9 0 0
v_cancel_write_off 1904 1904 0 0.00 0 9664 31 0 0
v_max_id_clo_ord 5489 5489 0 0.00 0 2269 3 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 4172 16 0 0
v_max_id_ord_sup 5505 5505 0 0.00 0 4400 4 0 0
v_max_non_paid_invoice 1689 1689 0 0.00 0 1709 4 0 0
v_max_non_paid_realizn 3835 3835 0 0.00 0 7092 6 0 0
v_min_id_clo_ord 5489 5489 0 0.00 0 4939 10 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 8094 21 0 0
v_min_id_ord_sup 5505 5505 0 0.00 0 5672 8 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 13717 17 0 0
v_min_non_paid_realizn 6939 6939 0 0.00 0 9624 24 0 0
v_our_firm 9948 9948 0 0.00 0 16 1 0 0
v_reserve_write_off 19930 19930 0 0.00 0 4974 8 0 0
x_lock_depdocs_on_canc_invoice 11413 11413 0 0.00 0 23591 108 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 4285 314 0 0
x_make_qstorno_1000_1200 5492 5492 0 0.00 1 29976 1199 0 0
x_make_qstorno_1200_2000 5506 5506 0 0.00 2 63141 3157 0 0
x_make_qstorno_2100_3300 451553 451553 0 0.00 0 58440 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-04-2022 22:39:37 to 12-04-2022 22:39:41.

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
20:08:17 61 8192000000 8832041392 8854347776 28416032 0.345 9 4 5 0 28724080 106384 201664
20:09:18 19524 8192000000 9903374352 10210422784 981047792 10.694 100 86 172 0 1008811840 5802144 21961904
20:10:44 24959 8192000000 9956273312 10279751680 1035284416 11.219 100 85 170 0 1065328032 8162576 21881040
20:12:14 6390 8192000000 9984790064 10315284480 1069834208 11.550 100 87 174 0 1101639232 10227088 21577936
20:13:33 13704 8192000000 10031843072 10379218944 1118346688 12.011 100 81 162 0 1148861296 10980000 19534608
20:14:57 9300 8192000000 10066084288 10428071936 1160342896 12.406 100 68 134 2 1186355424 10423456 15589072
20:16:10 9228 8192000000 10124321712 10493603840 1210869472 12.877 100 77 150 3 1239862064 12115472 16877120
20:17:21 4429 8192000000 10154255568 10531999744 1248334256 13.223 100 69 138 0 1276640304 13586048 14720000
20:18:28 1621 8192000000 10199793728 10579369984 1285345728 13.562 100 92 185 1 1319590992 14077488 20167776
20:19:31 19919 8192000000 10198439536 10596495360 1297824960 13.675 100 59 119 0 1321564480 10424080 13315440
20:20:53 21203 8192000000 10196628560 10608029696 1305829376 13.748 100 26 52 0 1318399440 6758192 5811872
20:22:15 11933 8192000000 10202471216 10614779904 1314340528 13.825 100 29 58 0 1327617952 6777056 6500368
20:23:30 21967 8192000000 10207815856 10625761280 1321530464 13.891 100 14 28 1 1331099904 6333040 3236400
20:24:55 64467 8192000000 10216437408 10637836288 1327029888 13.940 100 19 38 0 1336676096 5588592 4057616
20:27:00 9463 8192000000 10225678736 10643881984 1329438976 13.962 100 24 48 1 1341003584 6359536 5205072
20:28:10 11307 8192000000 10218105184 10635104256 1326150912 13.932 100 28 55 2 1338675872 5575136 6949824
20:29:23 9052 8192000000 10152597280 10555404288 1185314896 12.640 91 23 46 0 1195424304 5203328 4906080
20:30:34 29100 8192000000 10138016448 10534825984 1211189648 12.880 96 37 72 4 1224928784 5467984 8271152
20:32:06 7301 8192000000 10123577568 10518511616 1188048032 12.665 96 24 48 0 1200069632 5998688 6022912
20:33:20 7628 8192000000 10148123536 10547949568 1250815616 13.246 100 30 60 1 1267834144 9941376 7077152
20:34:30 8990 8192000000 10161123104 10567581696 1271585312 13.436 100 20 40 0 1281906096 5800288 4520496
20:35:43 36690 8192000000 10181078752 10591784960 1288402288 13.590 100 27 54 0 1300613328 6184704 6026336
20:37:21 10336 8192000000 10198399904 10609676288 1301689424 13.711 100 22 43 1 1312562512 5847168 5025920
20:38:35 13312 8192000000 10240292736 10644582400 1332539712 13.990 100 48 90 3 1349736224 6580496 10616016
20:40:00 33413 8192000000 10253522768 10671693824 1351423504 14.160 100 30 60 0 1372513472 14328928 6761040
20:41:36 29600 8192000000 10276579408 10694430720 1369160464 14.320 100 44 88 2 1391378928 11160016 11058448
20:43:23 25498 8192000000 10284586896 10704490496 1365442432 14.286 99 38 76 2 1392078720 17827216 8809072
20:44:59 12952 8192000000 10296260064 10721484800 1383949680 14.452 100 42 84 1 1410539312 16884160 9705472
20:46:18 9762 8192000000 10320722080 10744143872 1393392928 14.536 100 58 116 2 1433364208 25419024 14552256
20:47:34 7168 8192000000 10328085408 10752417792 1402819456 14.620 100 61 122 0 1440948656 23668304 14460896
20:48:46 9177 8192000000 10334265424 10752757760 1400986032 14.604 100 71 142 0 1443684064 25666624 17031408
20:50:01 5012 8192000000 10344710608 10759753728 1404239600 14.633 100 73 144 1 1449307168 28225792 16841776
20:51:11 1216 8192000000 10360866352 10771521536 1413459312 14.715 100 94 187 1 1464648832 29524832 21664688
20:52:16 1953 8192000000 10339762528 10749345792 1391452240 14.519 99 81 162 0 1445659584 34540400 19666944
20:53:24 5155 8192000000 10330320944 10745196544 1385637824 14.467 99 64 126 0 1438087536 36449472 16000240
20:54:40 3814 8192000000 10350432160 10762969088 1395669760 14.556 100 73 142 1 1451769872 37897616 18202496
20:55:48 1877 8192000000 10365256624 10779389952 1403980784 14.630 100 90 179 0 1469467456 43119168 22367504
20:56:55 5389 8192000000 10330312384 10738692096 1381929968 14.434 100 80 160 0 1438937008 36934240 20072800
20:58:10 3673 8192000000 10340175520 10761400320 1391805856 14.522 100 49 98 2 1439072480 34202320 13064304
20:59:25 5005 8192000000 10325270640 10750631936 1381308864 14.428 99 48 96 1 1428126512 34439584 12378064
21:00:34 21255 8192000000 10289650688 10725158912 1351729008 14.163 99 26 52 0 1387535328 28929216 6877104
21:02:11 19270 8192000000 10284763856 10719084544 1328872768 13.957 98 25 50 1 1365280784 29586480 6821536
21:03:33 21437 8192000000 10271217792 10701074432 1345179072 14.104 100 19 37 3 1370135488 18703232 6253184
21:04:59 77818 8192000000 10258824816 10701598720 1326742656 13.938 98 31 62 0 1351618208 17332848 7542704
21:07:58 31128 8192000000 10236594624 10678210560 1337332080 14.033 100 22 42 2 1352384848 10474624 4578144
21:09:32 47312 8192000000 10275992928 10713255936 1366164288 14.293 100 18 36 2 1381260656 10909392 4186976
21:11:21 86499 8192000000 10276261184 10719219712 1353185120 14.176 98 20 40 0 1364763600 7298288 4280192
21:13:58 92788 8192000000 10273252128 10716762112 1340580512 14.063 97 20 39 2 1352853696 7716592 4556592
21:16:43 68589 8192000000 10282635568 10724093952 1364830416 14.281 99 29 58 0 1381515152 9690400 6994336
21:18:54 47526 8192000000 10278542304 10737917952 1387794592 14.486 100 19 36 1 1407996224 15377520 4824112
21:20:45 70215 8192000000 10279175808 10741477376 1362977888 14.264 98 15 30 0 1389968592 23093440 3897264
21:23:15 58719 8192000000 10300811936 10759282688 1401390992 14.607 100 21 42 0 1419814112 12985424 5437696
21:25:20 48299 8192000000 10288980336 10754715648 1395480000 14.555 100 22 44 0 1410101808 9862080 4759728
21:27:28 54471 8192000000 10300601120 10745856000 1370356784 14.330 98 27 54 1 1384796368 8030384 6409200
21:29:25 77414 8192000000 10280477552 10726973440 1344916544 14.102 97 14 28 0 1358455936 10269776 3269616
21:31:44 57939 8192000000 10274372016 10737934336 1349014144 14.139 97 16 32 1 1370323632 17537920 3771568
21:33:43 60584 8192000000 10280796864 10717986816 1299060720 13.687 94 24 48 0 1317678256 13191600 5425936
21:35:48 44455 8192000000 10293383984 10749112320 1361056352 14.247 98 16 32 0 1386023152 21050064 3916736
21:37:40 41386 8192000000 10304295504 10753871872 1376266080 14.383 99 14 26 1 1388541472 9284224 2991168
21:39:21 66523 8192000000 10309004080 10773536768 1405790736 14.647 100 19 38 2 1427634944 16786240 5057968
21:41:44 70410 8192000000 10297672480 10752479232 1377125712 14.391 98 25 51 1 1393743600 11062656 5555232
21:43:55 51382 8192000000 10303173568 10758193152 1396899344 14.567 100 15 30 0 1413885152 13373456 3612352
21:45:51 89350 8192000000 10312128416 10763218944 1388596720 14.493 99 12 24 1 1407066400 15716816 2752864
21:48:43 56394 8192000000 10297531104 10742632448 1357957872 14.219 98 22 45 1 1380486416 17138656 5389888
21:50:48 38121 8192000000 10273680432 10705756160 1336966416 14.030 98 32 64 0 1354427056 9773744 7686896
21:52:29 54523 8192000000 10255617088 10689835008 1288617568 13.592 95 29 56 4 1308696112 13584832 6493712
21:54:25 56493 8192000000 10264419120 10701058048 1324083328 13.914 98 20 40 2 1343714576 15520560 4110688
21:56:24 79564 8192000000 10253489952 10688315392 1309922544 13.785 97 9 18 0 1324673904 12667104 2084256
21:58:45 19214 8192000000 10268294240 10706051072 1348089808 14.130 100 23 46 0 1367661632 13932464 5639360
22:00:12 117786 8192000000 10274144320 10707468288 1344247776 14.096 99 31 62 0 1368074352 16677664 7148912
22:03:33 60639 8192000000 10262187408 10720292864 1361423696 14.250 99 13 26 0 1374694944 10330448 2940800
22:05:34 43629 8192000000 10269240928 10722861056 1370810160 14.334 100 9 18 0 1383137056 10287376 2039520
22:07:18 69450 8192000000 10275648352 10722050048 1375966896 14.380 100 9 18 1 1385386224 7419104 2000224
22:09:43 67298 8192000000 10271380896 10730926080 1354539312 14.188 98 20 40 1 1369152320 9549424 5063584
22:11:52 81660 8192000000 10274277008 10732171264 1356829504 14.209 98 14 28 0 1376080512 15760176 3490832
22:14:17 44632 8192000000 10283501280 10729926656 1358546592 14.224 99 19 38 0 1374145456 11201488 4397376
22:16:10 35119 8192000000 10277259632 10727825408 1337288128 14.033 97 27 54 1 1357138048 13848384 6001536
22:17:46 52067 8192000000 10298466576 10736857088 1351391136 14.160 98 38 75 0 1370488080 10708880 8388064
22:20:15 73938 8192000000 10303633088 10763460608 1387380704 14.482 100 17 33 0 1407318528 16206736 3731088
22:22:57 92361 8192000000 10275208608 10721923072 1318505776 13.863 95 19 38 1 1331283232 8518304 4259152
22:25:30 83215 8192000000 10316301200 10760810496 1377519600 14.394 99 26 52 1 1395328304 12498336 5310368
22:28:01 53235 8192000000 10302305392 10759827456 1380848352 14.424 99 24 48 1 1407657568 21272624 5536592
22:29:56 68326 8192000000 10304952416 10775326720 1395927312 14.559 100 6 12 0 1411061792 13980224 1154256
22:32:14 73172 8192000000 10297894752 10755747840 1367618832 14.306 97 11 23 0 1377640672 7149904 2871936
22:35:03 60351 8192000000 10277353328 10734714880 1337030624 14.031 96 14 29 1 1350013680 9479152 3503904
22:37:06 78397 8192000000 9189452224 9452052480 353133456 4.132 26 6 12 0 358111328 3374752 1603120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-04-2022 22:39:43 to 12-04-2022 22:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 77 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-04-2022 22:39:59 to 12-04-2022 22:40:01.

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          237897
MON$OLDEST_ACTIVE               237898
MON$OLDEST_SNAPSHOT             237898
MON$NEXT_TRANSACTION            237898
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                       2007744
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:288: 
:289: mscs-test-161.budzdorov.ru    Tue Apr 12 22:38:28 2022
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:291: 
--- end of diff output ---

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

12.04.22 22:40:02. 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-04-12 22:40:04 No severe PSQL-related problems occured
12.04.22 22:40:05. End of report.