Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 22: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 15963908 kB
MemAvailable 30835880 kB
Buffers 0 kB
Cached 15685836 kB
SwapCached 340 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-09-07 22:37:49
DTS_END                         2021-09-07 22:37:49.5370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(120:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59832
TRN_ID                          243507
ATT_ID                          651
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: 9608.98 18825 1153077
customer order: creation 181.79 343 21815
customer order: refuse 14.85 397 1782
order to supplier: creation 47.49 1234 5699
order to supplier: removal 3.38 118594 406
invoice (draft): creation 47.91 3709 5749
invoice (draft): removal 55.93 894 6711
invoice accept: apply 42.29 19186 5075
invoice accept: cancel 49.92 835 5990
customer reserve: creation 3910.74 190 469289
customer reserve: removal 4945.50 63 593460
realization accept: apply 86.19 190 10343
realization accept: cancel 87.48 108 10497
payment from customer: creation 62.56 123 7507
payment from customer: removal 5.68 127 682
payment to supplier: creation 50.82 143 6098
payment to supplier: removal 3.46 80 415
service: total inventory turnovers 5.22 1087 626
service: total monetary turnovers 6.98 38 838
service: aggregate perf. data 0.76 24386 91
service: refresh index statistics 0.03 204769 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 22:38:34 to 07-09-2021 22:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1708
TEST_TIME 2 2244
TEST_TIME 3 3086
TEST_TIME 4 3776
TEST_TIME 5 4407
TEST_TIME 6 4719
TEST_TIME 7 4905
TEST_TIME 8 5663
TEST_TIME 9 5544
TEST_TIME 10 5547
TEST_TIME 11 5710
TEST_TIME 12 5686
TEST_TIME 13 5844
TEST_TIME 14 6154
TEST_TIME 15 6445
TEST_TIME 16 6662
TEST_TIME 17 6928
TEST_TIME 18 7422
TEST_TIME 19 7420
TEST_TIME 20 7423
TEST_TIME 21 7766
TEST_TIME 22 7831
TEST_TIME 23 7919
TEST_TIME 24 7945
TEST_TIME 25 8029
TEST_TIME 26 8489
TEST_TIME 27 8407
TEST_TIME 28 8847
TEST_TIME 29 8826
TEST_TIME 30 8723
TEST_TIME 31 9156
TEST_TIME 32 10129
TEST_TIME 33 10663
TEST_TIME 34 10858
TEST_TIME 35 11000
TEST_TIME 36 10821
TEST_TIME 37 10752
TEST_TIME 38 10706
TEST_TIME 39 10523
TEST_TIME 40 10506
TEST_TIME 41 10276
TEST_TIME 42 10374
TEST_TIME 43 10264
TEST_TIME 44 10220
TEST_TIME 45 10090
TEST_TIME 46 10097
TEST_TIME 47 10033
TEST_TIME 48 10037
TEST_TIME 49 9988
TEST_TIME 50 9890
TEST_TIME 51 9784
TEST_TIME 52 9717
TEST_TIME 53 9807
TEST_TIME 54 9804
TEST_TIME 55 9654
TEST_TIME 56 9685
TEST_TIME 57 9703
TEST_TIME 58 9753
TEST_TIME 59 9687
TEST_TIME 60 9755
TEST_TIME 61 9724
TEST_TIME 62 9795
TEST_TIME 63 9876
TEST_TIME 64 9855
TEST_TIME 65 9856
TEST_TIME 66 9879
TEST_TIME 67 10045
TEST_TIME 68 10051
TEST_TIME 69 10079
TEST_TIME 70 10078
TEST_TIME 71 10065
TEST_TIME 72 9975
TEST_TIME 73 9927
TEST_TIME 74 9868
TEST_TIME 75 9875
TEST_TIME 76 9852
TEST_TIME 77 9794
TEST_TIME 78 9872
TEST_TIME 79 9817
TEST_TIME 80 9785
TEST_TIME 81 9777
TEST_TIME 82 9709
TEST_TIME 83 9703
TEST_TIME 84 9659
TEST_TIME 85 9633
TEST_TIME 86 9619
TEST_TIME 87 9573
TEST_TIME 88 9590
TEST_TIME 89 9590
TEST_TIME 90 9566
TEST_TIME 91 9541
TEST_TIME 92 9658
TEST_TIME 93 9726
TEST_TIME 94 9711
TEST_TIME 95 9750
TEST_TIME 96 9732
TEST_TIME 97 9799
TEST_TIME 98 9788
TEST_TIME 99 9802
TEST_TIME 100 9895
TEST_TIME 101 9870
TEST_TIME 102 9778
TEST_TIME 103 9695
TEST_TIME 104 9705
TEST_TIME 105 9728
TEST_TIME 106 9678
TEST_TIME 107 9682
TEST_TIME 108 9661
TEST_TIME 109 9623
TEST_TIME 110 9569
TEST_TIME 111 9612
TEST_TIME 112 9600
TEST_TIME 113 9571
TEST_TIME 114 9579
TEST_TIME 115 9626
TEST_TIME 116 9626
TEST_TIME 117 9609
TEST_TIME 118 9581
TEST_TIME 119 9582
TEST_TIME 120 9609

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-09-2021 22:38:38 to 07-09-2021 22:38:41.

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 1588169 1588169 0 0.00 0 16239 6 0 0
doc_list_biud 1588150 1588150 0 0.00 0 37126 30 0 0
sp_add_invoice_to_stock 5183 5075 108 2.08 34 198596 19186 0 108
sp_cancel_adding_invoice 5990 5990 0 0.00 5 64464 836 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 26196 397 0 0
sp_cancel_customer_reserve 593460 593460 0 0.00 1 37130 63 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 25858 127 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1969 81 0 0
sp_cancel_supplier_invoice 6715 6711 4 0.05 2 19767 895 0 4
sp_cancel_supplier_order 406 406 0 0.00 5 1014990 118594 0 0
sp_cancel_write_off 10497 10497 0 0.00 1 15513 109 0 0
sp_client_order 21815 21815 0 0.00 3 31681 343 0 0
sp_customer_reserve 469289 469289 0 0.00 0 97798 190 0 0
sp_fill_shopping_cart 33260 33260 0 0.00 1 19064 38 0 0
sp_fill_shopping_cart_clo_res 469289 469289 0 0.00 0 16999 12 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 16772 234 0 0
sp_kill_cost_storno 17584 17584 0 0.00 0 6140 58 0 0
sp_kill_qstorno_ret_qs2qd 1204534 1204534 0 0.00 0 37103 34 0 0
sp_kill_qty_storno 634250 634250 0 0.00 0 37125 74 0 0
sp_lock_selected_doc 21286 21286 0 0.00 0 12586 7 0 0
sp_make_cost_storno 29023 29023 0 0.00 0 10639 50 0 0
sp_multiply_rows_for_pdistr 29004 29004 0 0.00 0 6569 27 0 0
sp_multiply_rows_for_qdistr 21815 21815 0 0.00 0 9740 135 0 0
sp_pay_from_customer 7507 7507 0 0.00 4 19516 124 0 0
sp_pay_to_supplier 6098 6098 0 0.00 5 28124 144 0 0
sp_payment_common 13600 13600 0 0.00 2 10019 28 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 10969 333 0 0
sp_qd_handle_on_invoice_upd_sts 11058 11058 0 0.00 0 15687 347 0 0
sp_qd_handle_on_reserve_upd_sts 10336 10336 0 0.00 0 11444 50 0 0
sp_reserve_write_off 10343 10343 0 0.00 6 26942 190 0 0
sp_supplier_invoice 5749 5749 0 0.00 7 113509 3709 0 0
sp_supplier_order 5699 5699 0 0.00 5 90220 1234 0 0
srv_aggregate_perf_data 92 91 1 1.08 2038 283549 24387 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 2081 130006 42006 0 0
srv_find_qd_qs_mism 1103853 1103853 0 0.00 0 16238 6 0 0
srv_make_invnt_saldo 626 626 0 0.00 31 109707 1088 0 0
srv_make_money_saldo 838 838 0 0.00 1 6972 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 129785 327413 204770 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 0 11001 187 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 4549 107 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 3737 13 0 0
t$perf-norm:sp_cancel_customer_reserve 776 776 0 0.00 0 1720 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 4278 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1986 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1598 23 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 30194 1949 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2004 8 0 0
t$perf-norm:sp_client_order 21815 21815 0 0.00 0 8313 17 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 3343 11 0 0
t$perf-norm:sp_pay_from_customer 7507 7507 0 0.00 0 4845 9 0 0
t$perf-norm:sp_pay_to_supplier 6098 6098 0 0.00 0 9406 9 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 4833 8 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 5928 18 0 0
t$perf-norm:sp_supplier_order 5699 5699 0 0.00 0 4568 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 275 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 54 2 0 0
t$perf-norm:srv_make_invnt_saldo 626 626 0 0.00 0 1214 24 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 941 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10352 10136 216 2.08 0 26267 27 0 216
v_all_customers 39784 39784 0 0.00 0 15 1 0 0
v_all_suppliers 31920 31920 0 0.00 0 18 1 0 0
v_all_wares 43630 43630 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1922 11 0 0
v_cancel_client_order 3564 3564 0 0.00 0 25835 18 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 25834 27 0 0
v_cancel_customer_reserve 1552 1552 0 0.00 0 3443 9 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1785 7 0 0
v_cancel_supplier_invoice 836 828 8 0.95 0 3057 20 0 8
v_cancel_supplier_order 812 812 0 0.00 0 2272 7 0 0
v_cancel_write_off 2016 2016 0 0.00 0 10075 24 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 625 1 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 5921 18 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 19054 9 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 3400 6 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 4414 5 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 2442 5 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 9352 22 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 6939 8 0 0
v_min_non_paid_invoice 6094 6094 0 0.00 0 7090 14 0 0
v_min_non_paid_realizn 7506 7506 0 0.00 0 8914 16 0 0
v_our_firm 10956 10956 0 0.00 0 16 1 0 0
v_reserve_write_off 20672 20672 0 0.00 0 26310 17 0 0
x_lock_depdocs_on_canc_invoice 12287 12287 0 0.00 0 25657 128 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 32361 440 0 0
x_make_qstorno_1000_1200 5699 5699 0 0.00 2 90212 1152 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 4 113478 3571 0 0
x_make_qstorno_2100_3300 469289 469289 0 0.00 0 97797 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 22:38:42 to 07-09-2021 22:38:46.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:53 4 8192000000 8808068496 8821182464 13111200 0.159 3 2 3 0 13290992 67504 112288
20:08:53 29582 8192000000 10367769616 10685419520 1412308976 14.704 100 90 180 1 1447788352 6012784 29466592
20:10:28 29117 8192000000 10447517680 10778062848 1493964624 15.424 100 86 172 1 1530059920 8224032 27871264
20:12:01 22505 8192000000 10498736576 10844307456 1554861040 15.952 100 83 166 0 1590422672 9152576 26409056
20:13:30 13264 8192000000 10588565024 10949603328 1649374928 16.759 100 80 160 0 1683632960 10077936 24180096
20:14:48 7140 8192000000 10663429152 11033546752 1723720192 17.383 100 80 160 2 1758744736 12521696 22502848
20:15:58 5654 8192000000 10705329296 11081068544 1761993056 17.701 100 73 146 2 1795441392 13403248 20045088
20:17:05 8812 8192000000 10728094352 11122937856 1806112592 18.064 100 75 150 1 1839208320 12240400 20855328
20:18:17 1701 8192000000 10802303376 11189411840 1858461360 18.491 100 90 178 1 1896705504 13259392 24984752
20:19:21 1987 8192000000 10809914704 11198533632 1865857296 18.551 100 87 175 1 1903495024 12985024 24652704
20:20:25 2033 8192000000 10813444288 11207397376 1874740560 18.623 100 86 173 0 1909825280 10216624 24868096
20:21:28 3713 8192000000 10798549488 11216814080 1878430960 18.652 100 46 91 0 1899421424 7864176 13126288
20:22:34 6957 8192000000 10801161728 11227435008 1888152416 18.731 100 19 38 1 1899513056 6006208 5354432
20:23:41 7187 8192000000 10795081936 11231858688 1896519376 18.798 100 23 47 1 1908492288 5147232 6825680
20:24:49 20377 8192000000 10838546240 11251118080 1904225088 18.860 100 55 111 1 1925811872 5738624 15848160
20:26:10 7834 8192000000 10825951216 11261616128 1909263456 18.901 100 25 50 5 1922860896 6698048 6899392
20:27:21 8291 8192000000 10826288224 11255091200 1905837408 18.873 100 18 36 2 1917119984 5708752 5573824
20:28:31 50061 8192000000 10776878576 11205222400 1834882800 18.299 97 22 45 0 1846483760 4930656 6670304
20:30:22 6712 8192000000 10695185792 11109150720 1759484320 17.680 98 34 68 0 1775897808 6404688 10008800
20:31:30 6914 8192000000 10716694672 11140345856 1809131232 18.089 100 33 65 1 1824427920 5548576 9748112
20:32:38 8596 8192000000 10726489904 11140218880 1779617424 17.846 98 33 66 0 1794410976 4720240 10073312
20:33:55 16282 8192000000 10743985456 11165843456 1813040240 18.121 99 26 52 1 1825816832 5007840 7768752
20:35:15 5567 8192000000 10761014640 11184226304 1845972240 18.389 100 33 67 2 1861538736 5550128 10016368
20:36:24 11017 8192000000 10779288304 11206647808 1866542192 18.556 100 21 43 0 1877532464 5356880 5633392
20:37:36 46472 8192000000 10812055824 11244015616 1895002640 18.786 100 21 38 1 1905304960 4714048 5588272
20:39:29 13638 8192000000 10845430768 11271012352 1919095232 18.980 100 40 80 1 1938303280 7784720 11423328
20:40:56 6246 8192000000 10868598112 11294875648 1943424752 19.174 100 53 105 1 1966995392 8172608 15398032
20:42:06 20817 8192000000 10877910576 11314085888 1938522832 19.135 99 52 104 0 1967222112 13737200 14962080
20:43:30 12480 8192000000 10903472704 11333275648 1963799152 19.336 100 47 92 3 1989843616 13214304 12830160
20:44:54 16126 8192000000 10923352736 11354324992 1979350128 19.460 100 51 102 1 2011877904 17444592 15083184
20:46:19 10374 8192000000 10923827040 11364982784 1976823184 19.440 99 47 92 0 2008020880 17413200 13784496
20:47:39 12227 8192000000 10924616816 11359158272 1971026768 19.394 98 50 100 1 2002168368 15620272 15521328
20:49:01 5932 8192000000 10959479072 11388723200 2007187232 19.679 100 60 120 1 2042076320 17192496 17696592
20:50:14 7107 8192000000 10951828192 11384844288 1992308592 19.562 99 60 120 0 2029103920 18646672 18148656
20:51:25 6554 8192000000 10931215328 11365335040 1983611344 19.493 100 64 128 2 2025741664 21660400 20469920
20:52:37 6388 8192000000 10936324992 11351564288 1958375024 19.293 99 82 163 1 2008183072 25313056 24494992
20:53:52 4706 8192000000 10905849040 11314028544 1908214752 18.892 98 72 142 0 1960579264 29629712 22734800
20:55:05 3510 8192000000 10942717584 11361763328 1960938336 19.313 100 73 146 0 2022900496 39317952 22644208
20:56:23 3643 8192000000 10939611344 11343454208 1937955056 19.130 99 82 164 2 1999719280 36215360 25548864
20:57:34 7127 8192000000 10944504400 11355750400 1957126240 19.283 100 78 154 0 2024246976 42569392 24551344
20:58:54 2078 8192000000 10968502464 11371315200 1955412896 19.270 99 95 190 1 2039981056 54883728 29684432
20:59:59 7330 8192000000 10938973472 11367198720 1957409232 19.285 100 60 120 0 2027394912 50213856 19771824
21:01:10 2207 8192000000 10958229392 11374088192 1940870576 19.154 98 79 159 1 2024399776 58935632 24593568
21:02:18 3729 8192000000 10982708304 11403403264 1981706000 19.478 100 60 120 1 2067085504 65205152 20174352
21:03:33 20230 8192000000 10976864544 11419803648 1982907136 19.488 100 38 76 0 2058569168 62776464 12885568
21:05:00 24233 8192000000 10966061072 11408039936 1954911776 19.266 99 33 66 2 2031569232 64992128 11665328
21:06:47 18467 8192000000 10953388464 11406479360 1964698384 19.343 100 29 56 1 2041593280 66764288 10130608
21:08:12 14330 8192000000 10962970688 11419049984 1947302576 19.205 99 33 66 0 2046808176 86922224 12583376
21:09:36 24782 8192000000 10912819248 11374694400 1922114208 19.004 98 24 48 2 1992086208 61967904 8004096
21:11:14 45402 8192000000 10883644576 11343740928 1944906384 19.186 99 16 32 0 1958099168 8816592 4376192
21:13:19 58900 8192000000 10905699472 11367575552 1973253920 19.411 100 5 10 0 1986085872 11446928 1385024
21:15:18 86994 8192000000 10925247008 11373543424 1945607232 19.191 98 19 38 0 1958204048 8187408 4409408
21:18:03 130005 8192000000 10899613136 11355070464 1920070160 18.987 97 32 64 0 1941026064 11325712 9630192
21:21:16 81457 8192000000 10865955664 11337781248 1920385296 18.990 97 39 78 0 1950650592 18664736 11600560
21:23:39 32775 8192000000 10879897200 11347693568 1890204800 18.747 96 12 22 1 1900285680 6661104 3419776
21:25:15 29676 8192000000 10882118304 11347259392 1957795360 19.289 100 26 46 5 1970775920 6533712 6446848
21:26:54 78845 8192000000 10912109376 11386638336 1986172272 19.514 100 20 40 0 2005200096 13209872 5817952
21:29:22 36142 8192000000 10907034272 11384135680 1980290368 19.467 100 10 20 1 1999811504 16709088 2812048
21:30:59 67270 8192000000 10904534000 11392376832 1958878480 19.297 99 7 14 0 1976252688 15336080 2038128
21:33:07 51560 8192000000 10900927568 11385618432 1974900480 19.424 100 16 33 1 1990389872 10826880 4662512
21:35:05 63901 8192000000 10908173808 11376041984 1941073568 19.155 98 11 22 0 1953396432 9405120 2917744
21:37:09 18365 8192000000 10917665920 11375386624 1966003552 19.354 99 48 96 1 1992907984 13973424 12931008
21:38:30 15504 8192000000 10914053984 11372199936 1955441952 19.270 99 36 72 2 1973266656 7363840 10460864
21:39:49 119859 8192000000 10905609072 11386785792 1897134416 18.803 96 7 14 0 1920170832 20876448 2159968
21:42:49 72000 8192000000 10914420080 11397906432 1947558320 19.207 98 18 36 0 1975533824 22495616 5479888
21:45:04 48998 8192000000 10906212320 11360309248 1893736752 18.776 96 31 62 5 1916922848 14480192 8705904
21:47:06 73551 8192000000 10862716944 11313197056 1793459184 17.960 92 12 24 0 1811821120 14607616 3754320
21:49:20 112922 8192000000 10839178080 11307917312 1836078112 18.309 95 29 59 1 1857485648 13093008 8314528
21:52:32 50196 8192000000 10832141808 11313348608 1907238032 18.884 100 28 56 1 1930829360 15676656 7914672
21:54:29 72484 8192000000 10846949488 11328126976 1904641248 18.864 99 16 32 0 1921015824 11659168 4715408
21:56:44 94076 8192000000 10853430640 11341451264 1913755856 18.937 99 13 27 1 1931894208 14154688 3983664
22:00:27 61252 8192000000 10863449824 11335680000 1928321200 19.053 99 18 35 0 1941384560 7589456 5473904
22:02:29 32521 8192000000 10858299520 11343552512 1940182160 19.148 100 11 22 0 1949838464 6818336 2837968
22:04:07 47929 8192000000 10877717568 11340718080 1909206032 18.900 98 26 52 2 1926326032 9690128 7429872
22:06:03 34362 8192000000 10884688464 11362947072 1949093760 19.219 100 21 42 0 1965793824 10894096 5805968
22:07:37 69787 8192000000 10906294112 11386257408 1946107776 19.195 98 16 32 1 1971272384 20635264 4529344
22:09:48 69666 8192000000 10925441456 11373039616 1919773280 18.985 97 30 60 1 1944357488 15899600 8684608
22:12:04 32558 8192000000 10921724224 11385356288 1974892256 19.424 100 36 72 0 1997276848 12029888 10354704
22:13:41 40736 8192000000 10895815312 11375153152 1921581088 19.000 97 21 41 1 1938777568 11467536 5728944
22:15:27 106610 8192000000 10881444208 11358973952 1859719680 18.501 94 10 20 0 1875381376 12948384 2713312
22:18:16 40818 8192000000 10884233888 11363303424 1961634752 19.319 100 22 44 2 1977807440 9997440 6175248
22:20:30 58141 8192000000 10875702048 11359182848 1910829648 18.913 97 29 58 1 1933956384 14630304 8496432
22:22:36 63129 8192000000 10877291472 11376570368 1957670896 19.288 100 11 22 0 1974221456 13491296 3059264
22:24:40 79847 8192000000 10872910832 11351953408 1863005904 18.528 95 12 24 0 1877587904 11189888 3392112
22:27:00 65154 8192000000 10862218000 11349807104 1926233216 19.037 99 17 34 1 1944235408 12894960 5107232
22:29:20 81031 8192000000 10880674736 11354050560 1892414064 18.765 97 13 26 0 1904603888 8781520 3408304
22:32:15 77785 8192000000 10869509856 11346042880 1895088032 18.787 97 13 26 0 1908520576 10045264 3387280
22:34:35 14061 8192000000 10872991824 11317346304 1890775520 18.752 98 53 106 0 1917031120 10643264 15612336
22:35:51 29273 8192000000 10845555216 11314614272 1880786544 18.671 98 31 62 1 1906778992 17251520 8740928
22:37:31 26063 8192000000 9372444816 9664380928 508625632 5.845 27 13 23 2 516247776 4185984 3436160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-09-2021 22:38:48 to 07-09-2021 22:39:03.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-09-2021 22:39:04 to 07-09-2021 22:39:06.

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          243789
MON$OLDEST_ACTIVE               243790
MON$OLDEST_SNAPSHOT             243790
MON$NEXT_TRANSACTION            243790
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                       2046944
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.33492 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33492 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33492 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33492 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Tue Sep  7 22:38:00 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Sep  7 22:38:03 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Sep  7 22:38:05 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
--- end of diff output ---

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

07.09.21 22:39:07. 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-09-07 22:39:09 No severe PSQL-related problems occured
07.09.21 22:39:11. End of report.