Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.06.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 16454184 kB
MemAvailable 31348708 kB
Buffers 0 kB
Cached 15290852 kB
SwapCached 2448 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-06-06 22:38:21
DTS_END                         2022-06-06 22:38:21.9390
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/46136
TRN_ID                          243926
ATT_ID                          731
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: 9114.96 25233 1093795
customer order: creation 173.58 322 20830
customer order: refuse 15.04 357 1805
order to supplier: creation 45.86 1273 5503
order to supplier: removal 3.51 94929 421
invoice (draft): creation 45.80 3266 5496
invoice (draft): removal 51.67 828 6200
invoice accept: apply 42.37 17396 5084
invoice accept: cancel 46.65 844 5598
customer reserve: creation 3843.63 175 461236
customer reserve: removal 4551.74 57 546209
realization accept: apply 84.58 192 10150
realization accept: cancel 81.01 106 9721
payment from customer: creation 58.91 113 7069
payment from customer: removal 6.08 105 729
payment to supplier: creation 48.78 122 5853
payment to supplier: removal 3.72 87 446
service: total inventory turnovers 4.80 943 576
service: total monetary turnovers 6.33 23 759
service: aggregate perf. data 0.88 23705 106
service: refresh index statistics 0.03 359815 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-06-2022 22:39:52 to 06-06-2022 22:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4812
TEST_TIME 2 3771
TEST_TIME 3 4294
TEST_TIME 4 4607
TEST_TIME 5 4841
TEST_TIME 6 4887
TEST_TIME 7 5137
TEST_TIME 8 5528
TEST_TIME 9 5975
TEST_TIME 10 5945
TEST_TIME 11 6732
TEST_TIME 12 6505
TEST_TIME 13 6620
TEST_TIME 14 6572
TEST_TIME 15 6897
TEST_TIME 16 6896
TEST_TIME 17 7037
TEST_TIME 18 7471
TEST_TIME 19 7753
TEST_TIME 20 7797
TEST_TIME 21 8180
TEST_TIME 22 8214
TEST_TIME 23 8461
TEST_TIME 24 8408
TEST_TIME 25 8686
TEST_TIME 26 8833
TEST_TIME 27 8923
TEST_TIME 28 9351
TEST_TIME 29 10197
TEST_TIME 30 10398
TEST_TIME 31 10188
TEST_TIME 32 10144
TEST_TIME 33 10252
TEST_TIME 34 10508
TEST_TIME 35 10328
TEST_TIME 36 10108
TEST_TIME 37 9964
TEST_TIME 38 9858
TEST_TIME 39 9822
TEST_TIME 40 9840
TEST_TIME 41 9790
TEST_TIME 42 9686
TEST_TIME 43 9609
TEST_TIME 44 9638
TEST_TIME 45 9597
TEST_TIME 46 9544
TEST_TIME 47 9473
TEST_TIME 48 9351
TEST_TIME 49 9424
TEST_TIME 50 9362
TEST_TIME 51 9280
TEST_TIME 52 9244
TEST_TIME 53 9177
TEST_TIME 54 9247
TEST_TIME 55 9207
TEST_TIME 56 9188
TEST_TIME 57 9329
TEST_TIME 58 9254
TEST_TIME 59 9352
TEST_TIME 60 9408
TEST_TIME 61 9383
TEST_TIME 62 9491
TEST_TIME 63 9474
TEST_TIME 64 9487
TEST_TIME 65 9455
TEST_TIME 66 9563
TEST_TIME 67 9588
TEST_TIME 68 9532
TEST_TIME 69 9527
TEST_TIME 70 9447
TEST_TIME 71 9427
TEST_TIME 72 9432
TEST_TIME 73 9365
TEST_TIME 74 9298
TEST_TIME 75 9248
TEST_TIME 76 9155
TEST_TIME 77 9120
TEST_TIME 78 9302
TEST_TIME 79 9368
TEST_TIME 80 9349
TEST_TIME 81 9360
TEST_TIME 82 9400
TEST_TIME 83 9456
TEST_TIME 84 9441
TEST_TIME 85 9422
TEST_TIME 86 9425
TEST_TIME 87 9443
TEST_TIME 88 9425
TEST_TIME 89 9467
TEST_TIME 90 9431
TEST_TIME 91 9352
TEST_TIME 92 9339
TEST_TIME 93 9320
TEST_TIME 94 9285
TEST_TIME 95 9269
TEST_TIME 96 9259
TEST_TIME 97 9261
TEST_TIME 98 9230
TEST_TIME 99 9231
TEST_TIME 100 9238
TEST_TIME 101 9241
TEST_TIME 102 9296
TEST_TIME 103 9311
TEST_TIME 104 9336
TEST_TIME 105 9357
TEST_TIME 106 9362
TEST_TIME 107 9426
TEST_TIME 108 9417
TEST_TIME 109 9426
TEST_TIME 110 9395
TEST_TIME 111 9364
TEST_TIME 112 9300
TEST_TIME 113 9277
TEST_TIME 114 9281
TEST_TIME 115 9276
TEST_TIME 116 9233
TEST_TIME 117 9236
TEST_TIME 118 9200
TEST_TIME 119 9182
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-06-2022 22:39:55 to 06-06-2022 22:39:59.

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 1521205 1521205 0 0.00 0 32577 6 0 0
doc_list_biud 1521205 1521205 0 0.00 0 27387 26 0 0
sp_add_invoice_to_stock 5171 5084 87 1.68 38 181773 17397 0 87
sp_cancel_adding_invoice 5598 5598 0 0.00 3 91149 844 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 10937 358 0 0
sp_cancel_customer_reserve 546209 546209 0 0.00 1 27395 57 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 10412 106 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 10417 87 0 0
sp_cancel_supplier_invoice 6212 6200 12 0.19 1 15671 828 0 12
sp_cancel_supplier_order 421 421 0 0.00 6 1037207 94929 0 0
sp_cancel_write_off 9721 9721 0 0.00 1 16308 106 0 0
sp_client_order 20830 20830 0 0.00 3 39982 322 0 0
sp_customer_reserve 461236 461236 0 0.00 0 34747 176 0 0
sp_fill_shopping_cart 31826 31826 0 0.00 1 12600 38 0 0
sp_fill_shopping_cart_clo_res 461230 461230 0 0.00 0 11236 11 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 15184 280 0 0
sp_kill_cost_storno 16494 16494 0 0.00 0 32567 58 0 0
sp_kill_qstorno_ret_qs2qd 1108760 1108760 0 0.00 0 27357 31 0 0
sp_kill_qty_storno 585180 585180 0 0.00 0 27387 68 0 0
sp_lock_selected_doc 21220 21220 0 0.00 0 7264 4 0 0
sp_make_cost_storno 28148 28148 0 0.00 0 17107 55 0 0
sp_multiply_rows_for_pdistr 28148 28148 0 0.00 0 17184 31 0 0
sp_multiply_rows_for_qdistr 20830 20830 0 0.00 0 39970 150 0 0
sp_pay_from_customer 7069 7069 0 0.00 4 17546 114 0 0
sp_pay_to_supplier 5853 5853 0 0.00 5 18298 123 0 0
sp_payment_common 12922 12922 0 0.00 1 17170 30 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 10916 323 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 25812 333 0 0
sp_qd_handle_on_reserve_upd_sts 10150 10150 0 0.00 0 20800 62 0 0
sp_reserve_write_off 10150 10150 0 0.00 6 20825 193 0 0
sp_supplier_invoice 5496 5496 0 0.00 6 86437 3267 0 0
sp_supplier_order 5503 5503 0 0.00 4 32238 1274 0 0
srv_aggregate_perf_data 107 106 1 0.93 2731 570687 23706 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2183 132317 45641 0 0
srv_find_qd_qs_mism 1046147 1046147 0 0.00 0 19226 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 44 65630 944 0 0
srv_make_money_saldo 759 759 0 0.00 1 414 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 193492 509439 359816 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 5084 5084 0 0.00 0 15616 164 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 5507 104 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 3172 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 943 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 4569 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 839 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2689 23 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 41012 1640 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 15645 27 0 0
t$perf-norm:sp_client_order 20830 20830 0 0.00 0 18244 20 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 8076 19 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 4770 8 0 0
t$perf-norm:sp_pay_to_supplier 5853 5853 0 0.00 0 2197 8 0 0
t$perf-norm:sp_reserve_write_off 10150 10150 0 0.00 0 14802 14 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 10886 20 0 0
t$perf-norm:sp_supplier_order 5503 5503 0 0.00 0 6301 22 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 416 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 985 19 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 509 17 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1030 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 8 0 0
v_add_invoice_to_stock 10326 10152 174 1.68 0 6622 22 0 174
v_all_customers 37830 37830 0 0.00 0 63 1 0 0
v_all_suppliers 30284 30284 0 0.00 0 30 1 0 0
v_all_wares 41660 41660 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2384 9 0 0
v_cancel_client_order 3610 3610 0 0.00 0 6125 10 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 4047 11 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2633 9 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 569 5 0 0
v_cancel_supplier_invoice 826 802 24 2.90 0 5014 24 0 24
v_cancel_supplier_order 842 842 0 0.00 0 7970 18 0 0
v_cancel_write_off 2122 2122 0 0.00 0 1823 14 0 0
v_max_id_clo_ord 5502 5502 0 0.00 0 3563 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 7388 19 0 0
v_max_id_ord_sup 5494 5494 0 0.00 0 3358 3 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 5608 5 0 0
v_max_non_paid_realizn 3959 3959 0 0.00 0 6748 5 0 0
v_min_id_clo_ord 5502 5502 0 0.00 0 6794 8 0 0
v_min_id_clo_res 975 975 0 0.00 0 6308 12 0 0
v_min_id_ord_sup 5494 5494 0 0.00 0 5926 12 0 0
v_min_non_paid_invoice 5853 5853 0 0.00 0 17160 21 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 11037 22 0 0
v_our_firm 10050 10050 0 0.00 0 15 1 0 0
v_reserve_write_off 20300 20300 0 0.00 0 6238 7 0 0
x_lock_depdocs_on_canc_invoice 11397 11397 0 0.00 0 10635 116 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 6128 353 0 0
x_make_qstorno_1000_1200 5503 5503 0 0.00 1 32193 1188 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 3 84499 3138 0 0
x_make_qstorno_2100_3300 461235 461235 0 0.00 0 34746 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-06-2022 22:40:00 to 06-06-2022 22:40:04.

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:25 2 8192000000 8797166096 8807809024 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:26 12751 8192000000 9884411936 10189553664 968674192 10.574 100 91 182 0 998233920 5306368 24253360
20:10:43 21537 8192000000 9932904368 10253123584 1013313152 11.007 100 87 174 2 1044385664 8188752 22883760
20:12:10 20330 8192000000 9968424624 10298384384 1051151056 11.372 100 84 168 0 1081996624 9498672 21346896
20:13:35 9115 8192000000 10015347712 10362085376 1106699920 11.901 100 72 144 1 1134139024 9685040 17754064
20:14:48 8482 8192000000 10070891872 10425139200 1153277392 12.340 100 80 160 2 1183894960 12008400 18609168
20:16:00 7437 8192000000 10118087184 10482565120 1201370288 12.789 100 85 170 0 1233699216 12865728 19463200
20:17:11 3660 8192000000 10153362720 10526126080 1232990224 13.082 100 81 162 0 1266282112 15399856 17892032
20:18:17 3706 8192000000 10183277968 10560622592 1258310880 13.315 100 84 164 2 1290580816 14780608 17489328
20:19:22 1343 8192000000 10208733584 10593050624 1297854176 13.676 100 91 184 2 1331050976 12752656 20444144
20:20:24 1415 8192000000 10221433232 10609373184 1306378544 13.753 100 86 171 0 1336755424 9480272 20896608
20:21:25 8204 8192000000 10191887664 10612985856 1314954560 13.831 100 19 37 1 1325447424 6631024 3861840
20:22:36 6110 8192000000 10206638384 10621083648 1319595120 13.873 100 19 38 0 1330413232 6717216 4100896
20:23:45 14013 8192000000 10221270528 10640105472 1330590272 13.972 100 25 50 0 1346081392 10092768 5398352
20:25:01 36830 8192000000 10217818416 10646863872 1335113600 14.013 100 59 121 0 1354837936 5893040 13831296
20:26:39 6749 8192000000 10237859504 10654306304 1327577088 13.945 99 40 80 2 1344709904 8242592 8890224
20:27:48 10124 8192000000 10232441232 10651885568 1332332096 13.988 100 23 46 2 1347410416 10271648 4806672
20:28:59 70020 8192000000 10136294640 10532921344 1126800800 12.091 86 24 46 1 1136819136 4242368 5775968
20:31:09 2557 8192000000 10160444832 10557902848 1240497712 13.151 97 37 73 3 1255146736 6202656 8446368
20:32:12 7283 8192000000 10153863712 10554425344 1240352416 13.149 98 24 48 1 1251404768 4870688 6181664
20:33:22 16262 8192000000 10167715136 10566938624 1270623456 13.427 100 33 66 2 1283654752 4911936 8119360
20:34:43 21557 8192000000 10178075024 10583257088 1278534464 13.500 100 30 58 1 1290507440 5053040 6919936
20:36:05 9685 8192000000 10198922352 10606419968 1298057296 13.678 100 34 66 5 1312042464 5658400 8326768
20:37:16 13400 8192000000 10200182736 10619981824 1310065824 13.787 100 16 32 1 1319009808 5251488 3692496
20:38:31 37301 8192000000 10236226960 10651693056 1338033392 14.040 100 49 96 2 1355743088 6754032 10955664
20:40:13 77998 8192000000 10267814544 10685620224 1357382848 14.214 100 34 67 2 1375350224 10375008 7592368
20:42:34 46418 8192000000 10280927232 10705346560 1371538432 14.341 100 50 98 2 1393549536 10409312 11601792
20:44:29 9921 8192000000 10303630368 10725576704 1386345936 14.473 100 57 113 0 1414620896 15864608 12410352
20:45:45 15773 8192000000 10318955968 10739470336 1395016928 14.551 100 60 119 0 1428791872 20056432 13718512
20:47:07 16966 8192000000 10323225312 10747744256 1395108240 14.551 100 58 115 1 1436993520 27862288 14022992
20:48:28 10684 8192000000 10325479248 10747383808 1389007728 14.497 99 53 106 2 1425695312 24004480 12683104
20:49:43 3957 8192000000 10313283120 10729631744 1369577024 14.323 98 68 135 0 1403918544 18312304 16029216
20:50:49 3825 8192000000 10325828544 10744066048 1390749824 14.513 100 61 122 2 1434515552 28523936 15241792
20:51:58 8889 8192000000 10308551264 10725826560 1372605264 14.350 100 67 129 2 1420995248 32132304 16257680
20:53:10 2329 8192000000 10331629088 10741329920 1381728512 14.432 100 86 170 1 1436711200 34009264 20973424
20:54:17 3497 8192000000 10339531456 10752487424 1392198640 14.525 100 70 140 0 1447502704 37860496 17443568
20:55:26 5254 8192000000 10332721952 10745094144 1375528368 14.377 100 65 130 1 1437451552 44416528 17506656
20:56:43 9197 8192000000 10328546096 10733662208 1359785776 14.235 99 77 151 1 1424536752 45664864 19086112
20:57:58 5770 8192000000 10315247520 10730000384 1365205808 14.284 100 54 106 2 1425726544 47181008 13339728
20:59:11 2181 8192000000 10339967936 10742890496 1371362768 14.339 100 89 177 2 1441112336 47739984 22009584
21:00:18 12638 8192000000 10315282624 10734403584 1357623808 14.216 99 32 64 2 1407196224 40754112 8818304
21:01:37 23423 8192000000 10307332320 10726416384 1363870880 14.272 100 42 84 1 1412896304 37557168 11468256
21:03:11 8615 8192000000 10314363152 10732851200 1360774112 14.244 100 43 87 1 1416106720 44784992 10547616
21:04:29 13080 8192000000 10315659904 10740973568 1363213824 14.266 100 30 60 1 1420908304 49461632 8232848
21:06:05 25769 8192000000 10314763456 10749890560 1355597168 14.198 100 20 40 0 1413996624 51778400 6621056
21:07:32 50113 8192000000 10281902192 10730307584 1333861952 14.002 98 13 26 1 1374790144 37851344 3076848
21:10:29 44675 8192000000 10309803920 10743574528 1369398208 14.322 99 42 84 2 1401448480 21464768 10585504
21:12:33 129374 8192000000 10311902016 10753040384 1381018720 14.426 99 18 36 1 1397092768 12110080 3963968
21:15:53 31529 8192000000 10304076112 10758819840 1398962736 14.586 100 29 58 0 1415448960 9326016 7160208
21:17:52 17687 8192000000 10326258816 10763771904 1406334256 14.651 100 50 94 0 1427235792 9247728 11653808
21:19:19 43860 8192000000 10300776416 10760810496 1403883584 14.630 100 19 37 0 1417248848 9598192 3767072
21:21:03 26004 8192000000 10309301584 10768510976 1409787664 14.682 100 14 28 1 1422165632 9418800 2959168
21:22:36 37411 8192000000 10305154544 10775707648 1398681536 14.583 99 5 10 0 1411335376 11492544 1161296
21:24:14 42831 8192000000 10301838608 10759004160 1401604560 14.609 100 16 32 2 1414008640 8210784 4193296
21:25:57 106733 8192000000 10306140032 10778148864 1410636288 14.690 100 14 28 1 1427734224 13579056 3518880
21:28:52 22454 8192000000 10308609424 10777870336 1409213376 14.677 100 11 22 0 1426146720 14440704 2492640
21:30:15 55253 8192000000 10316063440 10778193920 1407000912 14.657 99 27 54 0 1423798512 10075424 6722176
21:32:36 61470 8192000000 10300132784 10772090880 1361839232 14.254 96 13 26 0 1376764048 11390464 3534352
21:34:38 28841 8192000000 10291409232 10742951936 1372120944 14.346 99 27 54 1 1390904848 12624224 6159680
21:36:11 48753 8192000000 10318264320 10765729792 1382491280 14.439 99 13 26 0 1404351216 18305344 3554592
21:38:02 48081 8192000000 10286919712 10742312960 1345238256 14.105 97 18 36 0 1360669600 11399072 4032272
21:39:53 37217 8192000000 10264307552 10712883200 1326119936 13.932 97 17 34 1 1341415904 11490848 3805120
21:41:32 20593 8192000000 10252240000 10679971840 1305217968 13.743 97 39 78 0 1323679056 9932576 8528512
21:42:55 34184 8192000000 10236595152 10664984576 1221472032 12.975 91 31 63 0 1242030528 14085808 6472688
21:44:35 99017 8192000000 10240343888 10683244544 1279272576 13.506 96 13 24 1 1293895440 11875824 2747040
21:47:15 116923 8192000000 10247106848 10682634240 1295363408 13.653 97 25 48 0 1310099824 9514752 5221664
21:50:14 73938 8192000000 10233405408 10690719744 1315812976 13.839 98 7 14 0 1325281792 8001520 1467296
21:52:28 47253 8192000000 10251431104 10696732672 1336057024 14.022 99 11 22 1 1352005904 13265840 2683040
21:54:21 70696 8192000000 10267066688 10716221440 1356364432 14.205 100 23 45 0 1381172384 19194544 5613408
21:57:14 74799 8192000000 10260573136 10716905472 1316807600 13.848 97 19 37 1 1330696864 9684896 4204368
21:59:30 63086 8192000000 10263543312 10716930048 1336543072 14.026 98 17 34 0 1356383696 15803168 4037456
22:01:38 104031 8192000000 10283033968 10743365632 1362598800 14.261 99 19 37 2 1378956640 12432592 3925248
22:04:28 107502 8192000000 10295350000 10748862464 1387404848 14.483 100 14 28 0 1400681296 10377568 2898880
22:07:16 20240 8192000000 10318044880 10758807552 1379605376 14.413 99 44 88 0 1400798336 11778192 9414768
22:08:39 132316 8192000000 10300509424 10761494528 1377215024 14.392 99 20 40 0 1396493888 14763520 4515344
22:11:52 46818 8192000000 10294436704 10761576448 1401698240 14.610 100 13 26 0 1413004496 8037744 3268512
22:13:39 42854 8192000000 10299596304 10768777216 1405721696 14.646 100 5 10 0 1414767776 8053104 992976
22:15:22 68399 8192000000 10306913616 10764386304 1373694032 14.360 98 17 33 2 1388401088 10700544 4006512
22:17:51 98425 8192000000 10293695504 10758242304 1336622288 14.027 95 16 32 0 1350949888 10466480 3861120
22:20:31 83747 8192000000 10285581408 10741555200 1333678320 14.000 96 20 40 1 1352037648 14001472 4357856
22:23:02 38838 8192000000 10305216800 10758057984 1391210896 14.517 100 19 38 2 1410813136 15525360 4076880
22:24:41 85611 8192000000 10292422624 10753081344 1336560352 14.026 96 13 26 0 1362787824 23203360 3024112
22:27:11 94043 8192000000 10249421152 10709712896 1263330640 13.361 92 19 37 1 1281948224 14203968 4413616
22:30:38 16581 8192000000 10278424944 10722598912 1353375408 14.178 99 43 84 3 1375314368 11714160 10224800
22:31:55 87199 8192000000 10220226848 10682990592 1297795504 13.675 97 9 18 1 1311313120 11409200 2108416
22:34:22 50887 8192000000 10236405216 10684743680 1312268272 13.807 97 20 38 2 1324592240 8047872 4276096
22:36:18 56173 8192000000 10243864672 10697588736 1302661952 13.719 97 9 18 0 1312016032 7568160 1785920
22:38:16 11052 8192000000 9015072688 9220788224 185226912 2.211 14 4 7 0 196108832 9951616 930304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-06-2022 22:40:06 to 06-06-2022 22:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 87 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 06-06-2022 22:40:22 to 06-06-2022 22:40:23.

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

06.06.22 22:40:24. 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-06-06 22:40:27 No severe PSQL-related problems occured
06.06.22 22:40:28. End of report.