Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2021 16:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15583816 kB
MemAvailable 30672344 kB
Buffers 0 kB
Cached 16065384 kB
SwapCached 4788 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.8
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 2021-10-06 16:37:54
DTS_END                         2021-10-06 16:37:54.1300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(151:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56946
TRN_ID                          250843
ATT_ID                          663
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: 9551.43 18514 1146172
customer order: creation 183.80 326 22056
customer order: refuse 15.10 359 1812
order to supplier: creation 47.51 1248 5701
order to supplier: removal 3.57 100639 428
invoice (draft): creation 49.29 3394 5915
invoice (draft): removal 55.55 775 6666
invoice accept: apply 42.80 18668 5136
invoice accept: cancel 49.83 778 5979
customer reserve: creation 3959.03 186 475084
customer reserve: removal 4826.69 58 579203
realization accept: apply 86.44 180 10373
realization accept: cancel 97.87 105 11744
payment from customer: creation 62.16 115 7459
payment from customer: removal 5.92 69 710
payment to supplier: creation 50.43 113 6052
payment to supplier: removal 3.53 82 424
service: total inventory turnovers 4.52 1097 542
service: total monetary turnovers 6.58 43 789
service: aggregate perf. data 0.79 22038 95
service: refresh index statistics 0.03 220015 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2021 16:38:57 to 06-10-2021 16:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2385
TEST_TIME 2 3398
TEST_TIME 3 3154
TEST_TIME 4 3868
TEST_TIME 5 4579
TEST_TIME 6 4596
TEST_TIME 7 5209
TEST_TIME 8 5327
TEST_TIME 9 5638
TEST_TIME 10 5773
TEST_TIME 11 6202
TEST_TIME 12 6324
TEST_TIME 13 6498
TEST_TIME 14 6442
TEST_TIME 15 6511
TEST_TIME 16 6810
TEST_TIME 17 7192
TEST_TIME 18 7233
TEST_TIME 19 7618
TEST_TIME 20 7986
TEST_TIME 21 7927
TEST_TIME 22 8330
TEST_TIME 23 8771
TEST_TIME 24 8738
TEST_TIME 25 8870
TEST_TIME 26 9402
TEST_TIME 27 9752
TEST_TIME 28 9617
TEST_TIME 29 9624
TEST_TIME 30 10101
TEST_TIME 31 10601
TEST_TIME 32 10721
TEST_TIME 33 10672
TEST_TIME 34 10532
TEST_TIME 35 10881
TEST_TIME 36 10862
TEST_TIME 37 10803
TEST_TIME 38 10813
TEST_TIME 39 10699
TEST_TIME 40 10523
TEST_TIME 41 10334
TEST_TIME 42 10213
TEST_TIME 43 10286
TEST_TIME 44 10202
TEST_TIME 45 10170
TEST_TIME 46 10240
TEST_TIME 47 10189
TEST_TIME 48 10044
TEST_TIME 49 10044
TEST_TIME 50 9950
TEST_TIME 51 10201
TEST_TIME 52 10126
TEST_TIME 53 10038
TEST_TIME 54 9981
TEST_TIME 55 9996
TEST_TIME 56 9919
TEST_TIME 57 9875
TEST_TIME 58 9797
TEST_TIME 59 9826
TEST_TIME 60 9731
TEST_TIME 61 9694
TEST_TIME 62 9761
TEST_TIME 63 9717
TEST_TIME 64 9712
TEST_TIME 65 9797
TEST_TIME 66 9824
TEST_TIME 67 9773
TEST_TIME 68 9774
TEST_TIME 69 9784
TEST_TIME 70 9856
TEST_TIME 71 9899
TEST_TIME 72 9863
TEST_TIME 73 9934
TEST_TIME 74 9981
TEST_TIME 75 9939
TEST_TIME 76 9879
TEST_TIME 77 9811
TEST_TIME 78 9860
TEST_TIME 79 9770
TEST_TIME 80 9715
TEST_TIME 81 9655
TEST_TIME 82 9695
TEST_TIME 83 9684
TEST_TIME 84 9660
TEST_TIME 85 9595
TEST_TIME 86 9595
TEST_TIME 87 9746
TEST_TIME 88 9716
TEST_TIME 89 9742
TEST_TIME 90 9702
TEST_TIME 91 9755
TEST_TIME 92 9724
TEST_TIME 93 9754
TEST_TIME 94 9765
TEST_TIME 95 9760
TEST_TIME 96 9773
TEST_TIME 97 9794
TEST_TIME 98 9801
TEST_TIME 99 9851
TEST_TIME 100 9821
TEST_TIME 101 9924
TEST_TIME 102 9848
TEST_TIME 103 9764
TEST_TIME 104 9714
TEST_TIME 105 9698
TEST_TIME 106 9700
TEST_TIME 107 9653
TEST_TIME 108 9633
TEST_TIME 109 9608
TEST_TIME 110 9565
TEST_TIME 111 9532
TEST_TIME 112 9569
TEST_TIME 113 9542
TEST_TIME 114 9537
TEST_TIME 115 9567
TEST_TIME 116 9569
TEST_TIME 117 9547
TEST_TIME 118 9524
TEST_TIME 119 9545
TEST_TIME 120 9553

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-10-2021 16:39:01 to 06-10-2021 16:39:04.

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 1587871 1587871 0 0.00 0 11718 6 0 0
doc_list_biud 1587871 1587871 0 0.00 0 41880 27 0 0
sp_add_invoice_to_stock 5217 5136 81 1.55 33 171603 18668 0 81
sp_cancel_adding_invoice 5979 5979 0 0.00 5 82371 778 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 14453 360 0 0
sp_cancel_customer_reserve 579203 579203 0 0.00 1 22964 58 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 5420 69 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 6819 82 0 0
sp_cancel_supplier_invoice 6669 6666 3 0.04 1 20345 775 0 3
sp_cancel_supplier_order 428 428 0 0.00 8 1126164 100639 0 0
sp_cancel_write_off 11744 11744 0 0.00 1 9153 106 0 0
sp_client_order 22056 22056 0 0.00 3 20426 326 0 0
sp_customer_reserve 475084 475084 0 0.00 0 46683 186 0 0
sp_fill_shopping_cart 33667 33667 0 0.00 1 29832 38 0 0
sp_fill_shopping_cart_clo_res 475083 475083 0 0.00 0 16085 12 0 0
sp_get_clo_for_invoice 5133 5133 0 0.00 0 19276 222 0 0
sp_kill_cost_storno 18857 18857 0 0.00 0 9151 56 0 0
sp_kill_qstorno_ret_qs2qd 1177244 1177244 0 0.00 0 31994 31 0 0
sp_kill_qty_storno 621338 621338 0 0.00 0 41879 68 0 0
sp_lock_selected_doc 21505 21505 0 0.00 0 8403 6 0 0
sp_make_cost_storno 29017 29017 0 0.00 0 9394 49 0 0
sp_multiply_rows_for_pdistr 29017 29017 0 0.00 0 8804 31 0 0
sp_multiply_rows_for_qdistr 22056 22056 0 0.00 0 11083 132 0 0
sp_pay_from_customer 7459 7459 0 0.00 4 10799 116 0 0
sp_pay_to_supplier 6052 6052 0 0.00 4 8689 113 0 0
sp_payment_common 13510 13510 0 0.00 1 9500 28 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10632 328 0 0
sp_qd_handle_on_invoice_upd_sts 11112 11112 0 0.00 0 41877 344 0 0
sp_qd_handle_on_reserve_upd_sts 10373 10373 0 0.00 0 16112 56 0 0
sp_reserve_write_off 10373 10373 0 0.00 6 16176 180 0 0
sp_supplier_invoice 5915 5915 0 0.00 7 95382 3395 0 0
sp_supplier_order 5701 5701 0 0.00 6 38309 1249 0 0
srv_aggregate_perf_data 96 95 1 1.04 2118 356230 22038 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1518 146669 43395 0 0
srv_find_qd_qs_mism 1097283 1097283 0 0.00 0 11718 6 0 0
srv_make_invnt_saldo 542 542 0 0.00 25 34279 1097 0 0
srv_make_money_saldo 789 789 0 0.00 1 11231 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 61099 448109 220016 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 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 5136 5136 0 0.00 0 14728 159 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 7975 110 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 11272 18 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 6312 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 214 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1000 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 839 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 19847 1677 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 2775 9 0 0
t$perf-norm:sp_client_order 22056 22056 0 0.00 0 11304 19 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 850 3 0 0
t$perf-norm:sp_pay_from_customer 7459 7459 0 0.00 0 2491 6 0 0
t$perf-norm:sp_pay_to_supplier 6052 6052 0 0.00 0 3469 8 0 0
t$perf-norm:sp_reserve_write_off 10373 10373 0 0.00 0 7225 10 0 0
t$perf-norm:sp_supplier_invoice 5915 5915 0 0.00 0 9423 21 0 0
t$perf-norm:sp_supplier_order 5701 5701 0 0.00 0 8297 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 283 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1575 45 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 13623 42 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 579 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10428 10266 162 1.55 0 10034 21 0 162
v_all_customers 40388 40388 0 0.00 0 17 1 0 0
v_all_suppliers 31986 31986 0 0.00 0 29 1 0 0
v_all_wares 44106 44106 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 1553 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 4402 6 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2289 8 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1084 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 6768 14 0 0
v_cancel_supplier_invoice 882 876 6 0.68 0 5237 32 0 6
v_cancel_supplier_order 856 856 0 0.00 0 660 4 0 0
v_cancel_write_off 2110 2110 0 0.00 0 4628 20 0 0
v_max_id_clo_ord 5701 5701 0 0.00 0 1633 2 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 4515 18 0 0
v_max_id_ord_sup 5913 5913 0 0.00 0 2888 3 0 0
v_max_non_paid_invoice 1672 1672 0 0.00 0 3019 5 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 1656 4 0 0
v_min_id_clo_ord 5701 5701 0 0.00 0 4311 7 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 2139 8 0 0
v_min_id_ord_sup 5913 5913 0 0.00 0 7496 7 0 0
v_min_non_paid_invoice 6051 6051 0 0.00 0 6912 14 0 0
v_min_non_paid_realizn 7459 7459 0 0.00 0 9497 21 0 0
v_our_firm 10998 10998 0 0.00 0 79 1 0 0
v_reserve_write_off 20744 20744 0 0.00 0 8408 7 0 0
x_lock_depdocs_on_canc_invoice 12207 12207 0 0.00 0 12209 116 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 11293 426 0 0
x_make_qstorno_1000_1200 5701 5701 0 0.00 2 38267 1165 0 0
x_make_qstorno_1200_2000 5915 5915 0 0.00 3 89749 3263 0 0
x_make_qstorno_2100_3300 475084 475084 0 0.00 0 39898 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2021 16:39:05 to 06-10-2021 16:39:09.

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
14:07:57 29 8192000000 8828224080 8847331328 25485488 0.310 6 3 4 0 25796624 97328 213808
14:08:59 17814 8192000000 10367185616 10681872384 1428745536 14.850 100 87 174 0 1462501040 5912656 27842848
14:10:23 25410 8192000000 10448211616 10776973312 1497127584 15.451 100 89 175 2 1533345920 8219168 27999168
14:11:51 14353 8192000000 10485872432 10826514432 1539098048 15.816 100 88 176 0 1577459856 9628688 28733120
14:13:12 17497 8192000000 10565043472 10917654528 1615209472 16.469 100 82 164 2 1650282464 10981360 24091632
14:14:32 11780 8192000000 10632144912 10996137984 1691107792 17.111 100 87 173 1 1729373536 13562160 24703584
14:15:48 4484 8192000000 10685440544 11066273792 1752113760 17.619 100 76 151 0 1785921824 12642992 21165072
14:16:54 7149 8192000000 10761740736 11142328320 1820950784 18.185 100 85 170 1 1858441504 14437920 23052800
14:18:03 1524 8192000000 10812263936 11199262720 1864612384 18.541 100 88 176 0 1902910208 11971616 26326208
14:19:06 2297 8192000000 10814412176 11220385792 1881428544 18.677 100 55 108 0 1906774144 10246688 15098912
14:20:10 42315 8192000000 10824598464 11235094528 1893628080 18.775 100 60 121 3 1917109104 4527792 18953232
14:21:53 3422 8192000000 10818818336 11244732416 1897031216 18.802 100 31 63 1 1916389616 10625552 8732848
14:22:59 9326 8192000000 10818073712 11249123328 1901225488 18.836 100 23 46 1 1917432640 10004464 6202688
14:24:09 10083 8192000000 10829277648 11262210048 1914375920 18.942 100 19 38 1 1925979216 6251200 5352096
14:25:20 26462 8192000000 10834758192 11268399104 1918887616 18.978 100 31 61 2 1932833040 5476864 8468560
14:26:47 5001 8192000000 10834636176 11274047488 1925176704 19.028 100 24 48 2 1939010336 6808544 7025088
14:27:53 10715 8192000000 10790395184 11226554368 1837289264 18.319 97 16 32 0 1847330816 5529456 4512096
14:29:04 68496 8192000000 10704449520 11116990464 1623348288 16.538 88 28 56 0 1636566720 5135104 8083328
14:31:17 6889 8192000000 10711837552 11124269056 1794421120 17.968 100 35 68 0 1810188400 5523488 10243792
14:32:26 7147 8192000000 10723178208 11139948544 1798729312 18.003 99 29 58 2 1813338128 5529616 9079200
14:33:37 43099 8192000000 10770717936 11197612032 1857408016 18.482 100 36 72 1 1875796592 6174784 12213792
14:35:31 4294 8192000000 10787677632 11212701696 1867475168 18.564 100 39 75 1 1889557808 11227824 10854816
14:36:38 14356 8192000000 10783586304 11220819968 1879200320 18.659 100 21 42 0 1890783024 5789072 5793632
14:37:54 27398 8192000000 10848458064 11265404928 1914401936 18.942 100 32 60 4 1928049312 5242736 8404640
14:39:30 91840 8192000000 10882349888 11310456832 1941552800 19.159 100 39 77 1 1962606320 10191984 10861536
14:42:07 66522 8192000000 10889363216 11318312960 1948575872 19.215 100 35 70 0 1971691776 13156736 9959168
14:44:19 4103 8192000000 10917780000 11347988480 1974636640 19.422 100 55 110 0 2008197712 17948224 15612848
14:45:27 9619 8192000000 10929478592 11357544448 1968129424 19.371 99 61 122 1 2006100160 20152816 17817920
14:46:38 10045 8192000000 10923348960 11355570176 1969343264 19.380 99 43 87 1 2006590224 24140128 13106832
14:47:53 4662 8192000000 10949709888 11385274368 1981768688 19.479 99 52 104 0 2026927280 29232608 15925984
14:49:01 12325 8192000000 10937534096 11373342720 1972200736 19.403 99 42 84 0 2010738992 25028224 13510032
14:50:18 12033 8192000000 10945553824 11385786368 1992499936 19.564 100 47 93 1 2037937936 30125312 15312688
14:51:36 4251 8192000000 10955642352 11392323584 1997084848 19.600 100 47 93 1 2043924368 32698448 14141072
14:52:55 3760 8192000000 10966772560 11377655808 1952722528 19.248 98 78 154 1 2017267600 41151216 23393856
14:54:04 6989 8192000000 10903155856 11331092480 1933961216 19.099 100 51 102 0 1992583472 42419232 16203024
14:55:16 2753 8192000000 10956371456 11363962880 1967861600 19.368 100 95 189 3 2036096768 40846528 27388640
14:56:23 1592 8192000000 10940764640 11344371712 1946669424 19.200 100 92 181 1 2020850032 45577440 28603168
14:57:28 3739 8192000000 10947556288 11372830720 1962376576 19.325 100 69 137 2 2034399424 51253552 20769296
14:58:41 1518 8192000000 10958386384 11370459136 1961374144 19.317 100 84 167 3 2037029408 49186656 26468608
14:59:46 11060 8192000000 10931161264 11373875200 1942914816 19.170 99 37 74 1 2001477600 46299408 12263376
15:01:02 16482 8192000000 10925000112 11370942464 1960021744 19.306 100 36 72 1 2019382368 47065984 12294640
15:02:37 16919 8192000000 10936292512 11374854144 1947035856 19.203 100 36 72 1 2016594592 56052704 13506032
15:04:06 10135 8192000000 10932763808 11376480256 1973737904 19.415 100 43 86 0 2033388864 45673200 13977760
15:05:42 25657 8192000000 10933651824 11385131008 1971441040 19.397 100 36 71 1 2022743536 39693632 11608864
15:07:19 17676 8192000000 10919458880 11390697472 1963963744 19.338 100 17 34 0 2014078752 44335056 5779952
15:08:52 54987 8192000000 10966091936 11387523072 2001389232 19.634 100 40 78 4 2033107232 20506928 11211072
15:10:48 42031 8192000000 10928191952 11389063168 1988269072 19.530 100 18 35 0 2004739200 12173920 4296208
15:12:43 62625 8192000000 10900493088 11362508800 1937585872 19.127 97 9 18 0 1950693184 10930208 2177104
15:14:46 54435 8192000000 10924620496 11396059136 1978410160 19.452 99 14 32 2 1992244400 9316896 4517344
15:16:41 57897 8192000000 10925022688 11383595008 1976707824 19.439 99 18 36 3 1989564144 7627856 5228464
15:18:42 58833 8192000000 10959061712 11418619904 2014994272 19.741 100 31 62 2 2044416896 20444832 8977792
15:20:47 55808 8192000000 10953849840 11407392768 1993912496 19.575 99 33 67 1 2016040848 12771024 9357328
15:22:49 46934 8192000000 10941666720 11406983168 1988166736 19.529 99 33 64 1 2012901328 14914016 9820576
15:24:37 60589 8192000000 10938025168 11414110208 2013347632 19.728 100 19 38 2 2026712608 7857232 5507744
15:26:38 89689 8192000000 10955720208 11418992640 1989184096 19.537 98 20 41 0 2011241584 16389280 5668208
15:29:29 34418 8192000000 10897769728 11380957184 1981818768 19.479 100 9 18 0 1993039888 8690192 2530928
15:31:13 40784 8192000000 10853646912 11323183104 1896249376 18.796 96 18 36 1 1909705376 8224752 5231248
15:33:00 35395 8192000000 10879982848 11360780288 1959051904 19.299 100 10 20 0 1971276528 9271520 2953104
15:34:35 64337 8192000000 10864730272 11331637248 1880620448 18.670 95 30 60 1 1896656672 6771552 9264672
15:36:45 38302 8192000000 10862456320 11339776000 1911276640 18.917 98 15 30 1 1932159248 16786944 4095664
15:38:29 81572 8192000000 10840186464 11324755968 1877318528 18.643 97 11 22 2 1895118192 14854976 2944688
15:40:51 5544 8192000000 10858582320 11319603200 1910392592 18.910 98 57 101 5 1929423680 3922016 15109072
15:41:56 31731 8192000000 10876490352 11346792448 1931468624 19.079 99 30 58 1 1953048256 13135200 8444432
15:43:29 36174 8192000000 10856245536 11310174208 1851473872 18.434 95 19 36 0 1865808976 9301024 5034080
15:45:08 19746 8192000000 10872046016 11320242176 1926544512 19.039 100 48 95 3 1950749376 11090416 13114448
15:46:30 25313 8192000000 10880889856 11338301440 1922632704 19.008 99 41 82 0 1948452160 14054784 11764672
15:48:01 63594 8192000000 10874336992 11341176832 1913333168 18.933 98 24 48 0 1936554864 15996816 7224880
15:50:05 54822 8192000000 10885464640 11364012032 1911830624 18.921 98 22 44 0 1933610192 15463936 6315632
15:52:00 49375 8192000000 10893983168 11347845120 1918701648 18.976 98 31 60 1 1936013216 9026400 8285168
15:53:53 146669 8192000000 10873158608 11359870976 1918659024 18.976 98 27 54 0 1935819392 9280768 7879600
15:57:20 83747 8192000000 10895337856 11377623040 1943338992 19.173 98 28 56 1 1966918608 15347568 8232048
15:59:49 62613 8192000000 10898411120 11376947200 1965132544 19.347 100 19 37 1 1984744896 13908080 5704272
16:01:53 94369 8192000000 10921384144 11407314944 1995319344 19.586 100 16 32 0 2017425472 17490800 4615328
16:05:15 93006 8192000000 10911045232 11383803904 1928857696 19.058 97 17 34 1 1941519664 8110224 4551744
16:07:48 12999 8192000000 10944480224 11403870208 2008458592 19.689 100 43 84 1 2033144416 13697568 10988256
16:09:02 43924 8192000000 10939949248 11395981312 1964571072 19.342 98 21 43 0 1978974112 9009440 5393600
16:10:48 72159 8192000000 10926084608 11411738624 1964074736 19.338 98 31 62 1 1985515488 12610432 8830320
16:13:00 16156 8192000000 10938513024 11400675328 1979862928 19.464 99 41 83 2 2001185104 10394384 10927792
16:14:17 56919 8192000000 10890052688 11358507008 1909188080 18.900 97 11 22 0 1922731760 10752336 2791344
16:16:34 89625 8192000000 10915055808 11381620736 1943629200 19.176 98 10 20 2 1956788656 9845024 3314432
16:19:04 126916 8192000000 10877671040 11345039360 1892579856 18.767 96 13 26 1 1907972384 11860176 3532352
16:22:17 51911 8192000000 10884852208 11372015616 1966846016 19.360 100 12 24 0 1978559600 8567616 3145968
16:24:09 62937 8192000000 10882358032 11365593088 1891392128 18.757 96 6 12 0 1905727584 12782000 1553456
16:26:13 49930 8192000000 10861154304 11335733248 1857955792 18.487 95 16 32 0 1872722128 10142528 4623808
16:28:06 73529 8192000000 10841878832 11320733696 1870455040 18.588 97 21 42 1 1893171040 15948608 6767392
16:30:30 63476 8192000000 10838627424 11307053056 1849655872 18.419 96 10 20 0 1867924416 15600416 2668128
16:32:35 71174 8192000000 10823478624 11298537472 1809300240 18.090 94 17 34 0 1826447728 12033360 5114128
16:34:48 39263 8192000000 10854417296 11317358592 1883094304 18.690 98 31 59 2 1904083904 12008016 8981584
16:36:31 26714 8192000000 10873139728 11325878272 1927545648 19.047 100 48 95 0 1952364640 11359552 13459440

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-10-2021 16:39:11 to 06-10-2021 16:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 81 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-10-2021 16:39:27 to 06-10-2021 16:39:29.

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          249027
MON$OLDEST_ACTIVE               251053
MON$OLDEST_SNAPSHOT             251053
MON$NEXT_TRANSACTION            251053
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                       2072160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:294: 
:295: mscs-test-161.budzdorov.ru    Wed Oct  6 16:37:57 2021
:296:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:297: 
:298: 
:299: mscs-test-161.budzdorov.ru    Wed Oct  6 16:37:57 2021
:300:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:301: 
:302: 
:303: mscs-test-161.budzdorov.ru    Wed Oct  6 16:38:00 2021
:304:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:305: 
:306: 
:307: mscs-test-161.budzdorov.ru    Wed Oct  6 16:38:02 2021
:308:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:309: 
:310: 
:311: mscs-test-161.budzdorov.ru    Wed Oct  6 16:38:05 2021
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:313: 
:314: 
:315: mscs-test-161.budzdorov.ru    Wed Oct  6 16:38:05 2021
:316:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:317: 
--- end of diff output ---

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

06.10.21 16:39:30. 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
2021-10-06 16:39:32 No severe PSQL-related problems occured
06.10.21 16:39:33. End of report.