Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.2022 01: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 16538148 kB
MemAvailable 31546956 kB
Buffers 0 kB
Cached 15170240 kB
SwapCached 2364 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-09 01:38:10
DTS_END                         2022-04-09 01:38:10.7940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52206
TRN_ID                          252986
ATT_ID                          746
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: 9933.90 22015 1192068
customer order: creation 184.22 332 22106
customer order: refuse 16.06 309 1927
order to supplier: creation 49.43 1083 5932
order to supplier: removal 3.61 96894 433
invoice (draft): creation 48.35 2994 5802
invoice (draft): removal 55.13 685 6615
invoice accept: apply 44.78 15873 5373
invoice accept: cancel 50.24 729 6029
customer reserve: creation 4214.40 154 505728
customer reserve: removal 4959.20 56 595104
realization accept: apply 88.24 163 10589
realization accept: cancel 83.28 95 9994
payment from customer: creation 63.25 93 7590
payment from customer: removal 6.50 57 780
payment to supplier: creation 50.68 112 6081
payment to supplier: removal 3.59 68 431
service: total inventory turnovers 4.88 1100 585
service: total monetary turnovers 7.26 31 871
service: aggregate perf. data 0.78 22163 94
service: refresh index statistics 0.03 297309 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-04-2022 01:39:33 to 09-04-2022 01:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4710
TEST_TIME 2 5822
TEST_TIME 3 5287
TEST_TIME 4 4957
TEST_TIME 5 4972
TEST_TIME 6 5072
TEST_TIME 7 5236
TEST_TIME 8 5456
TEST_TIME 9 5665
TEST_TIME 10 5898
TEST_TIME 11 6032
TEST_TIME 12 6041
TEST_TIME 13 6554
TEST_TIME 14 6672
TEST_TIME 15 6847
TEST_TIME 16 7250
TEST_TIME 17 7501
TEST_TIME 18 8080
TEST_TIME 19 8726
TEST_TIME 20 8655
TEST_TIME 21 9306
TEST_TIME 22 9585
TEST_TIME 23 10323
TEST_TIME 24 10953
TEST_TIME 25 11680
TEST_TIME 26 12088
TEST_TIME 27 11916
TEST_TIME 28 12104
TEST_TIME 29 11860
TEST_TIME 30 11675
TEST_TIME 31 11449
TEST_TIME 32 11290
TEST_TIME 33 11308
TEST_TIME 34 11284
TEST_TIME 35 11294
TEST_TIME 36 11090
TEST_TIME 37 10997
TEST_TIME 38 10935
TEST_TIME 39 10897
TEST_TIME 40 11043
TEST_TIME 41 10898
TEST_TIME 42 10748
TEST_TIME 43 10717
TEST_TIME 44 10575
TEST_TIME 45 10410
TEST_TIME 46 10476
TEST_TIME 47 10364
TEST_TIME 48 10336
TEST_TIME 49 10291
TEST_TIME 50 10168
TEST_TIME 51 10123
TEST_TIME 52 10141
TEST_TIME 53 10037
TEST_TIME 54 10051
TEST_TIME 55 10100
TEST_TIME 56 10119
TEST_TIME 57 10242
TEST_TIME 58 10257
TEST_TIME 59 10219
TEST_TIME 60 10267
TEST_TIME 61 10285
TEST_TIME 62 10346
TEST_TIME 63 10311
TEST_TIME 64 10304
TEST_TIME 65 10183
TEST_TIME 66 10189
TEST_TIME 67 10115
TEST_TIME 68 10086
TEST_TIME 69 10055
TEST_TIME 70 9975
TEST_TIME 71 9860
TEST_TIME 72 9873
TEST_TIME 73 9898
TEST_TIME 74 9870
TEST_TIME 75 9892
TEST_TIME 76 9881
TEST_TIME 77 9873
TEST_TIME 78 9917
TEST_TIME 79 9939
TEST_TIME 80 9982
TEST_TIME 81 9974
TEST_TIME 82 10056
TEST_TIME 83 10110
TEST_TIME 84 10120
TEST_TIME 85 10028
TEST_TIME 86 10050
TEST_TIME 87 9987
TEST_TIME 88 9960
TEST_TIME 89 9942
TEST_TIME 90 9920
TEST_TIME 91 9906
TEST_TIME 92 9906
TEST_TIME 93 9969
TEST_TIME 94 9979
TEST_TIME 95 10006
TEST_TIME 96 10066
TEST_TIME 97 10053
TEST_TIME 98 10059
TEST_TIME 99 10090
TEST_TIME 100 10110
TEST_TIME 101 10189
TEST_TIME 102 10169
TEST_TIME 103 10209
TEST_TIME 104 10206
TEST_TIME 105 10210
TEST_TIME 106 10166
TEST_TIME 107 10143
TEST_TIME 108 10142
TEST_TIME 109 10098
TEST_TIME 110 10070
TEST_TIME 111 10087
TEST_TIME 112 10066
TEST_TIME 113 10050
TEST_TIME 114 9991
TEST_TIME 115 10012
TEST_TIME 116 9964
TEST_TIME 117 9937
TEST_TIME 118 10011
TEST_TIME 119 10008
TEST_TIME 120 9935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-04-2022 01:39:37 to 09-04-2022 01:39:40.

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 1659926 1659926 0 0.00 0 25758 6 0 0
doc_list_biud 1659926 1659926 0 0.00 0 42342 25 0 0
sp_add_invoice_to_stock 5481 5373 108 1.97 41 150083 15873 0 108
sp_cancel_adding_invoice 6029 6029 0 0.00 5 78826 729 0 0
sp_cancel_client_order 1927 1927 0 0.00 4 16373 309 0 0
sp_cancel_customer_reserve 595104 595104 0 0.00 1 42348 57 0 0
sp_cancel_pay_from_customer 780 780 0 0.00 2 2475 57 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 4299 69 0 0
sp_cancel_supplier_invoice 6623 6615 8 0.12 1 15179 686 0 8
sp_cancel_supplier_order 433 433 0 0.00 7 1105407 96894 0 0
sp_cancel_write_off 9994 9994 0 0.00 1 8265 96 0 0
sp_client_order 22106 22106 0 0.00 3 28496 333 0 0
sp_customer_reserve 505728 505728 0 0.00 0 71329 154 0 0
sp_fill_shopping_cart 33823 33823 0 0.00 1 7538 33 0 0
sp_fill_shopping_cart_clo_res 505727 505727 0 0.00 0 18024 14 0 0
sp_get_clo_for_invoice 5372 5372 0 0.00 0 21143 289 0 0
sp_kill_cost_storno 17234 17234 0 0.00 0 10771 49 0 0
sp_kill_qstorno_ret_qs2qd 1207250 1207250 0 0.00 0 42333 30 0 0
sp_kill_qty_storno 636063 636063 0 0.00 0 42341 65 0 0
sp_lock_selected_doc 22125 22125 0 0.00 0 10912 5 0 0
sp_make_cost_storno 29632 29632 0 0.00 0 22397 55 0 0
sp_multiply_rows_for_pdistr 29632 29632 0 0.00 0 6921 25 0 0
sp_multiply_rows_for_qdistr 22106 22106 0 0.00 0 15647 158 0 0
sp_pay_from_customer 7590 7590 0 0.00 4 10413 94 0 0
sp_pay_to_supplier 6081 6081 0 0.00 5 20615 112 0 0
sp_payment_common 13671 13671 0 0.00 1 8418 25 0 0
sp_qd_handle_on_cancel_clo 1927 1927 0 0.00 0 16361 285 0 0
sp_qd_handle_on_invoice_upd_sts 11401 11401 0 0.00 0 18045 295 0 0
sp_qd_handle_on_reserve_upd_sts 10589 10589 0 0.00 0 23611 49 0 0
sp_reserve_write_off 10589 10589 0 0.00 6 23623 164 0 0
sp_supplier_invoice 5802 5802 0 0.00 5 83865 2995 0 0
sp_supplier_order 5932 5932 0 0.00 5 28018 1084 0 0
srv_aggregate_perf_data 95 94 1 1.05 2538 353411 22164 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2276 134206 47758 0 0
srv_find_qd_qs_mism 1140562 1140562 0 0.00 0 25756 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 38 57840 1100 0 0
srv_make_money_saldo 871 871 0 0.00 1 5821 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 133906 427413 297309 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5373 5373 0 0.00 0 9836 133 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 7079 87 0 0
t$perf-norm:sp_cancel_client_order 1927 1927 0 0.00 0 3198 11 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2289 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 780 780 0 0.00 0 5219 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 3604 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 5612 25 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 21878 1506 0 0
t$perf-norm:sp_cancel_write_off 1085 1085 0 0.00 0 1340 6 0 0
t$perf-norm:sp_client_order 22106 22106 0 0.00 0 8145 14 0 0
t$perf-norm:sp_customer_reserve 1073 1073 0 0.00 0 3906 10 0 0
t$perf-norm:sp_pay_from_customer 7590 7590 0 0.00 0 21401 12 0 0
t$perf-norm:sp_pay_to_supplier 6081 6081 0 0.00 0 11176 11 0 0
t$perf-norm:sp_reserve_write_off 10589 10589 0 0.00 0 8124 14 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 9262 19 0 0
t$perf-norm:sp_supplier_order 5932 5932 0 0.00 0 3561 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 306 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 273 7 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1272 19 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 1704 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10960 10744 216 1.97 0 7491 18 0 216
v_all_customers 40262 40262 0 0.00 0 101 1 0 0
v_all_suppliers 32250 32250 0 0.00 0 101 1 0 0
v_all_wares 44212 44212 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2340 10 0 0
v_cancel_client_order 3854 3854 0 0.00 0 1681 4 0 0
v_cancel_customer_prepayment 1560 1560 0 0.00 0 2463 7 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 211 3 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 210 3 0 0
v_cancel_supplier_invoice 774 758 16 2.06 0 1259 15 0 16
v_cancel_supplier_order 866 866 0 0.00 0 1861 6 0 0
v_cancel_write_off 2170 2170 0 0.00 0 1003 12 0 0
v_max_id_clo_ord 5928 5928 0 0.00 0 697 1 0 0
v_max_id_clo_res 1073 1073 0 0.00 0 1789 12 0 0
v_max_id_ord_sup 5789 5789 0 0.00 0 6310 7 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 967 3 0 0
v_max_non_paid_realizn 4092 4092 0 0.00 0 3734 6 0 0
v_min_id_clo_ord 5928 5928 0 0.00 0 4153 7 0 0
v_min_id_clo_res 1073 1073 0 0.00 0 3142 6 0 0
v_min_id_ord_sup 5789 5789 0 0.00 0 7516 11 0 0
v_min_non_paid_invoice 6081 6081 0 0.00 0 4809 14 0 0
v_min_non_paid_realizn 7590 7590 0 0.00 0 6457 16 0 0
v_our_firm 10946 10946 0 0.00 0 18 1 0 0
v_reserve_write_off 21178 21178 0 0.00 0 3471 5 0 0
x_lock_depdocs_on_canc_invoice 12265 12265 0 0.00 0 30076 107 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 6477 259 0 0
x_make_qstorno_1000_1200 5932 5932 0 0.00 1 28013 1014 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 3 83840 2878 0 0
x_make_qstorno_2100_3300 505728 505728 0 0.00 0 71327 138 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-04-2022 01:39:41 to 09-04-2022 01:39:46.

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
23:08:14 6 8192000000 8804873888 8818233344 10213568 0.124 3 3 4 0 10397472 76800 107104
23:09:15 30536 8192000000 9918807504 10233614336 1000314288 10.882 100 86 172 0 1029413600 6205120 22894192
23:10:54 15281 8192000000 9971892928 10295865344 1054410096 11.403 100 90 180 1 1085518928 8406032 22702800
23:12:21 20072 8192000000 9999905056 10337067008 1079557328 11.643 100 78 156 0 1108598112 9764480 19276304
23:13:44 18804 8192000000 10058286112 10409488384 1137594928 12.193 100 81 161 0 1167019232 10436704 18987600
23:15:04 10218 8192000000 10102500944 10466193408 1186411328 12.650 100 81 161 1 1217175280 12107568 18656384
23:16:19 5099 8192000000 10141153728 10510516224 1228248032 13.038 100 82 164 0 1259143120 13189152 17705936
23:17:25 5396 8192000000 10164928384 10544140288 1254799712 13.282 100 75 150 2 1283538256 12516928 16221616
23:18:33 2538 8192000000 10201986624 10584395776 1287374160 13.580 100 93 186 1 1321438320 12909840 21154320
23:19:36 4615 8192000000 10213898848 10600067072 1301650432 13.710 100 85 168 1 1334145664 13928960 18566272
23:20:43 16686 8192000000 10197995392 10604797952 1304763024 13.739 100 30 61 1 1318684832 6987072 6934736
23:22:01 5739 8192000000 10201967536 10617569280 1315653936 13.837 100 28 54 2 1328226896 6824496 5748464
23:23:07 12923 8192000000 10210648096 10629029888 1323607792 13.909 100 17 34 0 1333134720 5367168 4159760
23:24:20 7625 8192000000 10222343632 10640396288 1336060576 14.022 100 22 43 4 1347088512 6609920 4418016
23:25:28 40014 8192000000 10232003440 10658365440 1341043696 14.067 100 37 74 3 1354690416 5008320 8638400
23:27:10 8974 8192000000 10245010832 10654793728 1320513856 13.881 98 25 50 1 1331847680 5421008 5912816
23:28:21 7364 8192000000 10201223680 10611920896 1282235984 13.533 97 17 33 1 1295292000 9501472 3554544
23:29:29 53008 8192000000 10150909744 10545504256 1194540544 12.726 93 37 74 0 1209294112 5293888 9459680
23:31:23 6247 8192000000 10153473152 10555981824 1243649968 13.180 98 32 64 1 1257224064 6532224 7041872
23:32:30 7366 8192000000 10145529232 10547834880 1234395520 13.095 98 23 46 0 1246007664 6211920 5400224
23:33:38 66685 8192000000 10153763728 10567798784 1269215360 13.414 99 33 66 1 1283764112 6073488 8475264
23:35:45 6865 8192000000 10187288176 10599026688 1292640912 13.628 100 29 58 3 1305329248 5543888 7144448
23:36:52 8427 8192000000 10188820816 10611286016 1303577056 13.728 100 27 54 0 1316833280 6793600 6462624
23:38:01 9370 8192000000 10217236896 10634543104 1320487728 13.881 100 17 34 1 1329124480 5104320 3532432
23:39:16 22249 8192000000 10250074128 10662965248 1346147824 14.113 100 50 101 1 1365032176 7116576 11767776
23:40:42 22788 8192000000 10259669024 10680971264 1344427280 14.097 99 42 84 2 1367268800 13039200 9802320
23:42:08 23541 8192000000 10284922128 10706735104 1373283008 14.356 100 56 112 4 1402760144 16658672 12818464
23:43:35 22854 8192000000 10309159968 10728992768 1386471648 14.474 100 45 90 0 1420092096 22738704 10881744
23:45:01 8172 8192000000 10328578576 10746200064 1399867776 14.594 100 66 129 0 1439701648 24239344 15594528
23:46:12 13243 8192000000 10339953504 10756857856 1403375712 14.625 100 49 98 1 1445591952 30387952 11828288
23:47:30 16362 8192000000 10325991808 10747387904 1380890928 14.425 98 44 88 1 1428613024 36314384 11407712
23:48:52 5306 8192000000 10355579504 10772738048 1403030512 14.622 100 70 140 2 1462218064 42116544 17071008
23:49:59 3241 8192000000 10345479344 10755371008 1393186176 14.534 100 74 146 1 1454696272 43539760 17970336
23:51:07 2276 8192000000 10351420096 10766946304 1399381280 14.589 100 62 122 0 1462049984 47223616 15445088
23:52:12 4766 8192000000 10347914912 10754453504 1383293392 14.446 99 73 144 1 1447204352 45586080 18324880
23:53:21 3273 8192000000 10375554080 10784636928 1410290976 14.687 100 88 176 2 1482422480 49238736 22892768
23:54:28 2838 8192000000 10365516896 10769588224 1390836880 14.513 100 85 169 1 1467207216 54768272 21602064
23:55:38 7248 8192000000 10355961520 10760699904 1377914112 14.398 100 71 143 2 1452988480 56607712 18466656
23:56:52 10967 8192000000 10345939488 10758303744 1373215376 14.356 99 50 99 2 1449017408 62432688 13369344
23:58:09 15451 8192000000 10351157824 10777690112 1399297120 14.589 100 33 66 0 1459080256 50055792 9727344
23:59:30 24084 8192000000 10335038896 10764865536 1348401984 14.133 97 34 68 2 1414182480 55597200 10183296
00:00:58 32615 8192000000 10340949152 10773004288 1381633488 14.431 100 27 54 2 1444029328 54362800 8033040
00:02:37 29039 8192000000 10299500496 10759786496 1345826160 14.110 99 19 37 0 1404928416 53713776 5388480
00:05:03 75336 8192000000 10283832000 10737762304 1354785744 14.191 99 14 28 0 1377254880 19380736 3088400
00:07:18 72078 8192000000 10275167872 10701062144 1316656288 13.846 96 23 46 1 1330873312 9444720 4772304
00:09:33 134206 8192000000 10286117168 10739400704 1373163744 14.355 100 20 40 1 1389316960 11372688 4780528
00:12:49 100618 8192000000 10273578288 10731577344 1355109424 14.193 99 14 28 1 1379530688 21618880 2802384
00:15:31 5235 8192000000 10300239760 10734870528 1379423920 14.411 100 66 132 1 1407277696 12979024 14874752
00:16:38 37759 8192000000 10300577744 10739761152 1382565280 14.439 100 31 62 0 1401954304 12133648 7255376
00:18:21 40835 8192000000 10276124432 10741354496 1354066864 14.184 98 7 14 0 1366955248 11445216 1443168
00:20:04 81739 8192000000 10280578880 10735403008 1362638336 14.261 98 17 34 0 1378257440 12176336 3442768
00:22:27 101674 8192000000 10293373040 10751135744 1376899808 14.389 99 14 28 1 1388595728 8343536 3352384
00:25:10 68256 8192000000 10280705728 10736332800 1373739616 14.361 99 18 36 1 1386900368 9586592 3574160
00:27:20 50871 8192000000 10289787312 10756100096 1393818496 14.540 100 13 26 1 1404533648 7962352 2752800
00:29:38 87975 8192000000 10281870816 10745548800 1338143152 14.041 96 11 22 0 1349721776 9389936 2188688
00:32:18 41286 8192000000 10289878032 10747228160 1365803056 14.289 98 23 46 0 1386856512 15656064 5397392
00:34:00 38480 8192000000 10298756272 10745856000 1359175728 14.230 98 24 49 0 1383890032 18758640 5955664
00:35:46 44390 8192000000 10269817072 10717794304 1330842528 13.975 97 30 61 0 1346688880 9326880 6519472
00:37:37 36622 8192000000 10277309856 10716188672 1338870048 14.047 98 24 48 1 1356083904 11885792 5328064
00:39:16 62599 8192000000 10278215616 10723991552 1346730304 14.118 98 16 32 1 1362665504 12255952 3679248
00:41:32 23377 8192000000 10275637984 10712276992 1331985840 13.985 97 40 80 0 1352302000 11296912 9019248
00:42:57 112802 8192000000 10250985424 10703523840 1350478432 14.152 100 20 38 0 1365190304 10328528 4383344
00:45:53 10559 8192000000 10275870576 10719166464 1361701392 14.253 100 30 60 0 1378780640 10570016 6509232
00:47:03 61051 8192000000 10242727696 10700492800 1298521616 13.682 95 14 28 1 1312481216 10425984 3533616
00:49:04 29401 8192000000 10249261312 10708500480 1352787536 14.173 100 5 10 0 1365570384 11643344 1139504
00:50:46 40517 8192000000 10276312016 10729775104 1362054320 14.256 99 12 24 0 1374443536 9295776 3093440
00:52:33 39883 8192000000 10267560640 10716966912 1365950576 14.291 100 19 36 0 1377780400 7933568 3896256
00:54:20 14898 8192000000 10286204464 10726457344 1376906064 14.389 100 61 119 1 1398755424 8410832 13438528
00:55:37 68313 8192000000 10265937040 10712608768 1333298016 13.997 98 15 30 1 1349380608 12277632 3804960
00:57:49 53080 8192000000 10259740272 10711859200 1297903056 13.676 95 15 30 1 1312885504 11647024 3335424
00:59:42 72988 8192000000 10275387056 10728460288 1326113040 13.932 97 24 48 0 1349014784 17391392 5510352
01:02:01 34201 8192000000 10282487376 10735448064 1372773248 14.352 100 32 64 1 1389519728 9630768 7115712
01:03:36 105505 8192000000 10290697248 10746646528 1365160160 14.284 99 24 48 1 1380386096 9625248 5600688
01:06:40 68115 8192000000 10285707280 10765266944 1388269072 14.490 100 6 12 0 1406961312 16992240 1700000
01:08:51 33540 8192000000 10248213424 10701209600 1287083792 13.578 93 11 20 1 1294814800 5605584 2125424
01:10:32 82935 8192000000 10289399328 10752069632 1374000944 14.363 99 15 29 1 1387109328 9977280 3131104
01:12:56 100003 8192000000 10282317328 10750713856 1282907264 13.540 93 10 20 0 1303817808 18503728 2406816
01:16:12 57807 8192000000 10274036160 10729381888 1343616432 14.090 98 11 22 2 1358466112 12436320 2413360
01:18:16 114833 8192000000 10284682912 10749210624 1309728576 13.784 95 14 29 0 1331074880 18326464 3019840
01:21:12 84242 8192000000 10285547280 10734317568 1326488016 13.935 96 12 24 0 1338139520 9031136 2620368
01:23:41 106469 8192000000 10283751376 10735992832 1378229712 14.401 100 34 66 2 1395923744 9395552 8298480
01:26:43 69919 8192000000 10268431152 10727497728 1310712752 13.793 95 18 36 0 1325687168 10860560 4113856
01:28:55 66489 8192000000 10270867888 10738102272 1364720320 14.280 99 11 22 1 1377064176 9979840 2364016
01:31:07 89886 8192000000 10289487200 10750230528 1387538576 14.484 100 23 46 1 1402920592 9779760 5602256
01:33:37 86101 8192000000 10288065360 10747547648 1358231216 14.221 98 14 27 0 1370757520 9219184 3307120
01:36:04 63072 8192000000 10262262944 10738532352 1344321472 14.096 98 8 15 0 1358095264 11830336 1943456
01:38:08 6810 8192000000 9089525008 9331204096 255190848 3.021 19 2 4 0 257726464 2450896 84720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-04-2022 01:39:47 to 09-04-2022 01:40:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-04-2022 01:40:03 to 09-04-2022 01:40:05.

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          253646
MON$OLDEST_ACTIVE               253647
MON$OLDEST_SNAPSHOT             253647
MON$NEXT_TRANSACTION            253647
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                       2040544
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 ---
--- end of diff output ---

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

09.04.22 01:40:06. 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-09 01:40:09 No severe PSQL-related problems occured
09.04.22 01:40:10. End of report.