Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.11.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 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 16956080 kB
MemAvailable 31599408 kB
Buffers 0 kB
Cached 14708600 kB
SwapCached 0 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-11-13 22:37:51
DTS_END                         2021-11-13 22:37:51.7550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47094
TRN_ID                          239241
ATT_ID                          669
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: 8977.58 24991 1077310
customer order: creation 166.55 364 19986
customer order: refuse 14.62 350 1754
order to supplier: creation 46.23 1268 5547
order to supplier: removal 3.35 94838 402
invoice (draft): creation 44.37 3386 5324
invoice (draft): removal 50.58 816 6070
invoice accept: apply 41.73 16729 5007
invoice accept: cancel 45.83 834 5499
customer reserve: creation 3809.78 167 457174
customer reserve: removal 4460.92 56 535310
realization accept: apply 80.23 207 9628
realization accept: cancel 88.35 97 10602
payment from customer: creation 57.28 138 6873
payment from customer: removal 5.81 97 697
payment to supplier: creation 47.44 161 5693
payment to supplier: removal 3.56 59 427
service: total inventory turnovers 4.39 1154 527
service: total monetary turnovers 5.73 25 688
service: aggregate perf. data 0.82 21051 98
service: refresh index statistics 0.03 358030 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-11-2021 22:38:42 to 13-11-2021 22:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4044
TEST_TIME 2 3275
TEST_TIME 3 3065
TEST_TIME 4 3513
TEST_TIME 5 3732
TEST_TIME 6 3736
TEST_TIME 7 4015
TEST_TIME 8 4496
TEST_TIME 9 4485
TEST_TIME 10 4736
TEST_TIME 11 5222
TEST_TIME 12 5631
TEST_TIME 13 5686
TEST_TIME 14 6168
TEST_TIME 15 6902
TEST_TIME 16 7253
TEST_TIME 17 7355
TEST_TIME 18 7719
TEST_TIME 19 7850
TEST_TIME 20 7910
TEST_TIME 21 8471
TEST_TIME 22 8673
TEST_TIME 23 8888
TEST_TIME 24 8971
TEST_TIME 25 9119
TEST_TIME 26 9131
TEST_TIME 27 9582
TEST_TIME 28 9925
TEST_TIME 29 10300
TEST_TIME 30 10314
TEST_TIME 31 10295
TEST_TIME 32 10089
TEST_TIME 33 10022
TEST_TIME 34 9862
TEST_TIME 35 9662
TEST_TIME 36 9592
TEST_TIME 37 9469
TEST_TIME 38 9344
TEST_TIME 39 9267
TEST_TIME 40 9248
TEST_TIME 41 9147
TEST_TIME 42 9176
TEST_TIME 43 9170
TEST_TIME 44 9187
TEST_TIME 45 9221
TEST_TIME 46 9234
TEST_TIME 47 9111
TEST_TIME 48 9001
TEST_TIME 49 8925
TEST_TIME 50 8861
TEST_TIME 51 8743
TEST_TIME 52 8748
TEST_TIME 53 8714
TEST_TIME 54 8662
TEST_TIME 55 8720
TEST_TIME 56 8723
TEST_TIME 57 8710
TEST_TIME 58 8738
TEST_TIME 59 8691
TEST_TIME 60 8830
TEST_TIME 61 8810
TEST_TIME 62 8885
TEST_TIME 63 8858
TEST_TIME 64 8976
TEST_TIME 65 8955
TEST_TIME 66 9117
TEST_TIME 67 9136
TEST_TIME 68 9068
TEST_TIME 69 9092
TEST_TIME 70 9184
TEST_TIME 71 9121
TEST_TIME 72 9020
TEST_TIME 73 9022
TEST_TIME 74 9000
TEST_TIME 75 8971
TEST_TIME 76 8944
TEST_TIME 77 8884
TEST_TIME 78 8975
TEST_TIME 79 8932
TEST_TIME 80 8866
TEST_TIME 81 8810
TEST_TIME 82 8803
TEST_TIME 83 8798
TEST_TIME 84 8762
TEST_TIME 85 8840
TEST_TIME 86 8807
TEST_TIME 87 8780
TEST_TIME 88 8857
TEST_TIME 89 8913
TEST_TIME 90 8946
TEST_TIME 91 8904
TEST_TIME 92 8959
TEST_TIME 93 9048
TEST_TIME 94 9052
TEST_TIME 95 9103
TEST_TIME 96 9097
TEST_TIME 97 9126
TEST_TIME 98 9063
TEST_TIME 99 9027
TEST_TIME 100 9013
TEST_TIME 101 8976
TEST_TIME 102 8915
TEST_TIME 103 8893
TEST_TIME 104 8850
TEST_TIME 105 8802
TEST_TIME 106 8772
TEST_TIME 107 8780
TEST_TIME 108 8807
TEST_TIME 109 8799
TEST_TIME 110 8816
TEST_TIME 111 8880
TEST_TIME 112 8868
TEST_TIME 113 8878
TEST_TIME 114 8880
TEST_TIME 115 8885
TEST_TIME 116 8918
TEST_TIME 117 8901
TEST_TIME 118 8951
TEST_TIME 119 8973
TEST_TIME 120 8980

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-11-2021 22:38:45 to 13-11-2021 22:38:49.

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 1500617 1500617 0 0.00 0 20190 6 0 0
doc_list_biud 1500617 1500617 0 0.00 0 50888 26 0 0
sp_add_invoice_to_stock 5151 5007 144 2.79 35 177900 16730 0 144
sp_cancel_adding_invoice 5499 5499 0 0.00 5 53121 835 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 8953 350 0 0
sp_cancel_customer_reserve 535310 535310 0 0.00 1 21194 57 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 16258 98 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 1349 59 0 0
sp_cancel_supplier_invoice 6080 6070 10 0.16 2 50952 817 0 10
sp_cancel_supplier_order 402 402 0 0.00 5 986820 94839 0 0
sp_cancel_write_off 10602 10602 0 0.00 1 10013 98 0 0
sp_client_order 19986 19986 0 0.00 4 20265 364 0 0
sp_customer_reserve 457174 457174 0 0.00 0 28134 167 0 0
sp_fill_shopping_cart 30852 30852 0 0.00 1 22172 44 0 0
sp_fill_shopping_cart_clo_res 457169 457169 0 0.00 0 13016 14 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 25799 342 0 0
sp_kill_cost_storno 17225 17225 0 0.00 0 16249 56 0 0
sp_kill_qstorno_ret_qs2qd 1087694 1087694 0 0.00 0 50887 31 0 0
sp_kill_qty_storno 574267 574267 0 0.00 0 50887 67 0 0
sp_lock_selected_doc 20370 20370 0 0.00 0 5841 4 0 0
sp_make_cost_storno 27196 27196 0 0.00 0 18162 64 0 0
sp_multiply_rows_for_pdistr 27196 27196 0 0.00 0 20144 36 0 0
sp_multiply_rows_for_qdistr 19986 19986 0 0.00 0 14577 164 0 0
sp_pay_from_customer 6873 6873 0 0.00 4 19685 139 0 0
sp_pay_to_supplier 5693 5693 0 0.00 5 20195 162 0 0
sp_payment_common 12566 12566 0 0.00 1 19680 44 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 8945 322 0 0
sp_qd_handle_on_invoice_upd_sts 10501 10501 0 0.00 0 33696 317 0 0
sp_qd_handle_on_reserve_upd_sts 9628 9628 0 0.00 0 22575 64 0 0
sp_reserve_write_off 9628 9628 0 0.00 6 22601 208 0 0
sp_supplier_invoice 5324 5324 0 0.00 6 80214 3386 0 0
sp_supplier_order 5547 5547 0 0.00 5 32392 1269 0 0
srv_aggregate_perf_data 99 98 1 1.01 2718 268248 21051 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2135 99150 44005 0 0
srv_find_qd_qs_mism 1030600 1030600 0 0.00 0 8257 5 0 0
srv_make_invnt_saldo 527 527 0 0.00 49 57030 1154 0 0
srv_make_money_saldo 688 688 0 0.00 1 711 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 147060 493523 358030 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 5007 5007 0 0.00 0 12756 185 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 4110 80 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 5740 15 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1632 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 1521 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 148 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1333 14 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 20160 1524 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 1442 5 0 0
t$perf-norm:sp_client_order 19986 19986 0 0.00 0 8752 18 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 2048 8 0 0
t$perf-norm:sp_pay_from_customer 6873 6873 0 0.00 0 5861 16 0 0
t$perf-norm:sp_pay_to_supplier 5693 5693 0 0.00 0 4435 11 0 0
t$perf-norm:sp_reserve_write_off 9628 9628 0 0.00 0 4937 13 0 0
t$perf-norm:sp_supplier_invoice 5324 5324 0 0.00 0 3510 14 0 0
t$perf-norm:sp_supplier_order 5547 5547 0 0.00 0 4788 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 555 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 845 21 0 0
t$perf-norm:srv_make_money_saldo 688 688 0 0.00 0 593 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 92 26 0 0
v_add_invoice_to_stock 10292 10004 288 2.79 0 8823 19 0 288
v_all_customers 36258 36258 0 0.00 0 19 1 0 0
v_all_suppliers 29882 29882 0 0.00 0 32 1 0 0
v_all_wares 39972 39972 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 1564 7 0 0
v_cancel_client_order 3508 3508 0 0.00 0 7679 9 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3890 6 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 5383 11 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1010 4 0 0
v_cancel_supplier_invoice 868 848 20 2.30 0 567 13 0 20
v_cancel_supplier_order 804 804 0 0.00 0 5684 16 0 0
v_cancel_write_off 1868 1868 0 0.00 0 1355 13 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 4409 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 6604 17 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 5962 5 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 1803 4 0 0
v_max_non_paid_realizn 3662 3662 0 0.00 0 4100 5 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 12049 12 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 7434 20 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 7299 7 0 0
v_min_non_paid_invoice 5693 5693 0 0.00 0 16005 36 0 0
v_min_non_paid_realizn 6873 6873 0 0.00 0 15856 36 0 0
v_our_firm 10136 10136 0 0.00 0 16 1 0 0
v_reserve_write_off 19256 19256 0 0.00 0 6765 7 0 0
x_lock_depdocs_on_canc_invoice 11145 11145 0 0.00 0 8099 110 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 20072 423 0 0
x_make_qstorno_1000_1200 5547 5547 0 0.00 2 32364 1182 0 0
x_make_qstorno_1200_2000 5324 5324 0 0.00 3 80201 3224 0 0
x_make_qstorno_2100_3300 457174 457174 0 0.00 0 21831 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-11-2021 22:38:50 to 13-11-2021 22:38:54.

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:55 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:56 27739 8192000000 9895312032 10206126080 981801536 10.702 100 91 182 0 1011245136 5493184 23950416
20:10:28 23083 8192000000 9942144704 10263650304 1022404368 11.095 100 91 182 0 1053825520 7707568 23713584
20:11:57 17373 8192000000 9986578592 10320281600 1071620048 11.568 100 90 180 1 1103767392 9494976 22652368
20:13:19 17160 8192000000 10047962368 10398539776 1129170464 12.114 100 80 159 2 1158494672 10637264 18686944
20:14:39 7521 8192000000 10089400480 10447552512 1171038688 12.507 100 83 166 1 1202597488 12609504 18949296
20:15:49 6223 8192000000 10136819184 10503258112 1221582096 12.976 100 88 176 0 1254833056 13890176 19360784
20:16:57 2734 8192000000 10160205792 10537971712 1249193824 13.231 100 78 156 0 1278968592 12300672 17474096
20:18:02 2001 8192000000 10201196608 10582614016 1285355504 13.562 100 93 187 0 1321119552 14809920 20954128
20:19:05 1431 8192000000 10222842624 10607611904 1303129040 13.724 100 97 195 0 1339055088 13824960 22101088
20:20:07 5499 8192000000 10216186160 10613858304 1309185104 13.779 100 71 143 1 1332534064 6642064 16706896
20:21:15 3499 8192000000 10219885328 10628354048 1317186864 13.851 100 45 90 1 1339309184 12397760 9724560
20:22:23 5728 8192000000 10209895616 10632671232 1328902512 13.957 100 18 36 2 1339451744 6236048 4313184
20:23:29 9848 8192000000 10219056448 10645704704 1335186544 14.014 100 19 38 0 1349926784 10693152 4047088
20:24:41 48026 8192000000 10256327440 10663129088 1345407504 14.106 100 64 131 1 1366563392 5010784 16145104
20:26:29 5878 8192000000 10233527616 10658889728 1332015872 13.985 99 25 49 0 1343505216 6339904 5149440
20:27:37 11424 8192000000 10232830512 10653069312 1327889232 13.948 99 27 53 3 1339440336 5087200 6463904
20:28:51 39705 8192000000 10192929776 10605187072 1256822480 13.301 96 37 74 0 1270963104 5888160 8252464
20:30:31 4384 8192000000 10151638256 10556706816 1250253312 13.241 98 27 54 2 1262491680 6357552 5880816
20:31:36 6133 8192000000 10123551376 10522939392 1209922240 12.868 97 27 54 2 1221745216 4978544 6844432
20:32:44 13188 8192000000 10151102912 10557198336 1241765792 13.162 98 10 20 0 1249708704 5413952 2528960
20:33:58 22450 8192000000 10160272576 10565107712 1257440064 13.307 99 35 70 1 1272215216 6706384 8068768
20:35:28 8933 8192000000 10173760704 10585677824 1286100880 13.569 100 29 58 2 1298337424 5252080 6984464
20:36:40 9337 8192000000 10184374960 10600108032 1298397920 13.681 100 20 40 2 1309162816 5763440 5001456
20:37:55 4691 8192000000 10222493696 10631983104 1325373200 13.925 100 30 58 2 1336935088 5138928 6422960
20:39:02 61524 8192000000 10238615344 10658488320 1336437120 14.025 100 37 72 1 1350826832 5242496 9147216
20:41:17 20553 8192000000 10274122624 10689896448 1365094288 14.283 100 43 86 1 1385950912 11472384 9384240
20:42:42 16975 8192000000 10281200880 10703409152 1376483200 14.385 100 37 73 2 1400469440 15133072 8853168
20:44:10 18919 8192000000 10305939776 10726719488 1391996096 14.524 100 47 94 1 1422031568 18458400 11577072
20:45:31 3950 8192000000 10323052704 10737340416 1393622896 14.538 100 70 140 0 1429467712 18822000 17022816
20:46:40 13599 8192000000 10322013584 10746425344 1401460480 14.608 100 53 106 0 1435627744 21297888 12869376
20:48:02 6900 8192000000 10319978352 10739187712 1395708128 14.557 100 57 113 1 1433595440 24448560 13438752
20:49:16 2130 8192000000 10318636432 10747482112 1390698064 14.512 99 56 112 1 1426440064 21654576 14087424
20:50:23 3434 8192000000 10333434816 10750791680 1385211664 14.463 99 71 138 1 1431820784 29239024 17370096
20:51:32 2627 8192000000 10353924656 10770333696 1399356624 14.589 99 85 169 8 1454949296 34564272 21028400
20:52:39 3308 8192000000 10343392560 10753437696 1396060960 14.560 100 76 151 3 1441370016 27693120 17615936
20:53:45 5441 8192000000 10319131984 10739007488 1388335904 14.491 100 62 125 2 1428277104 24283616 15657584
20:54:55 7590 8192000000 10311846528 10722557952 1357731296 14.217 99 72 144 3 1405626240 30486384 17408560
20:56:06 7877 8192000000 10317355424 10740555776 1388676768 14.494 100 59 117 0 1429089696 26398544 14014384
20:57:17 3436 8192000000 10307064176 10724327424 1362106656 14.256 99 52 101 2 1407322816 31963328 13252832
20:58:29 9650 8192000000 10308594528 10734022656 1375180528 14.373 100 40 80 0 1420133520 33939040 11013952
20:59:49 3160 8192000000 10312286304 10729017344 1369921584 14.326 100 68 137 3 1411431216 23936560 17573072
21:00:55 6157 8192000000 10309026528 10713530368 1343427392 14.088 97 73 144 5 1385351712 23168384 18755936
21:02:02 19350 8192000000 10321860128 10761515008 1389687472 14.503 100 28 56 0 1429058896 32120208 7251216
21:03:24 10813 8192000000 10316671264 10751164416 1369440224 14.322 99 40 79 1 1413073104 32857440 10775440
21:04:46 49479 8192000000 10294283344 10735910912 1318882576 13.867 96 14 28 0 1361724080 38662512 4178992
21:06:45 34189 8192000000 10264325072 10696388608 1320630464 13.882 97 24 48 1 1343021520 15846672 6544384
21:08:24 29456 8192000000 10264893712 10712793088 1336035856 14.022 98 24 48 1 1357983040 16352544 5594640
21:10:07 86730 8192000000 10265544480 10709901312 1351926128 14.165 99 15 30 0 1366843264 12026912 2890224
21:13:41 65713 8192000000 10284389648 10727837696 1358860832 14.227 99 21 42 0 1375506560 12049888 4595840
21:16:33 20871 8192000000 10299076400 10739453952 1370961536 14.336 99 28 57 0 1393151152 15500096 6689520
21:17:56 62095 8192000000 10303056112 10748010496 1391933696 14.523 100 17 34 0 1411233040 15280784 4018560
21:20:11 58585 8192000000 10294008704 10757079040 1393464896 14.537 100 23 46 1 1419656784 20342336 5849552
21:22:10 57272 8192000000 10305876704 10755637248 1399887872 14.594 100 12 24 0 1420012608 17292832 2831904
21:24:43 76111 8192000000 10294256512 10757877760 1400078336 14.596 100 16 32 1 1412423792 8461968 3883488
21:27:05 58365 8192000000 10299128528 10764124160 1383971056 14.452 99 9 18 0 1393688208 7581440 2135712
21:29:06 87829 8192000000 10306031808 10780618752 1402337792 14.616 100 18 35 0 1421369328 14868288 4163248
21:31:44 66680 8192000000 10285407824 10763886592 1396686528 14.565 100 14 28 0 1416893408 16737920 3468960
21:33:51 47614 8192000000 10299185984 10772713472 1398786080 14.584 100 8 16 0 1414396336 13715408 1894848
21:35:48 59859 8192000000 10303701168 10759311360 1383014480 14.443 99 17 34 0 1403356512 16263520 4078512
21:37:49 70430 8192000000 10289125120 10745020416 1329768512 13.965 95 8 16 0 1343569632 12393392 1407728
21:40:00 86912 8192000000 10275282192 10744995840 1309970224 13.786 94 15 29 0 1329928144 16851840 3106080
21:42:38 27232 8192000000 10282908560 10739511296 1351810336 14.164 98 16 32 0 1371983872 16626432 3547104
21:44:16 64565 8192000000 10266477168 10706329600 1319020064 13.868 96 19 39 2 1332032208 9255712 3756432
21:46:24 10015 8192000000 10300336560 10738954240 1375474912 14.376 100 62 124 1 1405742736 16861488 13406336
21:47:43 84523 8192000000 10271631152 10724245504 1338324512 14.042 97 14 28 0 1352630096 11560576 2745008
21:50:32 70035 8192000000 10263366192 10709590016 1299913488 13.694 95 21 42 1 1317687808 13171008 4603312
21:52:44 75709 8192000000 10263219360 10713677824 1316968064 13.849 96 18 35 1 1334208352 13036208 4204080
21:55:00 63122 8192000000 10270952256 10733182976 1351559504 14.162 99 14 28 0 1373006592 18345440 3101648
21:57:04 45892 8192000000 10265904224 10720731136 1374856320 14.371 100 15 31 0 1383934064 5627120 3450624
21:59:04 69166 8192000000 10261773392 10717380608 1338809984 14.047 97 12 24 0 1348006592 6420480 2776128
22:01:18 57908 8192000000 10267733440 10720989184 1343123872 14.086 97 15 30 1 1355950816 9290080 3536864
22:03:30 31602 8192000000 10280760640 10725253120 1358855472 14.227 99 34 68 0 1377242112 10474048 7912592
22:05:05 71412 8192000000 10288993808 10733740032 1360906544 14.245 99 17 33 1 1380117008 15374160 3836304
22:07:21 46339 8192000000 10296391536 10747822080 1381073104 14.426 99 20 40 0 1398431536 13218096 4140336
22:09:08 75149 8192000000 10312835120 10773229568 1387643536 14.485 99 19 38 0 1405583424 13912928 4026960
22:11:24 87278 8192000000 10307716320 10780045312 1386616976 14.476 99 10 20 3 1408588752 20253232 1718544
22:13:52 99150 8192000000 10313405888 10765438976 1392890320 14.532 99 28 56 1 1410560400 11359648 6310432
22:16:55 49179 8192000000 10287016512 10755383296 1388141632 14.489 99 11 22 0 1399819952 9141264 2537056
22:19:00 70997 8192000000 10295208832 10767675392 1401517344 14.609 100 3 6 0 1414434688 12433568 483776
22:21:19 20918 8192000000 10292539392 10756042752 1385926432 14.470 99 5 10 0 1394319792 7376992 1016368
22:22:42 69667 8192000000 10299255440 10753310720 1381742240 14.432 99 19 38 1 1396523568 10437600 4343728
22:25:01 63083 8192000000 10299432880 10756087808 1370868320 14.335 98 8 16 0 1386967648 14407120 1692208
22:27:04 52622 8192000000 10301872592 10759471104 1382774688 14.441 99 29 56 1 1398740544 9289584 6676272
22:28:59 48999 8192000000 10293739584 10744823808 1375843152 14.379 99 13 25 0 1390906800 12318400 2745248
22:30:48 72928 8192000000 10284883344 10733199360 1338606048 14.045 96 25 50 1 1356966400 12555520 5804832
22:33:02 78733 8192000000 10277304400 10716291072 1291129408 13.615 94 27 54 1 1306045504 9161696 5754400
22:35:21 80945 8192000000 10263335184 10719457280 1298038752 13.677 95 13 26 0 1315221744 14394816 2788176
22:37:42 22827 8192000000 8906630448 9148788736 56896400 0.689 5 1 2 0 57450768 503616 50752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-11-2021 22:38:56 to 13-11-2021 22:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 144 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-11-2021 22:39:11 to 13-11-2021 22:39:13.

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          239816
MON$OLDEST_ACTIVE               239817
MON$OLDEST_SNAPSHOT             239817
MON$NEXT_TRANSACTION            239817
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                       2017792
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

13.11.21 22:39:14. 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-11-13 22:39:17 No severe PSQL-related problems occured
13.11.21 22:39:18. End of report.