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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16134256 kB
MemAvailable 30840292 kB
Buffers 0 kB
Cached 15548376 kB
SwapCached 388 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-12 22:37:44
DTS_END                         2021-09-12 22:37:44.7670
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/54080
TRN_ID                          236299
ATT_ID                          675
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: 9287.12 22402 1114454
customer order: creation 166.11 333 19933
customer order: refuse 14.23 383 1707
order to supplier: creation 45.77 1221 5492
order to supplier: removal 3.48 113476 418
invoice (draft): creation 44.71 3285 5365
invoice (draft): removal 54.00 818 6480
invoice accept: apply 40.73 19663 4888
invoice accept: cancel 49.39 830 5927
customer reserve: creation 3761.77 195 451412
customer reserve: removal 4811.50 64 577380
realization accept: apply 79.41 156 9529
realization accept: cancel 90.62 105 10874
payment from customer: creation 56.83 114 6819
payment from customer: removal 5.50 110 660
payment to supplier: creation 47.92 120 5750
payment to supplier: removal 3.65 82 438
service: total inventory turnovers 4.47 1256 536
service: total monetary turnovers 6.23 46 747
service: aggregate perf. data 0.79 22355 95
service: refresh index statistics 0.03 283436 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-09-2021 22:39:40 to 12-09-2021 22:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1296
TEST_TIME 2 1950
TEST_TIME 3 3285
TEST_TIME 4 3674
TEST_TIME 5 4116
TEST_TIME 6 4811
TEST_TIME 7 5106
TEST_TIME 8 5425
TEST_TIME 9 5460
TEST_TIME 10 5782
TEST_TIME 11 6164
TEST_TIME 12 6000
TEST_TIME 13 6167
TEST_TIME 14 6688
TEST_TIME 15 6699
TEST_TIME 16 6924
TEST_TIME 17 7407
TEST_TIME 18 7224
TEST_TIME 19 7388
TEST_TIME 20 7305
TEST_TIME 21 8295
TEST_TIME 22 8634
TEST_TIME 23 8565
TEST_TIME 24 8977
TEST_TIME 25 9078
TEST_TIME 26 9579
TEST_TIME 27 10205
TEST_TIME 28 11157
TEST_TIME 29 10875
TEST_TIME 30 10717
TEST_TIME 31 10524
TEST_TIME 32 10561
TEST_TIME 33 10594
TEST_TIME 34 10375
TEST_TIME 35 10209
TEST_TIME 36 10125
TEST_TIME 37 10132
TEST_TIME 38 10032
TEST_TIME 39 10004
TEST_TIME 40 9991
TEST_TIME 41 9835
TEST_TIME 42 9725
TEST_TIME 43 9633
TEST_TIME 44 9635
TEST_TIME 45 9533
TEST_TIME 46 9491
TEST_TIME 47 9486
TEST_TIME 48 9468
TEST_TIME 49 9406
TEST_TIME 50 9348
TEST_TIME 51 9384
TEST_TIME 52 9359
TEST_TIME 53 9316
TEST_TIME 54 9205
TEST_TIME 55 9160
TEST_TIME 56 9110
TEST_TIME 57 9033
TEST_TIME 58 9072
TEST_TIME 59 9079
TEST_TIME 60 9105
TEST_TIME 61 9040
TEST_TIME 62 9129
TEST_TIME 63 9115
TEST_TIME 64 9217
TEST_TIME 65 9162
TEST_TIME 66 9214
TEST_TIME 67 9271
TEST_TIME 68 9373
TEST_TIME 69 9354
TEST_TIME 70 9346
TEST_TIME 71 9429
TEST_TIME 72 9417
TEST_TIME 73 9392
TEST_TIME 74 9282
TEST_TIME 75 9250
TEST_TIME 76 9272
TEST_TIME 77 9264
TEST_TIME 78 9209
TEST_TIME 79 9156
TEST_TIME 80 9110
TEST_TIME 81 9104
TEST_TIME 82 9101
TEST_TIME 83 9125
TEST_TIME 84 9107
TEST_TIME 85 9205
TEST_TIME 86 9158
TEST_TIME 87 9161
TEST_TIME 88 9197
TEST_TIME 89 9196
TEST_TIME 90 9255
TEST_TIME 91 9242
TEST_TIME 92 9290
TEST_TIME 93 9348
TEST_TIME 94 9321
TEST_TIME 95 9387
TEST_TIME 96 9384
TEST_TIME 97 9316
TEST_TIME 98 9259
TEST_TIME 99 9286
TEST_TIME 100 9284
TEST_TIME 101 9245
TEST_TIME 102 9210
TEST_TIME 103 9195
TEST_TIME 104 9162
TEST_TIME 105 9128
TEST_TIME 106 9087
TEST_TIME 107 9178
TEST_TIME 108 9168
TEST_TIME 109 9200
TEST_TIME 110 9184
TEST_TIME 111 9187
TEST_TIME 112 9284
TEST_TIME 113 9260
TEST_TIME 114 9271
TEST_TIME 115 9267
TEST_TIME 116 9290
TEST_TIME 117 9284
TEST_TIME 118 9309
TEST_TIME 119 9344
TEST_TIME 120 9288

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-09-2021 22:39:44 to 12-09-2021 22:39:47.

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 1533971 1533971 0 0.00 0 19274 7 0 0
doc_list_biud 1533971 1533971 0 0.00 0 33241 30 0 0
sp_add_invoice_to_stock 5004 4888 116 2.31 38 175867 19664 0 116
sp_cancel_adding_invoice 5927 5927 0 0.00 4 90472 831 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 11819 384 0 0
sp_cancel_customer_reserve 577380 577380 0 0.00 1 29445 64 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 7478 111 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 7974 82 0 0
sp_cancel_supplier_invoice 6491 6480 11 0.16 1 33249 819 0 11
sp_cancel_supplier_order 418 418 0 0.00 7 978327 113476 0 0
sp_cancel_write_off 10874 10874 0 0.00 1 7120 106 0 0
sp_client_order 19933 19933 0 0.00 3 21169 334 0 0
sp_customer_reserve 451412 451412 0 0.00 0 45058 196 0 0
sp_fill_shopping_cart 30774 30774 0 0.00 1 15356 40 0 0
sp_fill_shopping_cart_clo_res 451409 451409 0 0.00 0 41678 14 0 0
sp_get_clo_for_invoice 4886 4886 0 0.00 0 54077 419 0 0
sp_kill_cost_storno 17899 17899 0 0.00 0 11433 56 0 0
sp_kill_qstorno_ret_qs2qd 1172532 1172532 0 0.00 0 33238 35 0 0
sp_kill_qty_storno 617201 617201 0 0.00 0 33240 74 0 0
sp_lock_selected_doc 20061 20061 0 0.00 0 8012 7 0 0
sp_make_cost_storno 26984 26984 0 0.00 0 11041 50 0 0
sp_multiply_rows_for_pdistr 26984 26984 0 0.00 0 8658 29 0 0
sp_multiply_rows_for_qdistr 19933 19933 0 0.00 0 19185 148 0 0
sp_pay_from_customer 6819 6819 0 0.00 4 18579 115 0 0
sp_pay_to_supplier 5750 5750 0 0.00 5 8705 120 0 0
sp_payment_common 12569 12569 0 0.00 1 18574 36 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 11805 354 0 0
sp_qd_handle_on_invoice_upd_sts 10813 10813 0 0.00 0 17400 326 0 0
sp_qd_handle_on_reserve_upd_sts 9529 9529 0 0.00 0 8642 44 0 0
sp_reserve_write_off 9529 9529 0 0.00 6 8680 156 0 0
sp_supplier_invoice 5365 5365 0 0.00 7 66358 3285 0 0
sp_supplier_order 5492 5492 0 0.00 5 25229 1222 0 0
srv_aggregate_perf_data 96 95 1 1.04 3344 275379 22356 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1127 119797 46777 0 0
srv_find_qd_qs_mism 1068762 1068762 0 0.00 0 19194 6 0 0
srv_make_invnt_saldo 536 536 0 0.00 52 154762 1257 0 0
srv_make_money_saldo 747 747 0 0.00 1 13869 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 163728 503499 283436 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 4888 4888 0 0.00 0 37524 178 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 6289 77 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 9354 24 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 641 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 3833 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 515 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 1002 13 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 29391 1731 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 1029 8 0 0
t$perf-norm:sp_client_order 19933 19933 0 0.00 0 10295 19 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 4913 16 0 0
t$perf-norm:sp_pay_from_customer 6819 6819 0 0.00 0 5236 12 0 0
t$perf-norm:sp_pay_to_supplier 5750 5750 0 0.00 0 5560 8 0 0
t$perf-norm:sp_reserve_write_off 9529 9529 0 0.00 0 14674 17 0 0
t$perf-norm:sp_supplier_invoice 5365 5365 0 0.00 0 5168 23 0 0
t$perf-norm:sp_supplier_order 5492 5492 0 0.00 0 4415 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 242 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1054 24 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 3618 28 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 3048 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10004 9772 232 2.31 0 6194 19 0 232
v_all_customers 36490 36490 0 0.00 0 32 1 0 0
v_all_suppliers 29908 29908 0 0.00 0 23 1 0 0
v_all_wares 39866 39866 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 409 7 0 0
v_cancel_client_order 3414 3414 0 0.00 0 4523 7 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 1582 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 868 3 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 7941 16 0 0
v_cancel_supplier_invoice 798 776 22 2.75 0 3495 21 0 22
v_cancel_supplier_order 836 836 0 0.00 0 1091 5 0 0
v_cancel_write_off 1922 1922 0 0.00 0 1020 12 0 0
v_max_id_clo_ord 5492 5492 0 0.00 0 2371 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 3154 16 0 0
v_max_id_ord_sup 5349 5349 0 0.00 0 4686 5 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 2931 5 0 0
v_max_non_paid_realizn 3548 3548 0 0.00 0 4409 8 0 0
v_min_id_clo_ord 5492 5492 0 0.00 0 5042 6 0 0
v_min_id_clo_res 983 983 0 0.00 0 2561 9 0 0
v_min_id_ord_sup 5349 5349 0 0.00 0 8253 6 0 0
v_min_non_paid_invoice 5750 5750 0 0.00 0 5549 19 0 0
v_min_non_paid_realizn 6819 6819 0 0.00 0 18572 24 0 0
v_our_firm 9918 9918 0 0.00 0 23 1 0 0
v_reserve_write_off 19058 19058 0 0.00 0 7388 5 0 0
x_lock_depdocs_on_canc_invoice 12019 12019 0 0.00 0 19815 162 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8357 435 0 0
x_make_qstorno_1000_1200 5492 5492 0 0.00 2 25186 1141 0 0
x_make_qstorno_1200_2000 5365 5365 0 0.00 3 66349 3140 0 0
x_make_qstorno_2100_3300 451412 451412 0 0.00 0 35685 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 22:39:48 to 12-09-2021 22:39:52.

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:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:49 14239 8192000000 10355485648 10671603712 1415622128 14.734 100 88 176 0 1449470016 5381376 28466512
20:10:09 20721 8192000000 10431492512 10762240000 1486132112 15.355 100 90 180 0 1522833392 7664080 29037200
20:11:43 16756 8192000000 10501454096 10844217344 1556790880 15.969 100 86 172 0 1592745744 9109760 26845104
20:13:01 12331 8192000000 10567454480 10927140864 1624141904 16.545 100 78 156 0 1658013536 10654960 23216672
20:14:16 7796 8192000000 10641136656 11008995328 1701482400 17.198 100 76 152 2 1735355104 11461936 22410768
20:15:28 6273 8192000000 10702900208 11079852032 1761159872 17.694 100 82 163 0 1798128752 13988880 22980000
20:16:37 3333 8192000000 10757500000 11140108288 1814242064 18.131 100 91 179 1 1851524624 13137552 24145008
20:17:42 2916 8192000000 10792985184 11183484928 1854142304 18.456 100 93 184 1 1893524352 13661920 25720128
20:18:46 5505 8192000000 10825000720 11224055808 1883738016 18.695 100 78 157 2 1918066032 12404368 21923648
20:20:03 9893 8192000000 10802327232 11225358336 1887557488 18.726 100 29 60 1 1902216352 6642320 8016544
20:21:15 7011 8192000000 10813913248 11243216896 1906832880 18.881 100 30 60 2 1921853136 5951920 9068336
20:22:25 11761 8192000000 10822182560 11256397824 1912533232 18.927 100 11 22 1 1921513776 5927232 3053312
20:23:38 65950 8192000000 10842140176 11279020032 1928738624 19.057 100 34 69 1 1944934960 6260160 9936176
20:25:45 4553 8192000000 10844700352 11284013056 1930135760 19.068 100 31 63 0 1945374912 6677696 8561456
20:26:53 6293 8192000000 10821078544 11255476224 1903446272 18.854 99 18 36 0 1914772480 6289568 5036640
20:28:02 6238 8192000000 10757343664 11173801984 1822022256 18.194 98 27 53 1 1835669728 6583104 7064368
20:29:08 15082 8192000000 10750200080 11179933696 1788186384 17.917 96 23 46 1 1799851568 4952464 6712720
20:30:32 5620 8192000000 10708513440 11133788160 1772817136 17.790 98 28 55 1 1791344800 10536880 7990784
20:31:38 8164 8192000000 10688124592 11096707072 1708561680 17.257 95 29 58 1 1722609616 5396752 8651184
20:32:51 60945 8192000000 10792321984 11181699072 1842586528 18.362 100 54 106 2 1863794592 4782416 16425648
20:34:53 6907 8192000000 10785611808 11205160960 1864536960 18.540 100 37 74 2 1882134768 6425280 11172528
20:36:00 8087 8192000000 10786305888 11218317312 1871998640 18.600 100 20 39 0 1883490768 6160496 5331632
20:37:09 9774 8192000000 10820418496 11237597184 1891650976 18.759 100 29 59 1 1905667600 5260832 8755792
20:38:20 77003 8192000000 10847852960 11269464064 1918008032 18.971 100 44 88 0 1937640864 7718992 11913840
20:40:43 27111 8192000000 10883831648 11309535232 1947163840 19.204 100 50 99 0 1971125088 9947392 14013856
20:42:16 7561 8192000000 10893990640 11322982400 1954587360 19.263 100 51 102 1 1982862704 13792688 14482656
20:43:31 6406 8192000000 10903412352 11330588672 1961799456 19.320 100 50 99 1 1990756512 14463472 14493584
20:44:43 15331 8192000000 10924688576 11348832256 1980128752 19.466 100 63 126 2 2014185200 15227104 18829344
20:46:00 2889 8192000000 10944591248 11363663872 1993487104 19.571 100 72 143 1 2032046688 16925248 21634336
20:47:07 9502 8192000000 10958257824 11379163136 1999374896 19.618 100 68 135 1 2040624992 21371040 19879056
20:48:21 6470 8192000000 10948346816 11375501312 1966644352 19.359 98 60 118 3 2004124912 19369376 18111184
20:49:30 13667 8192000000 10967033488 11392409600 2002938784 19.646 100 71 141 3 2049592720 24735472 21918464
20:50:47 5645 8192000000 10955770944 11376852992 1989147568 19.537 100 74 149 0 2044237088 32517904 22571616
20:51:57 6034 8192000000 10972486848 11397722112 2001724080 19.636 100 76 152 1 2056945104 31867936 23353088
20:53:12 7505 8192000000 10964994960 11398643712 1996938416 19.599 100 62 125 2 2052140480 35609712 19592352
20:54:25 2020 8192000000 10974063024 11387248640 1987532912 19.524 100 88 177 1 2054435328 40014032 26888384
20:55:38 2950 8192000000 10979734256 11396366336 1998459312 19.611 100 85 170 1 2069408368 43394720 27554336
20:56:45 7753 8192000000 10992313760 11412484096 1997343664 19.602 100 83 164 3 2077212128 53449264 26419200
20:57:58 1125 8192000000 11013920960 11425013760 1995855296 19.590 100 96 192 0 2093232752 66518416 30859040
20:59:03 12123 8192000000 10977420816 11416989696 1996170768 19.593 100 46 92 1 2061604784 49919392 15514624
21:00:25 19713 8192000000 10979724480 11424317440 1980451952 19.468 99 34 68 0 2049268448 57078224 11738272
21:01:54 14115 8192000000 10946937040 11391561728 1946476448 19.198 99 41 81 2 2020150432 60288608 13385376
21:03:26 19802 8192000000 10920788624 11390656512 1928114064 19.052 99 24 48 2 2006625232 68578208 9932960
21:04:46 36127 8192000000 10895411376 11364057088 1896931360 18.802 98 21 42 1 1955821456 51638992 7251104
21:06:22 63057 8192000000 10884813312 11335045120 1935956912 19.114 100 36 73 3 1964141872 17591600 10593360
21:08:28 64074 8192000000 10885043792 11362893824 1968827136 19.376 100 30 61 1 1992384272 15600256 7956880
21:10:50 66993 8192000000 10863643664 11322585088 1900376048 18.829 97 15 30 0 1911521968 7324576 3821344
21:12:57 49772 8192000000 10908716112 11368853504 1954744784 19.264 99 16 32 1 1975726032 16475536 4505712
21:14:48 90118 8192000000 10902114816 11373539328 1950102944 19.227 99 11 22 0 1974052832 21133968 2815920
21:17:45 74972 8192000000 10883287664 11353788416 1928174448 19.052 98 13 26 0 1945636000 13926256 3535296
21:20:01 39694 8192000000 10915506784 11374104576 1972431168 19.405 99 45 88 2 1996691776 11722368 12538240
21:21:48 60632 8192000000 10889095296 11359805440 1932813312 19.089 98 9 18 0 1948513936 12901744 2798880
21:23:49 58629 8192000000 10873099968 11343564800 1927003744 19.043 98 13 26 0 1942705584 11982048 3719792
21:26:43 84686 8192000000 10902226352 11381825536 1982043536 19.481 100 30 58 0 2004136272 13430128 8662608
21:29:15 29699 8192000000 10895843632 11368366080 1981459200 19.476 100 35 70 0 1999890400 8399136 10032064
21:30:54 80733 8192000000 10900682656 11368026112 1964950528 19.345 99 29 59 0 1980586112 7500400 8135184
21:33:34 41846 8192000000 10891756752 11376205824 1973376656 19.412 99 28 56 3 1989620848 7590176 8654016
21:35:19 74701 8192000000 10889132512 11364114432 1927983728 19.051 97 9 18 0 1939559344 8895952 2679664
21:37:37 43515 8192000000 10903981216 11359023104 1894282160 18.780 95 14 28 0 1903914688 6512192 3120336
21:39:21 45720 8192000000 10930269488 11398934528 1981697024 19.478 99 33 66 0 2001244352 10296864 9250464
21:41:11 71927 8192000000 10917402160 11384782848 1923272336 19.013 96 18 36 1 1943318224 14883408 5162480
21:43:23 45105 8192000000 10918127808 11385372672 1959094032 19.299 98 36 72 1 1982579984 13474864 10011088
21:45:10 100261 8192000000 10873140752 11344883712 1816325168 18.148 92 7 14 0 1836333456 17616080 2392208
21:47:58 41005 8192000000 10904711328 11372113920 1941653280 19.160 98 29 58 2 1966520976 16648480 8219216
21:49:42 119797 8192000000 10894432768 11367043072 1930618976 19.072 98 16 33 1 1950329840 15687184 4023680
21:52:57 33749 8192000000 10888387344 11362938880 1940278192 19.149 99 28 56 0 1971715024 23523888 7912944
21:54:39 74624 8192000000 10862631440 11339706368 1911439376 18.918 98 10 20 0 1928940816 15008928 2492512
21:56:54 76110 8192000000 10842150880 11309408256 1861116864 18.512 95 21 40 1 1879543120 12846160 5580096
21:59:10 52452 8192000000 10851807984 11333423104 1906405904 18.878 99 8 16 1 1924398288 15734336 2258048
22:01:07 28846 8192000000 10834841904 11298799616 1858308880 18.490 96 17 34 1 1870994320 8153168 4532272
22:02:40 40561 8192000000 10868736784 11320057856 1919144096 18.980 99 34 67 0 1935949440 7780336 9025008
22:04:23 29443 8192000000 10894391760 11350867968 1956261056 19.276 100 41 82 0 1979367616 11757840 11348720
22:06:00 98430 8192000000 10867690816 11333607424 1842360544 18.360 94 9 18 0 1857125488 12103648 2661296
22:08:41 41914 8192000000 10911137584 11365658624 1942592368 19.167 99 25 50 2 1962150672 12114448 7443856
22:10:24 47996 8192000000 10915496016 11368329216 1953974688 19.258 99 9 17 0 1966359744 10496928 1888128
22:12:18 62005 8192000000 10886994496 11356049408 1895351248 18.789 96 15 30 1 1914012128 14680320 3980560
22:14:23 86940 8192000000 10892326016 11352309760 1924654416 19.024 98 20 40 1 1938313728 7735424 5923888
22:16:55 48688 8192000000 10882569344 11350192128 1956919520 19.282 100 9 18 0 1973946800 14827616 2199664
22:18:46 56051 8192000000 10887780992 11352866816 1960706464 19.312 100 15 30 0 1971788528 7090448 3991616
22:21:27 92422 8192000000 10900572160 11379523584 1973665856 19.415 100 14 28 2 1989860896 12215168 3979872
22:25:16 73890 8192000000 10886587072 11351842816 1887541328 18.726 96 10 20 0 1910258976 19104048 3613600
22:27:30 65658 8192000000 10900848304 11364880384 1933514240 19.095 98 22 44 1 1954881488 14871072 6496176
22:29:44 66009 8192000000 10906167536 11360382976 1959644144 19.303 100 17 33 0 1973734864 9680960 4409760
22:31:55 68860 8192000000 10892986560 11353137152 1919462832 18.983 98 29 58 0 1941270048 13891408 7915808
22:34:08 64219 8192000000 10863838816 11321221120 1873716176 18.614 96 28 56 0 1896542816 14818944 8007696
22:36:17 90171 8192000000 10559331376 11000414208 1641691600 16.694 84 20 38 2 1660788560 13507136 5589824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-09-2021 22:39:54 to 12-09-2021 22:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 116 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-09-2021 22:40:10 to 12-09-2021 22:40:12.

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          236530
MON$OLDEST_ACTIVE               236531
MON$OLDEST_SNAPSHOT             236531
MON$NEXT_TRANSACTION            236531
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                       2028640
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Sun Sep 12 22:38:32 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
--- end of diff output ---

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

12.09.21 22:40:13. 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-12 22:40:15 No severe PSQL-related problems occured
12.09.21 22:40:17. End of report.