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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16147288 kB
MemAvailable 30774036 kB
Buffers 0 kB
Cached 15540908 kB
SwapCached 2448 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-07 16:37:55
DTS_END                         2021-11-07 16:37:55.6320
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/46950
TRN_ID                          238100
ATT_ID                          637
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: 9147.25 23052 1097670
customer order: creation 168.40 357 20208
customer order: refuse 14.34 328 1721
order to supplier: creation 44.96 1215 5395
order to supplier: removal 3.53 91904 424
invoice (draft): creation 44.81 3146 5377
invoice (draft): removal 53.07 682 6368
invoice accept: apply 40.87 18060 4904
invoice accept: cancel 47.35 706 5682
customer reserve: creation 3803.93 178 456472
customer reserve: removal 4634.09 55 556091
realization accept: apply 81.81 201 9817
realization accept: cancel 85.27 114 10232
payment from customer: creation 56.43 132 6772
payment from customer: removal 5.40 124 648
payment to supplier: creation 47.48 164 5697
payment to supplier: removal 3.39 94 407
service: total inventory turnovers 4.72 1235 567
service: total monetary turnovers 6.48 29 778
service: aggregate perf. data 0.89 14837 107
service: refresh index statistics 0.03 327471 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-11-2021 16:39:10 to 07-11-2021 16:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2081
TEST_TIME 2 4901
TEST_TIME 3 4598
TEST_TIME 4 4505
TEST_TIME 5 3760
TEST_TIME 6 3910
TEST_TIME 7 4316
TEST_TIME 8 4643
TEST_TIME 9 5258
TEST_TIME 10 5743
TEST_TIME 11 6084
TEST_TIME 12 6764
TEST_TIME 13 7146
TEST_TIME 14 7163
TEST_TIME 15 7187
TEST_TIME 16 7447
TEST_TIME 17 7447
TEST_TIME 18 7381
TEST_TIME 19 7906
TEST_TIME 20 7948
TEST_TIME 21 8174
TEST_TIME 22 8290
TEST_TIME 23 8918
TEST_TIME 24 9398
TEST_TIME 25 9614
TEST_TIME 26 9941
TEST_TIME 27 10480
TEST_TIME 28 10401
TEST_TIME 29 10251
TEST_TIME 30 10027
TEST_TIME 31 10108
TEST_TIME 32 10184
TEST_TIME 33 10015
TEST_TIME 34 9884
TEST_TIME 35 9695
TEST_TIME 36 9617
TEST_TIME 37 9666
TEST_TIME 38 9576
TEST_TIME 39 9483
TEST_TIME 40 9384
TEST_TIME 41 9387
TEST_TIME 42 9373
TEST_TIME 43 9443
TEST_TIME 44 9313
TEST_TIME 45 9265
TEST_TIME 46 9109
TEST_TIME 47 9144
TEST_TIME 48 9115
TEST_TIME 49 9201
TEST_TIME 50 9169
TEST_TIME 51 9209
TEST_TIME 52 9265
TEST_TIME 53 9357
TEST_TIME 54 9380
TEST_TIME 55 9322
TEST_TIME 56 9371
TEST_TIME 57 9424
TEST_TIME 58 9379
TEST_TIME 59 9456
TEST_TIME 60 9502
TEST_TIME 61 9583
TEST_TIME 62 9527
TEST_TIME 63 9405
TEST_TIME 64 9333
TEST_TIME 65 9276
TEST_TIME 66 9284
TEST_TIME 67 9204
TEST_TIME 68 9107
TEST_TIME 69 9107
TEST_TIME 70 9151
TEST_TIME 71 9109
TEST_TIME 72 9101
TEST_TIME 73 9133
TEST_TIME 74 9195
TEST_TIME 75 9151
TEST_TIME 76 9122
TEST_TIME 77 9144
TEST_TIME 78 9253
TEST_TIME 79 9327
TEST_TIME 80 9330
TEST_TIME 81 9346
TEST_TIME 82 9314
TEST_TIME 83 9209
TEST_TIME 84 9206
TEST_TIME 85 9168
TEST_TIME 86 9210
TEST_TIME 87 9143
TEST_TIME 88 9124
TEST_TIME 89 9062
TEST_TIME 90 9059
TEST_TIME 91 9030
TEST_TIME 92 9045
TEST_TIME 93 9089
TEST_TIME 94 9079
TEST_TIME 95 9077
TEST_TIME 96 9113
TEST_TIME 97 9138
TEST_TIME 98 9141
TEST_TIME 99 9139
TEST_TIME 100 9180
TEST_TIME 101 9215
TEST_TIME 102 9245
TEST_TIME 103 9243
TEST_TIME 104 9227
TEST_TIME 105 9325
TEST_TIME 106 9301
TEST_TIME 107 9249
TEST_TIME 108 9220
TEST_TIME 109 9191
TEST_TIME 110 9194
TEST_TIME 111 9164
TEST_TIME 112 9212
TEST_TIME 113 9181
TEST_TIME 114 9150
TEST_TIME 115 9095
TEST_TIME 116 9109
TEST_TIME 117 9098
TEST_TIME 118 9107
TEST_TIME 119 9139
TEST_TIME 120 9152

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-11-2021 16:39:14 to 07-11-2021 16:39:17.

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 1521092 1521092 0 0.00 0 24517 6 0 0
doc_list_biud 1521092 1521092 0 0.00 0 36146 25 0 0
sp_add_invoice_to_stock 5033 4904 129 2.56 38 165021 18060 0 129
sp_cancel_adding_invoice 5682 5682 0 0.00 5 57635 707 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 10221 329 0 0
sp_cancel_customer_reserve 556091 556091 0 0.00 1 36148 55 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 11957 125 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 5491 94 0 0
sp_cancel_supplier_invoice 6381 6368 13 0.20 1 12684 683 0 13
sp_cancel_supplier_order 424 424 0 0.00 5 851090 91904 0 0
sp_cancel_write_off 10232 10232 0 0.00 1 19300 115 0 0
sp_client_order 20208 20208 0 0.00 3 24727 358 0 0
sp_customer_reserve 456472 456472 0 0.00 0 101756 178 0 0
sp_fill_shopping_cart 30959 30959 0 0.00 1 19972 36 0 0
sp_fill_shopping_cart_clo_res 456460 456460 0 0.00 0 21203 14 0 0
sp_get_clo_for_invoice 4892 4892 0 0.00 0 20849 297 0 0
sp_kill_cost_storno 16969 16969 0 0.00 0 9634 55 0 0
sp_kill_qstorno_ret_qs2qd 1129206 1129206 0 0.00 0 36144 30 0 0
sp_kill_qty_storno 595227 595227 0 0.00 0 36146 64 0 0
sp_lock_selected_doc 20298 20298 0 0.00 0 9959 6 0 0
sp_make_cost_storno 27178 27178 0 0.00 0 16922 55 0 0
sp_multiply_rows_for_pdistr 27178 27178 0 0.00 0 9495 34 0 0
sp_multiply_rows_for_qdistr 20208 20208 0 0.00 0 24719 173 0 0
sp_pay_from_customer 6772 6772 0 0.00 4 22567 133 0 0
sp_pay_to_supplier 5697 5697 0 0.00 5 16991 164 0 0
sp_payment_common 12469 12469 0 0.00 1 9683 39 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 8742 277 0 0
sp_qd_handle_on_invoice_upd_sts 10574 10574 0 0.00 0 27537 307 0 0
sp_qd_handle_on_reserve_upd_sts 9817 9817 0 0.00 0 13737 58 0 0
sp_reserve_write_off 9817 9817 0 0.00 6 23888 202 0 0
sp_supplier_invoice 5377 5377 0 0.00 6 100160 3147 0 0
sp_supplier_order 5395 5395 0 0.00 5 43122 1216 0 0
srv_aggregate_perf_data 108 107 1 0.92 2477 108299 14837 0 1
srv_fill_mon_cache_memory 57 57 0 0.00 1257 165503 54565 0 0
srv_find_qd_qs_mism 1051414 1051414 0 0.00 0 24516 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 42 201223 1236 0 0
srv_make_money_saldo 778 778 0 0.00 1 5174 29 0 0
srv_recalc_idx_stat 3 3 0 0.00 260721 398745 327472 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4904 4904 0 0.00 0 14821 174 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 2733 87 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 4229 15 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 1334 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 431 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1691 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 805 12 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 18888 1561 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 2015 5 0 0
t$perf-norm:sp_client_order 20208 20208 0 0.00 0 17739 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 7506 15 0 0
t$perf-norm:sp_pay_from_customer 6772 6772 0 0.00 0 17251 13 0 0
t$perf-norm:sp_pay_to_supplier 5697 5697 0 0.00 0 9900 11 0 0
t$perf-norm:sp_reserve_write_off 9817 9817 0 0.00 0 8476 12 0 0
t$perf-norm:sp_supplier_invoice 5377 5377 0 0.00 0 5061 19 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 6992 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 296 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 57 57 0 0.00 0 2318 41 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 485 15 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1632 14 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 15 7 0 0
v_add_invoice_to_stock 10030 9772 258 2.57 0 15325 22 0 258
v_all_customers 36354 36354 0 0.00 0 65 1 0 0
v_all_suppliers 29798 29798 0 0.00 0 18 1 0 0
v_all_wares 40404 40404 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 3373 11 0 0
v_cancel_client_order 3442 3442 0 0.00 0 6281 13 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 3004 7 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1793 7 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 5480 19 0 0
v_cancel_supplier_invoice 756 730 26 3.43 0 4313 19 0 26
v_cancel_supplier_order 848 848 0 0.00 0 3017 14 0 0
v_cancel_write_off 1952 1952 0 0.00 0 6277 16 0 0
v_max_id_clo_ord 5385 5385 0 0.00 0 2738 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 789 7 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 8204 4 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 6950 7 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 2238 5 0 0
v_min_id_clo_ord 5385 5385 0 0.00 0 18148 22 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 1534 3 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 5102 6 0 0
v_min_non_paid_invoice 5697 5697 0 0.00 0 9678 31 0 0
v_min_non_paid_realizn 6772 6772 0 0.00 0 7955 27 0 0
v_our_firm 10196 10196 0 0.00 0 179 1 0 0
v_reserve_write_off 19624 19624 0 0.00 0 9885 10 0 0
x_lock_depdocs_on_canc_invoice 11685 11685 0 0.00 0 16045 115 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 17639 404 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 1 43111 1124 0 0
x_make_qstorno_1200_2000 5377 5377 0 0.00 2 84265 3032 0 0
x_make_qstorno_2100_3300 456472 456472 0 0.00 0 101754 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-11-2021 16:39:18 to 07-11-2021 16:39:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:01 14602 8192000000 9903674736 10212315136 983965296 10.723 100 87 174 0 1012673344 5625632 23082416
14:10:23 11978 8192000000 9942436576 10261520384 1021839488 11.090 100 87 174 0 1052374224 8037488 22497248
14:11:46 16828 8192000000 9967343360 10298875904 1052928576 11.389 100 88 174 0 1084610224 9746944 21934704
14:13:08 9006 8192000000 10004421552 10348089344 1092320528 11.765 100 81 162 1 1122659520 11123968 19215024
14:14:26 11762 8192000000 10070483056 10430775296 1154556384 12.352 100 69 138 0 1182321920 11920384 15845152
14:15:40 7023 8192000000 10103684656 10475507712 1201869360 12.794 100 75 150 2 1231409312 12609552 16930400
14:16:51 5807 8192000000 10154828832 10539368448 1249879888 13.237 100 65 130 0 1276395632 12528096 13987648
14:17:59 1780 8192000000 10214027536 10596036608 1293840048 13.639 100 96 192 0 1330573456 15445872 21287536
14:19:02 3939 8192000000 10202929152 10607923200 1307652448 13.765 100 56 112 3 1330754048 10938544 12163056
14:20:07 6899 8192000000 10214002816 10620231680 1322463680 13.899 100 18 35 2 1332117424 5578080 4075664
14:21:16 35420 8192000000 10213868464 10637193216 1327246896 13.942 100 25 49 4 1338517280 5598800 5671584
14:23:05 5543 8192000000 10222705600 10647556096 1332279056 13.988 100 30 60 0 1345441792 6576512 6586224
14:24:14 10681 8192000000 10222701824 10647547904 1333702080 14.001 100 26 52 2 1345518352 6418512 5397760
14:25:28 10281 8192000000 10233306976 10661265408 1342035392 14.076 100 15 30 0 1355598112 10085152 3477568
14:26:39 33503 8192000000 10233057616 10657210368 1328447424 13.953 99 27 54 2 1341001552 6522016 6032112
14:28:12 4218 8192000000 10209019904 10624139264 1317865440 13.857 100 39 78 1 1333311984 5971536 9475008
14:29:20 3986 8192000000 10151603520 10556592128 1259970912 13.330 99 19 39 1 1269460144 5367888 4121344
14:30:26 46163 8192000000 10149947024 10550272000 1185095904 12.638 93 24 48 0 1196019136 4994736 5928496
14:32:12 8567 8192000000 10151201744 10541670400 1220382016 12.965 97 40 80 0 1235841376 6030976 9428384
14:33:23 9698 8192000000 10159328624 10569478144 1271887680 13.439 100 27 54 0 1283920080 5548416 6483984
14:34:34 7340 8192000000 10172426656 10579730432 1280151120 13.514 100 25 50 1 1291864416 5965232 5748064
14:35:46 38939 8192000000 10185063504 10596700160 1291563136 13.618 100 30 60 1 1303843056 5742128 6537792
14:37:28 7659 8192000000 10214232544 10624753664 1319708656 13.874 100 33 66 3 1333312416 5165040 8438720
14:38:40 13051 8192000000 10252635440 10657902592 1338316720 14.042 100 65 133 0 1363419840 10210048 14893072
14:40:04 17333 8192000000 10258961328 10674372608 1355148256 14.194 100 46 93 0 1375558288 9666832 10743200
14:41:24 74047 8192000000 10281591152 10703122432 1375852752 14.379 100 38 77 5 1396166752 10708304 9605696
14:43:58 20462 8192000000 10290886432 10718343168 1381655248 14.431 100 35 70 2 1406675952 16408288 8612416
14:45:33 12509 8192000000 10314667584 10734387200 1386804992 14.477 100 66 132 0 1428059824 24534928 16719904
14:46:49 4083 8192000000 10307970496 10724380672 1384190368 14.454 100 60 118 1 1421113568 22522000 14401200
14:48:05 6355 8192000000 10299902544 10720112640 1379404528 14.411 100 47 94 0 1416195408 24920240 11870640
14:49:20 17690 8192000000 10320817488 10740207616 1379996544 14.417 100 58 117 2 1423898832 29379040 14523248
14:50:41 4354 8192000000 10298508768 10713821184 1356358880 14.205 99 57 109 2 1400152016 30203920 13589216
14:51:48 5165 8192000000 10303553936 10710872064 1350897360 14.156 99 70 137 2 1399232656 32131568 16203728
14:52:59 2250 8192000000 10340867792 10741932032 1379934448 14.416 100 90 180 3 1439621952 37340992 22346512
14:54:06 2990 8192000000 10334209808 10739044352 1381929840 14.434 100 87 173 1 1440741056 36730928 22080288
14:55:17 4367 8192000000 10344972112 10749222912 1372734512 14.352 99 78 156 0 1437558064 44873344 19950208
14:56:26 1255 8192000000 10343489584 10747252736 1377155904 14.391 100 88 176 1 1447321424 46600208 23565312
14:57:34 3300 8192000000 10331996304 10736422912 1361127984 14.247 99 76 152 3 1423848032 42807296 19912752
14:58:41 10227 8192000000 10312886752 10734972928 1367876400 14.308 100 42 83 0 1423680768 44400352 11404016
15:00:07 22870 8192000000 10298344368 10734518272 1349764032 14.145 99 31 62 3 1411327792 52056352 9507408
15:01:32 49658 8192000000 10288625280 10728865792 1337982624 14.039 99 21 42 1 1381310928 36603584 6724720
15:03:51 63805 8192000000 10258690544 10714804224 1321700960 13.892 98 11 22 0 1346108192 21191200 3216032
15:06:07 33592 8192000000 10264858480 10694520832 1329078688 13.959 98 54 108 1 1349717344 8222432 12416224
15:07:44 96457 8192000000 10261069232 10707677184 1321982560 13.895 97 14 28 2 1335554720 9995024 3577136
15:10:26 74144 8192000000 10260118960 10703597568 1357542752 14.215 100 18 36 1 1368705088 7123216 4039120
15:13:12 165502 8192000000 10266154816 10717036544 1302158256 13.715 95 22 44 1 1325191184 17672368 5360560
15:17:03 52826 8192000000 10289331520 10731413504 1373629968 14.360 100 57 113 3 1403281104 16542288 13108848
15:19:00 76399 8192000000 10284444592 10739961856 1378217680 14.401 100 12 24 1 1405560928 23977824 3365424
15:21:21 78146 8192000000 10271245616 10733051904 1379951904 14.416 100 15 31 0 1394616240 11382336 3282000
15:24:07 83771 8192000000 10288376400 10753486848 1380464624 14.421 99 16 32 0 1399060496 14573424 4022448
15:27:37 61532 8192000000 10280599568 10737659904 1365253328 14.284 98 15 30 0 1379174640 10286736 3634576
15:29:39 90992 8192000000 10287904144 10747125760 1335772656 14.019 96 14 29 0 1356594128 17741616 3079856
15:32:21 69468 8192000000 10304767312 10756943872 1381949440 14.434 99 18 36 0 1403471984 17535936 3986608
15:34:33 28055 8192000000 10318390624 10750918656 1388209888 14.490 100 50 99 5 1410993728 10793120 11990720
15:36:02 51418 8192000000 10304464848 10754367488 1368490224 14.314 98 36 72 1 1390946016 14506352 7949440
15:37:55 94863 8192000000 10271710016 10724229120 1273437488 13.453 92 14 28 0 1292050928 15987360 2626080
15:40:32 74124 8192000000 10272424688 10719318016 1346323536 14.114 98 40 80 0 1364142624 8365840 9453248
15:42:53 51878 8192000000 10263172640 10726940672 1349464224 14.143 98 10 20 0 1364501152 13356896 1680032
15:47:32 37995 8192000000 10260379264 10698350592 1320787760 13.884 97 13 25 1 1332348992 8674704 2886528
15:49:17 66033 8192000000 10265048272 10712141824 1320165296 13.878 97 24 47 0 1336875072 11081792 5627984
15:51:23 81951 8192000000 10247889504 10682957824 1278005920 13.495 94 12 24 0 1292618144 12058352 2553872
15:53:48 58156 8192000000 10252963664 10695639040 1295783760 13.657 96 33 66 2 1320831088 17853696 7193632
15:56:24 153641 8192000000 10254385232 10698731520 1271366400 13.434 94 8 16 2 1287380448 14416048 1598000
16:00:12 91879 8192000000 10249094048 10688790528 1333579552 13.999 99 32 64 0 1348717664 7919632 7218480
16:02:50 64207 8192000000 10252722192 10710716416 1356784736 14.208 100 16 32 0 1374725712 14188864 3752112
16:05:02 56220 8192000000 10254539328 10708418560 1333815776 14.002 98 4 8 0 1348274992 13651488 807728
16:07:49 89953 8192000000 10288554432 10730217472 1364470112 14.277 99 13 26 0 1375530752 8285568 2775072
16:10:28 67464 8192000000 10283029184 10733682688 1359288592 14.231 98 8 15 0 1369474528 8618064 1567872
16:12:39 47794 8192000000 10295886240 10752438272 1386640416 14.476 99 16 32 0 1403667968 13275648 3751904
16:14:31 87280 8192000000 10298006944 10744389632 1348182048 14.131 96 14 28 0 1362924064 11956992 2785024
16:16:59 47031 8192000000 10302807520 10748821504 1377563888 14.395 99 34 67 1 1398284784 13117280 7603616
16:18:50 119662 8192000000 10311841904 10765033472 1383647360 14.449 99 20 41 0 1399964752 12024160 4293232
16:21:50 50763 8192000000 10299351328 10763005952 1360746304 14.244 97 7 14 0 1379774128 17531696 1496128
16:23:48 89755 8192000000 10303518112 10756571136 1395885808 14.558 100 31 62 0 1414225952 10921328 7418816
16:26:21 58090 8192000000 10302876768 10745032704 1352387536 14.169 96 32 64 0 1371453680 11855744 7210400
16:28:21 72215 8192000000 10286778272 10756694016 1387021088 14.479 100 11 22 0 1406318368 16863344 2433936
16:30:40 98152 8192000000 10279595472 10739625984 1358947040 14.228 97 20 39 1 1370255664 6917264 4391360
16:33:22 50261 8192000000 10285547056 10740531200 1365911712 14.290 98 7 14 1 1374449280 7060672 1476896
16:35:13 51865 8192000000 10290223824 10742927360 1364571056 14.278 98 21 42 1 1380685504 11061392 5053056
16:37:10 50855 8192000000 9221368928 9483018240 367040192 4.288 27 3 6 0 374131472 6504880 586400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-11-2021 16:39:24 to 07-11-2021 16:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 129 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 07-11-2021 16:39:40 to 07-11-2021 16:39:42.

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          235930
MON$OLDEST_ACTIVE               238357
MON$OLDEST_SNAPSHOT             238357
MON$NEXT_TRANSACTION            238357
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                       2016640
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:177: 
:178: mscs-test-161.budzdorov.ru    Sun Nov  7 16:38:05 2021
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Sun Nov  7 16:38:07 2021
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
--- end of diff output ---

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

07.11.21 16:39:43. 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-07 16:39:45 No severe PSQL-related problems occured
07.11.21 16:39:46. End of report.