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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16204156 kB
MemAvailable 31253944 kB
Buffers 0 kB
Cached 15540132 kB
SwapCached 2508 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-24 19:38:22
DTS_END                         2022-06-24 19:38:22.3160
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/38456
TRN_ID                          245244
ATT_ID                          664
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: 9039.99 22874 1084799
customer order: creation 176.61 337 21193
customer order: refuse 14.46 343 1735
order to supplier: creation 47.23 1224 5667
order to supplier: removal 3.21 101158 385
invoice (draft): creation 46.49 3317 5579
invoice (draft): removal 49.17 792 5900
invoice accept: apply 43.16 17718 5179
invoice accept: cancel 44.32 763 5318
customer reserve: creation 3907.28 177 468874
customer reserve: removal 4404.70 58 528564
realization accept: apply 85.65 187 10278
realization accept: cancel 84.44 103 10133
payment from customer: creation 61.98 120 7438
payment from customer: removal 6.13 89 736
payment to supplier: creation 49.23 120 5908
payment to supplier: removal 3.37 83 404
service: total inventory turnovers 4.87 1152 584
service: total monetary turnovers 6.85 30 822
service: aggregate perf. data 0.82 23283 98
service: refresh index statistics 0.03 306430 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-06-2022 19:40:21 to 24-06-2022 19:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1551
TEST_TIME 2 3950
TEST_TIME 3 4291
TEST_TIME 4 4381
TEST_TIME 5 4611
TEST_TIME 6 4884
TEST_TIME 7 5156
TEST_TIME 8 5243
TEST_TIME 9 5275
TEST_TIME 10 6112
TEST_TIME 11 6273
TEST_TIME 12 6411
TEST_TIME 13 6961
TEST_TIME 14 6917
TEST_TIME 15 7443
TEST_TIME 16 7751
TEST_TIME 17 7691
TEST_TIME 18 8183
TEST_TIME 19 8317
TEST_TIME 20 8512
TEST_TIME 21 9083
TEST_TIME 22 8938
TEST_TIME 23 9164
TEST_TIME 24 9608
TEST_TIME 25 9715
TEST_TIME 26 9969
TEST_TIME 27 10255
TEST_TIME 28 10263
TEST_TIME 29 10611
TEST_TIME 30 10806
TEST_TIME 31 10529
TEST_TIME 32 10572
TEST_TIME 33 10373
TEST_TIME 34 10285
TEST_TIME 35 10093
TEST_TIME 36 10076
TEST_TIME 37 10165
TEST_TIME 38 10090
TEST_TIME 39 10075
TEST_TIME 40 9932
TEST_TIME 41 9871
TEST_TIME 42 9898
TEST_TIME 43 9765
TEST_TIME 44 9649
TEST_TIME 45 9689
TEST_TIME 46 9564
TEST_TIME 47 9489
TEST_TIME 48 9412
TEST_TIME 49 9465
TEST_TIME 50 9379
TEST_TIME 51 9302
TEST_TIME 52 9403
TEST_TIME 53 9442
TEST_TIME 54 9367
TEST_TIME 55 9351
TEST_TIME 56 9408
TEST_TIME 57 9485
TEST_TIME 58 9506
TEST_TIME 59 9504
TEST_TIME 60 9478
TEST_TIME 61 9501
TEST_TIME 62 9616
TEST_TIME 63 9605
TEST_TIME 64 9666
TEST_TIME 65 9622
TEST_TIME 66 9563
TEST_TIME 67 9524
TEST_TIME 68 9482
TEST_TIME 69 9403
TEST_TIME 70 9453
TEST_TIME 71 9442
TEST_TIME 72 9413
TEST_TIME 73 9365
TEST_TIME 74 9298
TEST_TIME 75 9234
TEST_TIME 76 9178
TEST_TIME 77 9177
TEST_TIME 78 9214
TEST_TIME 79 9166
TEST_TIME 80 9160
TEST_TIME 81 9217
TEST_TIME 82 9233
TEST_TIME 83 9231
TEST_TIME 84 9249
TEST_TIME 85 9322
TEST_TIME 86 9314
TEST_TIME 87 9292
TEST_TIME 88 9359
TEST_TIME 89 9362
TEST_TIME 90 9374
TEST_TIME 91 9331
TEST_TIME 92 9254
TEST_TIME 93 9233
TEST_TIME 94 9234
TEST_TIME 95 9211
TEST_TIME 96 9165
TEST_TIME 97 9223
TEST_TIME 98 9191
TEST_TIME 99 9215
TEST_TIME 100 9174
TEST_TIME 101 9325
TEST_TIME 102 9320
TEST_TIME 103 9280
TEST_TIME 104 9289
TEST_TIME 105 9321
TEST_TIME 106 9336
TEST_TIME 107 9363
TEST_TIME 108 9395
TEST_TIME 109 9393
TEST_TIME 110 9331
TEST_TIME 111 9259
TEST_TIME 112 9306
TEST_TIME 113 9255
TEST_TIME 114 9191
TEST_TIME 115 9178
TEST_TIME 116 9181
TEST_TIME 117 9155
TEST_TIME 118 9113
TEST_TIME 119 9095
TEST_TIME 120 9041

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-06-2022 19:40:25 to 24-06-2022 19:40:28.

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 1519621 1519621 0 0.00 0 26618 6 0 0
doc_list_biud 1519621 1519621 0 0.00 0 56360 26 0 0
sp_add_invoice_to_stock 5299 5179 120 2.26 29 206155 17718 0 120
sp_cancel_adding_invoice 5318 5318 0 0.00 5 77347 764 0 0
sp_cancel_client_order 1735 1735 0 0.00 3 24719 344 0 0
sp_cancel_customer_reserve 528564 528564 0 0.00 1 56361 58 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 8566 89 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 4658 83 0 0
sp_cancel_supplier_invoice 5909 5900 9 0.15 1 22095 793 0 9
sp_cancel_supplier_order 385 385 0 0.00 7 841297 101159 0 0
sp_cancel_write_off 10133 10133 0 0.00 1 13273 104 0 0
sp_client_order 21193 21193 0 0.00 3 21181 337 0 0
sp_customer_reserve 468874 468874 0 0.00 0 53301 178 0 0
sp_fill_shopping_cart 32429 32429 0 0.00 1 18638 39 0 0
sp_fill_shopping_cart_clo_res 468872 468872 0 0.00 0 15558 14 0 0
sp_get_clo_for_invoice 5177 5177 0 0.00 0 31196 320 0 0
sp_kill_cost_storno 16591 16591 0 0.00 0 8562 54 0 0
sp_kill_qstorno_ret_qs2qd 1073546 1073546 0 0.00 0 53559 31 0 0
sp_kill_qty_storno 567489 567489 0 0.00 0 56360 69 0 0
sp_lock_selected_doc 21189 21189 0 0.00 0 7349 4 0 0
sp_make_cost_storno 28800 28800 0 0.00 0 26613 48 0 0
sp_multiply_rows_for_pdistr 28800 28800 0 0.00 0 9223 28 0 0
sp_multiply_rows_for_qdistr 21193 21193 0 0.00 0 11706 138 0 0
sp_pay_from_customer 7438 7438 0 0.00 4 13851 120 0 0
sp_pay_to_supplier 5908 5908 0 0.00 5 9256 120 0 0
sp_payment_common 13346 13346 0 0.00 2 10149 22 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 24713 305 0 0
sp_qd_handle_on_invoice_upd_sts 10494 10494 0 0.00 0 24333 337 0 0
sp_qd_handle_on_reserve_upd_sts 10278 10278 0 0.00 0 18319 64 0 0
sp_reserve_write_off 10278 10278 0 0.00 6 26670 187 0 0
sp_supplier_invoice 5579 5579 0 0.00 7 82224 3317 0 0
sp_supplier_order 5667 5667 0 0.00 5 36947 1225 0 0
srv_aggregate_perf_data 99 98 1 1.01 2813 390177 23283 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1827 201083 44009 0 0
srv_find_qd_qs_mism 1036318 1036318 0 0.00 0 21537 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 41 38372 1153 0 0
srv_make_money_saldo 822 822 0 0.00 1 3404 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 235547 404898 306431 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5179 5179 0 0.00 0 20977 185 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 8024 161 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 2493 13 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1632 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 1765 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1170 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1455 18 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 43557 1810 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 7276 16 0 0
t$perf-norm:sp_client_order 21193 21193 0 0.00 0 9750 22 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 2492 13 0 0
t$perf-norm:sp_pay_from_customer 7438 7438 0 0.00 0 11599 15 0 0
t$perf-norm:sp_pay_to_supplier 5908 5908 0 0.00 0 6377 15 0 0
t$perf-norm:sp_reserve_write_off 10278 10278 0 0.00 0 10522 13 0 0
t$perf-norm:sp_supplier_invoice 5579 5579 0 0.00 0 10131 23 0 0
t$perf-norm:sp_supplier_order 5667 5667 0 0.00 0 7167 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 327 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 73 4 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 3598 34 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 3739 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 44 13 0 0
v_add_invoice_to_stock 10592 10352 240 2.26 0 8952 16 0 240
v_all_customers 38434 38434 0 0.00 0 18 1 0 0
v_all_suppliers 30510 30510 0 0.00 0 45 1 0 0
v_all_wares 42386 42386 0 0.00 0 59 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3146 10 0 0
v_cancel_client_order 3470 3470 0 0.00 0 5069 11 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 3564 9 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 5811 8 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 4618 16 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 815 13 0 18
v_cancel_supplier_order 770 770 0 0.00 0 5630 19 0 0
v_cancel_write_off 2006 2006 0 0.00 0 10023 19 0 0
v_max_id_clo_ord 5666 5666 0 0.00 0 4061 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 2364 13 0 0
v_max_id_ord_sup 5570 5570 0 0.00 0 2115 3 0 0
v_max_non_paid_invoice 1889 1889 0 0.00 0 85 2 0 0
v_max_non_paid_realizn 4080 4080 0 0.00 0 9463 7 0 0
v_min_id_clo_ord 5666 5666 0 0.00 0 1790 4 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 3474 8 0 0
v_min_id_ord_sup 5570 5570 0 0.00 0 8164 6 0 0
v_min_non_paid_invoice 5908 5908 0 0.00 0 3735 11 0 0
v_min_non_paid_realizn 7438 7438 0 0.00 0 10143 14 0 0
v_our_firm 10668 10668 0 0.00 0 19 1 0 0
v_reserve_write_off 20556 20556 0 0.00 0 13806 11 0 0
x_lock_depdocs_on_canc_invoice 10803 10803 0 0.00 0 14808 118 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 8380 346 0 0
x_make_qstorno_1000_1200 5667 5667 0 0.00 1 36940 1144 0 0
x_make_qstorno_1200_2000 5579 5579 0 0.00 3 82210 3167 0 0
x_make_qstorno_2100_3300 468874 468874 0 0.00 0 53258 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 19:40:29 to 24-06-2022 19:40:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:26 11 8192000000 8806719328 8820531200 11844272 0.144 3 3 5 0 12038752 77360 117120
17:09:27 27008 8192000000 9915253600 10226147328 991315328 10.794 100 89 178 0 1021030304 6427264 23287712
17:11:03 20106 8192000000 9950988800 10273632256 1031752592 11.185 100 89 178 0 1062973776 8307264 22913920
17:12:33 14370 8192000000 9977254896 10312101888 1068000672 11.533 100 83 166 0 1098422208 9265808 21155728
17:13:54 6610 8192000000 10039941744 10385408000 1123067472 12.056 100 87 174 0 1155450304 11216944 21165888
17:15:07 9133 8192000000 10100027648 10460667904 1182752080 12.616 100 82 165 1 1214309488 12762000 18795408
17:16:21 4709 8192000000 10139478688 10504990720 1217341408 12.937 100 85 170 2 1251355824 15261376 18753040
17:17:27 2946 8192000000 10161311248 10536001536 1245706896 13.199 100 83 164 1 1277961376 14454160 17800320
17:18:32 1917 8192000000 10201205760 10581561344 1281696368 13.528 100 94 185 3 1316006384 14355808 19954208
17:19:35 43000 8192000000 10197414576 10600779776 1303108432 13.723 100 37 73 2 1316870224 5543600 8218192
17:21:19 4561 8192000000 10210470768 10618134528 1316059408 13.841 100 35 70 0 1332261744 7890800 8311536
17:22:24 7202 8192000000 10218118064 10625941504 1325837840 13.930 100 32 62 2 1340069568 7302976 6928752
17:23:33 7757 8192000000 10216054256 10636255232 1331395520 13.980 100 25 48 0 1342264272 5284944 5583808
17:24:41 45513 8192000000 10256537040 10657742848 1339815424 14.056 100 52 105 3 1358090608 5499232 12775952
17:26:27 6188 8192000000 10243283200 10663759872 1343515504 14.089 100 39 78 1 1364335696 12173120 8647072
17:27:35 5075 8192000000 10221428752 10639605760 1321048224 13.886 99 28 56 0 1333443632 6376960 6018448
17:28:42 61861 8192000000 10200319040 10613809152 1239866544 13.145 93 37 72 0 1253711216 5980160 7864512
17:30:47 7032 8192000000 10146166320 10545856512 1253205424 13.268 99 40 78 1 1272309616 9821760 9282432
17:31:54 7999 8192000000 10131508016 10526552064 1217094256 12.935 98 24 48 0 1228994608 6233952 5666400
17:33:02 17737 8192000000 10157561056 10561744896 1252195360 13.258 99 18 36 2 1266132096 9053408 4883328
17:34:20 28295 8192000000 10161276304 10571210752 1274528416 13.463 100 22 43 0 1285881376 6576736 4776224
17:35:49 6584 8192000000 10181016176 10592669696 1291725408 13.620 100 26 51 0 1303172832 5189776 6257648
17:36:56 10046 8192000000 10190653952 10610155520 1305777984 13.748 100 36 72 0 1320361856 5757504 8826368
17:38:07 68659 8192000000 10219634832 10634616832 1319778672 13.875 100 28 55 1 1330386912 4353408 6254832
17:40:26 5768 8192000000 10258465088 10668687360 1347569200 14.126 100 55 110 0 1371528384 10986528 12972656
17:41:36 22103 8192000000 10271512304 10689593344 1362585648 14.261 100 40 80 0 1381922560 10149024 9187888
17:43:01 14051 8192000000 10286510864 10700677120 1361446480 14.250 99 52 104 1 1387942960 14542144 11954336
17:44:25 13866 8192000000 10306277728 10724229120 1387803440 14.486 100 58 113 0 1417996800 16580800 13612560
17:45:45 14310 8192000000 10310101872 10734104576 1390523696 14.511 100 45 90 1 1423735856 22450384 10761776
17:47:04 5490 8192000000 10314729056 10732834816 1389532624 14.502 100 58 116 1 1425980944 22401664 14046656
17:48:12 4967 8192000000 10320052560 10730774528 1373170480 14.355 99 69 135 1 1416071472 27336800 15564192
17:49:21 3427 8192000000 10337638224 10753028096 1399312416 14.589 100 62 122 2 1439467216 25815984 14338816
17:50:29 3978 8192000000 10334926176 10746441728 1387388704 14.483 99 76 153 3 1435366624 29789888 18188032
17:51:37 7401 8192000000 10329483744 10751270912 1396084608 14.560 100 66 130 0 1444114464 31823776 16206080
17:52:52 1830 8192000000 10338694528 10747138048 1378921552 14.407 100 81 163 0 1440098576 40451408 20725616
17:54:00 5103 8192000000 10326906832 10740244480 1383027888 14.444 100 66 130 0 1431212176 31877584 16306704
17:55:14 1825 8192000000 10339159584 10743975936 1379506592 14.412 100 90 181 1 1437354848 34745168 23103088
17:56:19 3490 8192000000 10327570560 10739470336 1378948048 14.407 100 73 146 1 1433878064 36168752 18761264
17:57:29 10111 8192000000 10309824688 10731520000 1368234688 14.311 100 55 110 0 1419052416 36458352 14359376
17:58:44 7224 8192000000 10321016800 10728894464 1362279216 14.258 100 60 120 0 1421268352 42374128 16615008
17:59:59 7535 8192000000 10302876048 10722455552 1363642416 14.270 100 48 97 1 1408515552 32469872 12403264
18:01:11 8186 8192000000 10309746544 10719674368 1348511040 14.134 99 79 160 2 1405182944 36872704 19799200
18:02:24 16627 8192000000 10307749008 10737217536 1374471600 14.367 100 29 57 1 1416130464 34160976 7497888
18:03:47 22420 8192000000 10307629312 10742910976 1370726448 14.334 100 28 56 0 1415933312 37586848 7620016
18:05:14 21272 8192000000 10299044032 10745757696 1370229008 14.329 100 24 48 0 1410456944 33714912 6513024
18:06:36 40860 8192000000 10302699120 10750717952 1367149792 14.302 100 22 44 2 1409505856 36592736 5763328
18:08:20 60060 8192000000 10287057856 10721648640 1362443536 14.259 99 22 44 0 1381209200 14061808 4703856
18:10:26 39606 8192000000 10313546720 10738302976 1371229808 14.338 99 46 93 3 1391127696 9338944 10558944
18:12:07 38741 8192000000 10316772704 10756571136 1391948080 14.523 100 35 71 0 1411244256 10854000 8442176
18:14:02 22745 8192000000 10313214912 10760544256 1396896224 14.567 100 62 118 4 1428055584 17320208 13839152
18:15:48 36762 8192000000 10281611296 10736578560 1373982320 14.363 99 17 34 0 1386695248 8582544 4130384
18:17:32 32860 8192000000 10265393312 10723045376 1349233504 14.141 98 11 24 0 1358685872 7306336 2146032
18:19:13 64120 8192000000 10297754512 10747928576 1393001424 14.533 100 15 31 0 1405647792 9504752 3141616
18:22:24 46819 8192000000 10276921456 10733117440 1349277200 14.141 97 38 76 1 1369367728 11178896 8911632
18:24:17 77072 8192000000 10296816832 10761814016 1395146608 14.552 100 23 46 3 1414073152 13708608 5217936
18:26:37 54663 8192000000 10285801120 10755608576 1366299392 14.294 98 12 24 1 1381567504 12256304 3011808
18:28:36 67028 8192000000 10293568832 10752393216 1373030400 14.354 99 14 28 0 1395408224 19225392 3152432
18:30:50 44181 8192000000 10276790480 10748424192 1359942000 14.237 98 6 12 0 1378315408 16972912 1400496
18:32:36 19025 8192000000 10305471152 10755559424 1391847584 14.522 100 36 70 1 1410655984 10228912 8579488
18:33:56 83315 8192000000 10298059776 10758692864 1394594704 14.547 100 18 35 1 1414505104 15269728 4640672
18:36:46 63879 8192000000 10274090816 10726117376 1294691584 13.647 93 26 50 0 1308807536 8463120 5652832
18:39:07 105601 8192000000 10250835792 10713501696 1194876944 12.729 87 14 28 1 1208833136 10955408 3000784
18:42:58 66575 8192000000 10277652800 10714660864 1321221536 13.888 96 20 40 0 1335335520 9420752 4693232
18:45:06 62003 8192000000 10271809248 10716340224 1358770000 14.226 99 37 74 0 1377495360 10582000 8143360
18:47:12 13326 8192000000 10273112416 10724335616 1366037264 14.292 100 26 52 0 1385044816 13375808 5631744
18:48:25 54419 8192000000 10266170672 10726617088 1353326992 14.177 99 22 43 0 1375205280 15927920 5950368
18:50:26 37986 8192000000 10259111328 10721587200 1348317296 14.132 99 4 8 0 1364531600 15488592 725712
18:52:05 32967 8192000000 10257327408 10714263552 1369718592 14.325 100 6 12 0 1379205520 8091216 1395712
18:53:41 6133 8192000000 10237752176 10693595136 1324608576 13.918 97 14 29 0 1336529552 8576032 3344944
18:54:48 68161 8192000000 10238749664 10700009472 1326485168 13.935 98 8 16 0 1339063936 10718288 1860480
18:57:02 49252 8192000000 10256546928 10700910592 1302573008 13.719 96 21 42 1 1316815328 9853152 4389168
18:58:55 41614 8192000000 10242167648 10700853248 1313786096 13.820 97 19 38 0 1331534048 13262896 4485056
19:00:39 80450 8192000000 10277564224 10730913792 1353722800 14.181 99 14 28 0 1377844688 20740464 3381424
19:03:00 112776 8192000000 10273329328 10723307520 1323475856 13.908 96 15 30 0 1339454176 12943712 3034608
19:05:55 31406 8192000000 10294064016 10735792128 1365492736 14.287 99 39 78 1 1388467904 14582608 8392560
19:07:32 96191 8192000000 10284026928 10737160192 1348011312 14.130 97 14 28 0 1362002720 11422496 2568912
19:10:40 66615 8192000000 10298316528 10760458240 1381987024 14.434 99 21 42 0 1399110832 12368272 4755536
19:12:46 43416 8192000000 10300332848 10781179904 1397836576 14.576 100 6 12 1 1415985440 16499040 1649824
19:14:33 56246 8192000000 10299658240 10758660096 1372903520 14.353 98 24 48 0 1390279184 11048704 6326960
19:16:34 54601 8192000000 10294213632 10751193088 1377554448 14.395 99 17 34 0 1393356240 11768640 4033152
19:18:41 111186 8192000000 10309330672 10800664576 1386669792 14.476 99 5 10 0 1406011184 18201424 1139968
19:22:09 32357 8192000000 10298955600 10748862464 1372228368 14.347 99 33 66 2 1391627232 11840752 7558112
19:23:41 201082 8192000000 10286252176 10745794560 1267772992 13.401 91 11 22 0 1282092464 11673344 2646128
19:28:17 123138 8192000000 10278946784 10737950720 1356573488 14.207 99 26 52 0 1372470480 9640448 6256544
19:31:29 49409 8192000000 10272036336 10746449920 1371656160 14.342 100 5 10 0 1391440752 18253392 1531200
19:33:33 72164 8192000000 10270207392 10739236864 1367559248 14.305 99 9 18 0 1377221104 7815024 1846832
19:35:45 86023 8192000000 10291134912 10749399040 1390144240 14.507 100 18 36 0 1402493920 8098288 4251392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-06-2022 19:40:35 to 24-06-2022 19:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 120 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 19:40:51 to 24-06-2022 19:40:53.

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          244318
MON$OLDEST_ACTIVE               245943
MON$OLDEST_SNAPSHOT             245943
MON$NEXT_TRANSACTION            245943
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                       2040864
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:371: 
:372: mscs-test-161.budzdorov.ru    Fri Jun 24 19:38:25 2022
:373:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:374: 
:375: 
:376: mscs-test-161.budzdorov.ru    Fri Jun 24 19:38:39 2022
:377:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:378: 
:379: 
:380: mscs-test-161.budzdorov.ru    Fri Jun 24 19:38:39 2022
:381:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:382: 
:383: 
:384: mscs-test-161.budzdorov.ru    Fri Jun 24 19:38:40 2022
:385:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:386: 
--- end of diff output ---

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

24.06.22 19:40:54. 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-24 19:40:56 No severe PSQL-related problems occured
24.06.22 19:40:57. End of report.