Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2021 19: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 16142000 kB
MemAvailable 30620972 kB
Buffers 0 kB
Cached 15540332 kB
SwapCached 3704 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-18 19:37:51
DTS_END                         2021-10-18 19:37:51.2600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47818
TRN_ID                          240867
ATT_ID                          712
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9114.69 21733 1093763
customer order: creation 176.01 365 21121
customer order: refuse 14.95 326 1794
order to supplier: creation 46.59 1300 5591
order to supplier: removal 3.26 93517 391
invoice (draft): creation 46.97 3414 5636
invoice (draft): removal 50.03 746 6003
invoice accept: apply 42.77 18194 5132
invoice accept: cancel 45.38 817 5445
customer reserve: creation 3920.48 182 470458
customer reserve: removal 4466.52 54 535982
realization accept: apply 84.32 167 10118
realization accept: cancel 86.37 104 10364
payment from customer: creation 60.19 112 7223
payment from customer: removal 6.00 75 720
payment to supplier: creation 49.39 129 5927
payment to supplier: removal 3.30 87 396
service: total inventory turnovers 5.05 860 606
service: total monetary turnovers 6.27 31 752
service: aggregate perf. data 0.83 21137 100
service: refresh index statistics 0.03 293051 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2021 19:38:48 to 18-10-2021 19:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2471
TEST_TIME 2 3235
TEST_TIME 3 4218
TEST_TIME 4 4857
TEST_TIME 5 5594
TEST_TIME 6 5280
TEST_TIME 7 5761
TEST_TIME 8 5910
TEST_TIME 9 5753
TEST_TIME 10 6257
TEST_TIME 11 6460
TEST_TIME 12 6859
TEST_TIME 13 6889
TEST_TIME 14 7122
TEST_TIME 15 7536
TEST_TIME 16 7598
TEST_TIME 17 7617
TEST_TIME 18 8048
TEST_TIME 19 8355
TEST_TIME 20 9157
TEST_TIME 21 9093
TEST_TIME 22 9521
TEST_TIME 23 9627
TEST_TIME 24 9540
TEST_TIME 25 10354
TEST_TIME 26 10690
TEST_TIME 27 10758
TEST_TIME 28 10664
TEST_TIME 29 10656
TEST_TIME 30 10363
TEST_TIME 31 10279
TEST_TIME 32 10227
TEST_TIME 33 10305
TEST_TIME 34 10251
TEST_TIME 35 10071
TEST_TIME 36 9965
TEST_TIME 37 9815
TEST_TIME 38 9857
TEST_TIME 39 9976
TEST_TIME 40 9884
TEST_TIME 41 9785
TEST_TIME 42 9638
TEST_TIME 43 9596
TEST_TIME 44 9449
TEST_TIME 45 9328
TEST_TIME 46 9423
TEST_TIME 47 9310
TEST_TIME 48 9371
TEST_TIME 49 9293
TEST_TIME 50 9171
TEST_TIME 51 9232
TEST_TIME 52 9177
TEST_TIME 53 9191
TEST_TIME 54 9182
TEST_TIME 55 9216
TEST_TIME 56 9297
TEST_TIME 57 9252
TEST_TIME 58 9256
TEST_TIME 59 9285
TEST_TIME 60 9304
TEST_TIME 61 9406
TEST_TIME 62 9366
TEST_TIME 63 9421
TEST_TIME 64 9474
TEST_TIME 65 9384
TEST_TIME 66 9305
TEST_TIME 67 9249
TEST_TIME 68 9233
TEST_TIME 69 9177
TEST_TIME 70 9107
TEST_TIME 71 9110
TEST_TIME 72 9144
TEST_TIME 73 9099
TEST_TIME 74 9190
TEST_TIME 75 9178
TEST_TIME 76 9177
TEST_TIME 77 9151
TEST_TIME 78 9178
TEST_TIME 79 9165
TEST_TIME 80 9217
TEST_TIME 81 9205
TEST_TIME 82 9310
TEST_TIME 83 9267
TEST_TIME 84 9331
TEST_TIME 85 9269
TEST_TIME 86 9194
TEST_TIME 87 9164
TEST_TIME 88 9188
TEST_TIME 89 9141
TEST_TIME 90 9099
TEST_TIME 91 9099
TEST_TIME 92 9090
TEST_TIME 93 9074
TEST_TIME 94 9044
TEST_TIME 95 9087
TEST_TIME 96 9170
TEST_TIME 97 9183
TEST_TIME 98 9168
TEST_TIME 99 9162
TEST_TIME 100 9258
TEST_TIME 101 9207
TEST_TIME 102 9202
TEST_TIME 103 9287
TEST_TIME 104 9299
TEST_TIME 105 9261
TEST_TIME 106 9301
TEST_TIME 107 9258
TEST_TIME 108 9230
TEST_TIME 109 9233
TEST_TIME 110 9195
TEST_TIME 111 9140
TEST_TIME 112 9144
TEST_TIME 113 9150
TEST_TIME 114 9107
TEST_TIME 115 9119
TEST_TIME 116 9096
TEST_TIME 117 9104
TEST_TIME 118 9120
TEST_TIME 119 9113
TEST_TIME 120 9116

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2021 19:38:51 to 18-10-2021 19:38:55.

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 1530054 1530054 0 0.00 0 19503 6 0 0
doc_list_biud 1530054 1530054 0 0.00 0 24317 24 0 0
sp_add_invoice_to_stock 5222 5132 90 1.72 33 219232 18195 0 90
sp_cancel_adding_invoice 5445 5445 0 0.00 5 81444 817 0 0
sp_cancel_client_order 1794 1794 0 0.00 4 11867 327 0 0
sp_cancel_customer_reserve 535982 535982 0 0.00 1 20457 54 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 7005 76 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 2 9007 87 0 0
sp_cancel_supplier_invoice 6016 6003 13 0.21 2 24197 747 0 13
sp_cancel_supplier_order 391 391 0 0.00 6 980811 93518 0 0
sp_cancel_write_off 10364 10364 0 0.00 1 8706 105 0 0
sp_client_order 21121 21121 0 0.00 3 23469 366 0 0
sp_customer_reserve 470458 470458 0 0.00 0 96968 183 0 0
sp_fill_shopping_cart 32331 32331 0 0.00 1 9916 39 0 0
sp_fill_shopping_cart_clo_res 470456 470456 0 0.00 0 17740 13 0 0
sp_get_clo_for_invoice 5126 5126 0 0.00 0 19043 311 0 0
sp_kill_cost_storno 16925 16925 0 0.00 0 19496 57 0 0
sp_kill_qstorno_ret_qs2qd 1088722 1088722 0 0.00 0 24183 29 0 0
sp_kill_qty_storno 575218 575218 0 0.00 0 24316 64 0 0
sp_lock_selected_doc 21047 21047 0 0.00 0 8240 4 0 0
sp_make_cost_storno 28389 28389 0 0.00 0 8847 49 0 0
sp_multiply_rows_for_pdistr 28389 28389 0 0.00 0 7299 29 0 0
sp_multiply_rows_for_qdistr 21121 21121 0 0.00 0 23459 175 0 0
sp_pay_from_customer 7223 7223 0 0.00 4 17013 113 0 0
sp_pay_to_supplier 5927 5927 0 0.00 4 7454 129 0 0
sp_payment_common 13150 13150 0 0.00 1 16993 30 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 11217 291 0 0
sp_qd_handle_on_invoice_upd_sts 10566 10566 0 0.00 0 24316 308 0 0
sp_qd_handle_on_reserve_upd_sts 10118 10118 0 0.00 0 11591 58 0 0
sp_reserve_write_off 10118 10118 0 0.00 6 13726 167 0 0
sp_supplier_invoice 5636 5636 0 0.00 6 86215 3414 0 0
sp_supplier_order 5591 5591 0 0.00 5 35133 1300 0 0
srv_aggregate_perf_data 101 100 1 0.99 2760 245058 21138 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1685 162188 44808 0 0
srv_find_qd_qs_mism 1045512 1045512 0 0.00 0 9556 6 0 0
srv_make_invnt_saldo 606 606 0 0.00 10 25800 860 0 0
srv_make_money_saldo 752 752 0 0.00 1 6290 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 218535 379258 293052 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 5132 5132 0 0.00 0 22731 173 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 8179 128 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 9052 22 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 790 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 1999 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 755 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 5401 24 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 20020 1650 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 3972 15 0 0
t$perf-norm:sp_client_order 21121 21121 0 0.00 0 8941 16 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 2900 10 0 0
t$perf-norm:sp_pay_from_customer 7223 7223 0 0.00 0 11479 11 0 0
t$perf-norm:sp_pay_to_supplier 5927 5927 0 0.00 0 4293 10 0 0
t$perf-norm:sp_reserve_write_off 10118 10118 0 0.00 0 9914 13 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 10582 16 0 0
t$perf-norm:sp_supplier_order 5591 5591 0 0.00 0 1879 12 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 311 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 47 1 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 2176 21 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 546 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 10420 10240 180 1.72 0 11091 17 0 180
v_all_customers 38788 38788 0 0.00 0 183 1 0 0
v_all_suppliers 30844 30844 0 0.00 0 21 1 0 0
v_all_wares 42242 42242 0 0.00 0 81 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 10962 19 0 0
v_cancel_client_order 3588 3588 0 0.00 0 11146 8 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 2019 7 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2543 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 8997 14 0 0
v_cancel_supplier_invoice 820 794 26 3.17 0 5119 30 0 26
v_cancel_supplier_order 782 782 0 0.00 0 3193 11 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2368 14 0 0
v_max_id_clo_ord 5590 5590 0 0.00 0 9793 3 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1666 10 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 5871 7 0 0
v_max_non_paid_invoice 1713 1713 0 0.00 0 1345 3 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 4864 5 0 0
v_min_id_clo_ord 5590 5590 0 0.00 0 4409 6 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 7106 11 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 6353 5 0 0
v_min_non_paid_invoice 5927 5927 0 0.00 0 5259 18 0 0
v_min_non_paid_realizn 7223 7223 0 0.00 0 16988 25 0 0
v_our_firm 10400 10400 0 0.00 0 16 1 0 0
v_reserve_write_off 20236 20236 0 0.00 0 10857 6 0 0
x_lock_depdocs_on_canc_invoice 11051 11051 0 0.00 0 10597 112 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 17237 449 0 0
x_make_qstorno_1000_1200 5591 5591 0 0.00 2 35064 1223 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 2 80362 3269 0 0
x_make_qstorno_2100_3300 470458 470458 0 0.00 0 96960 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2021 19:38:56 to 18-10-2021 19:39:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:55 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:56 34876 8192000000 9918907840 10232844288 990752688 10.789 100 86 172 0 1019587536 6200368 22634480
17:10:36 28516 8192000000 9963004272 10289205248 1035746304 11.224 100 88 176 0 1066782080 9028592 22007184
17:12:11 8992 8192000000 9990908416 10327166976 1076585104 11.615 100 86 172 0 1108133760 10366768 21181888
17:13:34 12685 8192000000 10045766608 10398543872 1130416688 12.125 100 73 147 0 1158922816 11158096 17348032
17:14:50 6981 8192000000 10099543632 10464759808 1183836336 12.626 100 78 156 2 1212960496 11100656 18023504
17:16:00 10323 8192000000 10148401152 10528305152 1240705072 13.153 100 68 136 0 1266657520 10276944 15675504
17:17:12 2940 8192000000 10193141008 10577313792 1279101440 13.505 100 80 160 1 1311244928 13836720 18306768
17:18:18 2139 8192000000 10209432976 10600804352 1301727840 13.711 100 82 163 0 1330540064 10177776 18634448
17:19:21 1592 8192000000 10225631056 10611286016 1317953472 13.858 100 89 178 0 1351440080 13711744 19774864
17:20:24 7873 8192000000 10208545200 10619682816 1317185792 13.851 100 39 78 1 1333370944 7482768 8702384
17:21:36 12325 8192000000 10208291264 10630950912 1326434048 13.935 100 12 24 0 1334475264 5504512 2536704
17:22:49 63860 8192000000 10230537552 10654027776 1333588816 14.000 100 32 65 0 1347056016 5254000 8213200
17:24:53 6423 8192000000 10245406160 10662068224 1345230656 14.105 100 29 58 1 1357285328 5508064 6546608
17:26:00 8060 8192000000 10227634384 10653667328 1339530048 14.053 100 17 34 1 1349441104 5370336 4540720
17:27:09 22777 8192000000 10236581600 10661965824 1331837856 13.984 99 33 66 0 1345003312 5401408 7764048
17:29:06 7644 8192000000 10215062880 10637455360 1316021392 13.841 99 24 48 1 1328896208 6580288 6294528
17:30:17 16129 8192000000 10173387072 10580885504 1230511296 13.059 95 25 50 2 1241734480 5597232 5625952
17:31:35 7844 8192000000 10122385648 10518044672 1202015664 12.795 97 15 30 0 1211124336 5450928 3657744
17:32:43 45919 8192000000 10136998176 10542383104 1242323024 13.168 98 25 49 2 1253235696 5238448 5674224
17:34:29 4796 8192000000 10191590448 10595110912 1286452848 13.572 100 49 97 1 1310281968 13030160 10798960
17:35:34 7718 8192000000 10198583504 10606579712 1298165920 13.679 100 34 68 1 1311788064 4996096 8626048
17:36:47 61168 8192000000 10211009568 10626453504 1315436480 13.835 100 18 35 1 1324051536 4837232 3777824
17:39:05 27113 8192000000 10255827808 10671112192 1348262144 14.132 100 38 76 0 1365164768 8388688 8513936
17:40:35 5323 8192000000 10278533600 10693632000 1366667520 14.297 100 48 96 0 1388002384 9487024 11847840
17:41:45 8273 8192000000 10298505344 10716614656 1378582480 14.404 100 51 102 4 1405598944 14835712 12180752
17:43:01 26115 8192000000 10308332080 10728886272 1389962384 14.506 100 67 134 0 1420448320 14401088 16084848
17:44:30 14148 8192000000 10319932080 10741104640 1382053056 14.435 99 53 106 1 1418360208 23758208 12548944
17:45:48 8595 8192000000 10329593008 10753867776 1407602160 14.663 100 55 108 0 1442178880 21368320 13208400
17:47:03 3100 8192000000 10333695984 10754617344 1406700592 14.655 100 61 122 1 1446459072 25661104 14097376
17:48:13 8879 8192000000 10340702784 10764427264 1409372144 14.678 100 64 126 0 1451617824 26951920 15293760
17:49:27 3652 8192000000 10356841232 10775121920 1412728080 14.708 100 77 154 0 1463700816 32069504 18903232
17:50:36 5973 8192000000 10348018480 10760282112 1400782704 14.602 99 73 146 2 1450742240 31685536 18274000
17:51:47 2056 8192000000 10373168544 10785759232 1415873536 14.736 100 81 163 1 1473206944 37190832 20142576
17:52:52 1683 8192000000 10362571168 10772475904 1405970400 14.648 100 88 175 1 1465602288 38127504 21504384
17:53:58 2910 8192000000 10367273680 10779176960 1406839232 14.656 100 81 160 1 1470939696 44450752 19649712
17:55:08 2647 8192000000 10356469392 10763087872 1386598416 14.476 100 83 166 1 1456344464 48476816 21269232
17:56:14 5008 8192000000 10337033728 10751275008 1362057424 14.256 99 61 123 2 1426380544 47524784 16798336
17:57:24 5321 8192000000 10319542752 10746429440 1368278928 14.312 100 49 98 2 1427230592 46680144 12271520
17:58:35 9744 8192000000 10326816096 10754060288 1380221584 14.419 100 44 88 2 1433611152 41060352 12329216
17:59:50 22859 8192000000 10336514960 10781331456 1398294000 14.580 100 24 48 1 1447778400 42780032 6704368
18:01:15 18923 8192000000 10323125168 10762592256 1369460432 14.322 99 28 56 1 1423533104 46415712 7656960
18:02:36 21981 8192000000 10287609696 10732744704 1323977408 13.913 97 32 64 1 1375066096 42541120 8547568
18:04:01 35975 8192000000 10298764208 10729709568 1365627840 14.288 100 38 76 2 1391414096 16456976 9329280
18:05:47 35714 8192000000 10282526576 10722734080 1349647696 14.144 99 24 49 1 1371581136 15138800 6794640
18:07:24 89845 8192000000 10238694016 10678648832 1324565904 13.918 97 26 53 0 1340801664 10079712 6156048
18:10:04 85226 8192000000 10246527232 10685104128 1318030576 13.859 96 27 53 0 1340374384 16854768 5489040
18:12:32 83367 8192000000 10281300304 10725945344 1364632768 14.279 99 22 44 2 1381974368 11754960 5586640
18:15:03 37378 8192000000 10303311936 10741178368 1383724336 14.450 100 22 44 0 1401321968 12277616 5320016
18:16:52 39533 8192000000 10263512448 10720653312 1353685200 14.181 98 12 24 2 1365642800 9299408 2658192
18:18:39 162188 8192000000 10276332784 10733264896 1350864672 14.155 98 15 30 2 1366285264 11131808 4288784
18:22:24 91023 8192000000 10275331216 10736455680 1373792576 14.361 99 26 52 0 1391830464 11295456 6742432
18:25:11 54027 8192000000 10271525232 10738606080 1371569040 14.341 99 19 38 0 1389004592 12852352 4583200
18:27:21 69354 8192000000 10287308640 10747088896 1369241360 14.320 98 8 16 0 1382301168 11361616 1698192
18:29:31 28333 8192000000 10302258512 10753728512 1392836096 14.531 100 21 42 0 1411236864 13547888 4852880
18:31:07 41638 8192000000 10298912064 10748788736 1381137936 14.427 98 19 38 1 1396267280 10861072 4268272
18:32:48 60324 8192000000 10300700736 10763993088 1386956880 14.479 99 16 32 0 1401167152 11095072 3115200
18:34:53 43509 8192000000 10300778928 10751111168 1369812960 14.325 98 15 30 2 1384303984 10969920 3521104
18:36:38 46187 8192000000 10293825856 10730745856 1335607744 14.018 96 24 48 0 1349989712 9390768 4991200
18:38:33 53178 8192000000 10290475248 10737803264 1322292528 13.897 95 12 24 0 1335415648 11080528 2042592
18:40:33 85923 8192000000 10258965136 10705920000 1263420304 13.361 92 11 22 0 1278513488 12946544 2146640
18:42:59 73929 8192000000 10282288176 10715213824 1351826784 14.164 99 50 100 2 1376018528 12994496 11197248
18:45:25 32629 8192000000 10258381616 10713882624 1362026432 14.256 100 20 40 0 1374799968 8534432 4239104
18:47:05 71063 8192000000 10271373152 10727469056 1372674800 14.351 100 8 16 0 1387166800 12898448 1593552
18:49:16 60841 8192000000 10247606592 10699665408 1298635488 13.683 95 13 26 0 1314619296 12960832 3022976
18:51:19 66148 8192000000 10257094960 10700079104 1315467648 13.836 97 23 46 0 1332568480 11803584 5297248
18:53:28 45500 8192000000 10266463440 10701447168 1316437248 13.844 97 23 46 0 1328784224 6939776 5407200
18:55:14 59699 8192000000 10282658384 10727436288 1358267552 14.222 99 15 30 0 1371867264 10259920 3339792
18:57:21 67482 8192000000 10283547344 10732077056 1363185888 14.266 99 17 34 0 1381122800 13988176 3948736
19:00:05 17099 8192000000 10291151840 10724241408 1345533824 14.107 98 32 62 0 1362518000 9561376 7422800
19:01:35 137099 8192000000 10281395328 10725179392 1340009056 14.057 98 18 36 1 1360545984 16121872 4415056
19:04:53 2201 8192000000 10289781904 10736783360 1366684784 14.297 100 31 62 2 1392740064 18764240 7291040
19:05:55 60256 8192000000 10277100192 10733580288 1378225920 14.401 100 10 20 0 1387962720 7383136 2353664
19:09:25 49746 8192000000 10292134032 10749440000 1387769936 14.486 100 9 16 0 1398756848 9875808 1111104
19:11:15 101869 8192000000 10301832368 10764537856 1356752848 14.208 97 13 26 1 1377921232 18186736 2981648
19:14:04 46262 8192000000 10292339488 10754396160 1382635712 14.440 99 10 20 0 1396165760 11159616 2370432
19:15:53 26671 8192000000 10326272128 10769866752 1402477184 14.617 100 48 96 0 1423651744 10962768 10211792
19:17:26 62415 8192000000 10307488224 10750017536 1356721504 14.208 97 23 46 1 1375563888 13406304 5436080
19:19:36 31683 8192000000 10316696400 10762321920 1393097664 14.533 100 36 72 1 1417582160 16020272 8464224
19:21:10 91171 8192000000 10312364416 10759655424 1368524448 14.314 98 18 36 0 1387207600 15212688 3470464
19:23:42 73370 8192000000 10271841936 10727190528 1335496256 14.017 97 24 46 0 1351811968 10559424 5756288
19:26:24 55195 8192000000 10282659472 10741547008 1377621296 14.395 100 15 30 0 1392104016 11218176 3264544
19:28:19 54595 8192000000 10268015232 10727297024 1344146064 14.095 97 11 22 1 1361836656 15132576 2558016
19:30:18 51869 8192000000 10256548912 10705489920 1297983904 13.677 95 12 24 1 1312611584 11650048 2977632
19:32:19 66742 8192000000 10218048000 10658684928 1191546944 12.698 88 11 22 0 1203467152 9129216 2790992
19:34:30 59803 8192000000 10255539232 10706366464 1348036400 14.130 100 19 40 1 1362422864 9709376 4677088
19:36:41 55300 8192000000 10240543808 10681102336 1264741488 13.373 94 24 48 1 1278277008 8377760 5157760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-10-2021 19:39:02 to 18-10-2021 19:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 90 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2021 19:39:17 to 18-10-2021 19:39:19.

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          240076
MON$OLDEST_ACTIVE               241580
MON$OLDEST_SNAPSHOT             241580
MON$NEXT_TRANSACTION            241580
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                       2000832
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:567: 
:568: mscs-test-161.budzdorov.ru    Mon Oct 18 19:37:52 2021
:569:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:570: 
:571: 
:572: mscs-test-161.budzdorov.ru    Mon Oct 18 19:37:53 2021
:573:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:574: 
:575: 
:576: mscs-test-161.budzdorov.ru    Mon Oct 18 19:37:54 2021
:577:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:578: 
:579: 
:580: mscs-test-161.budzdorov.ru    Mon Oct 18 19:37:55 2021
:581:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:582: 
:583: 
:584: mscs-test-161.budzdorov.ru    Mon Oct 18 19:37:57 2021
:585:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:586: 
--- end of diff output ---

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

18.10.21 19:39:20. 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-18 19:39:23 No severe PSQL-related problems occured
18.10.21 19:39:24. End of report.