Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.08.2021 01: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 16105136 kB
MemAvailable 30880076 kB
Buffers 0 kB
Cached 15543400 kB
SwapCached 324 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-08-30 01:37:49
DTS_END                         2021-08-30 01:37:49.1870
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/60274
TRN_ID                          236163
ATT_ID                          616
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: 9107.68 25930 1092922
customer order: creation 162.84 363 19541
customer order: refuse 13.86 369 1663
order to supplier: creation 43.93 1368 5272
order to supplier: removal 3.36 120862 403
invoice (draft): creation 43.95 3866 5274
invoice (draft): removal 52.20 951 6264
invoice accept: apply 40.82 18364 4898
invoice accept: cancel 48.16 833 5779
customer reserve: creation 3720.49 182 446459
customer reserve: removal 4681.18 67 561742
realization accept: apply 79.38 166 9525
realization accept: cancel 94.23 107 11308
payment from customer: creation 55.69 149 6683
payment from customer: removal 5.52 82 662
payment to supplier: creation 47.35 130 5682
payment to supplier: removal 3.24 70 389
service: total inventory turnovers 4.81 1519 577
service: total monetary turnovers 5.89 38 707
service: aggregate perf. data 0.75 24054 90
service: refresh index statistics 0.03 345067 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-08-2021 01:38:46 to 30-08-2021 01:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1191
TEST_TIME 2 1861
TEST_TIME 3 4682
TEST_TIME 4 4304
TEST_TIME 5 4651
TEST_TIME 6 4922
TEST_TIME 7 4662
TEST_TIME 8 4753
TEST_TIME 9 4956
TEST_TIME 10 5283
TEST_TIME 11 6426
TEST_TIME 12 6500
TEST_TIME 13 6501
TEST_TIME 14 6483
TEST_TIME 15 6504
TEST_TIME 16 6615
TEST_TIME 17 6897
TEST_TIME 18 7052
TEST_TIME 19 7086
TEST_TIME 20 7220
TEST_TIME 21 7668
TEST_TIME 22 7912
TEST_TIME 23 8290
TEST_TIME 24 8423
TEST_TIME 25 9036
TEST_TIME 26 8909
TEST_TIME 27 9431
TEST_TIME 28 9997
TEST_TIME 29 11157
TEST_TIME 30 10836
TEST_TIME 31 10714
TEST_TIME 32 10701
TEST_TIME 33 10442
TEST_TIME 34 10321
TEST_TIME 35 10605
TEST_TIME 36 10471
TEST_TIME 37 10404
TEST_TIME 38 10273
TEST_TIME 39 10126
TEST_TIME 40 9905
TEST_TIME 41 9695
TEST_TIME 42 9802
TEST_TIME 43 9737
TEST_TIME 44 9607
TEST_TIME 45 9530
TEST_TIME 46 9454
TEST_TIME 47 9399
TEST_TIME 48 9284
TEST_TIME 49 9325
TEST_TIME 50 9332
TEST_TIME 51 9318
TEST_TIME 52 9201
TEST_TIME 53 9208
TEST_TIME 54 9189
TEST_TIME 55 9104
TEST_TIME 56 9030
TEST_TIME 57 9000
TEST_TIME 58 9031
TEST_TIME 59 8931
TEST_TIME 60 8891
TEST_TIME 61 8859
TEST_TIME 62 8813
TEST_TIME 63 8905
TEST_TIME 64 8873
TEST_TIME 65 8999
TEST_TIME 66 9005
TEST_TIME 67 9100
TEST_TIME 68 9129
TEST_TIME 69 9052
TEST_TIME 70 9087
TEST_TIME 71 9275
TEST_TIME 72 9246
TEST_TIME 73 9265
TEST_TIME 74 9284
TEST_TIME 75 9369
TEST_TIME 76 9338
TEST_TIME 77 9273
TEST_TIME 78 9278
TEST_TIME 79 9234
TEST_TIME 80 9183
TEST_TIME 81 9188
TEST_TIME 82 9169
TEST_TIME 83 9201
TEST_TIME 84 9185
TEST_TIME 85 9135
TEST_TIME 86 9055
TEST_TIME 87 9016
TEST_TIME 88 9016
TEST_TIME 89 9026
TEST_TIME 90 9019
TEST_TIME 91 9078
TEST_TIME 92 9063
TEST_TIME 93 9104
TEST_TIME 94 9189
TEST_TIME 95 9179
TEST_TIME 96 9202
TEST_TIME 97 9203
TEST_TIME 98 9226
TEST_TIME 99 9269
TEST_TIME 100 9270
TEST_TIME 101 9276
TEST_TIME 102 9307
TEST_TIME 103 9268
TEST_TIME 104 9225
TEST_TIME 105 9187
TEST_TIME 106 9138
TEST_TIME 107 9151
TEST_TIME 108 9117
TEST_TIME 109 9095
TEST_TIME 110 9091
TEST_TIME 111 9072
TEST_TIME 112 9038
TEST_TIME 113 9002
TEST_TIME 114 9023
TEST_TIME 115 9075
TEST_TIME 116 9089
TEST_TIME 117 9059
TEST_TIME 118 9076
TEST_TIME 119 9095
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-08-2021 01:38:49 to 30-08-2021 01:38:53.

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 1506867 1506867 0 0.00 0 30409 6 0 0
doc_list_biud 1506867 1506867 0 0.00 0 49772 32 0 0
sp_add_invoice_to_stock 5073 4898 175 3.44 36 238112 18364 0 175
sp_cancel_adding_invoice 5779 5779 0 0.00 5 78179 834 0 0
sp_cancel_client_order 1663 1663 0 0.00 4 9032 369 0 0
sp_cancel_customer_reserve 561742 561742 0 0.00 1 33838 68 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 5072 83 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 2 1502 70 0 0
sp_cancel_supplier_invoice 6280 6264 16 0.25 2 31549 951 0 16
sp_cancel_supplier_order 403 403 0 0.00 7 1212779 120863 0 0
sp_cancel_write_off 11308 11308 0 0.00 1 10017 107 0 0
sp_client_order 19541 19541 0 0.00 4 42032 363 0 0
sp_customer_reserve 446459 446459 0 0.00 0 56069 183 0 0
sp_fill_shopping_cart 30059 30059 0 0.00 1 11902 47 0 0
sp_fill_shopping_cart_clo_res 446456 446456 0 0.00 0 26900 15 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 26531 344 0 0
sp_kill_cost_storno 18138 18138 0 0.00 0 10453 60 0 0
sp_kill_qstorno_ret_qs2qd 1141459 1141459 0 0.00 0 33810 36 0 0
sp_kill_qty_storno 601579 601579 0 0.00 0 49771 78 0 0
sp_lock_selected_doc 19935 19935 0 0.00 0 6921 6 0 0
sp_make_cost_storno 26785 26785 0 0.00 0 13357 49 0 0
sp_multiply_rows_for_pdistr 26785 26785 0 0.00 0 30400 36 0 0
sp_multiply_rows_for_qdistr 19541 19541 0 0.00 0 30167 162 0 0
sp_pay_from_customer 6683 6683 0 0.00 4 30418 149 0 0
sp_pay_to_supplier 5682 5682 0 0.00 5 14592 131 0 0
sp_payment_common 12365 12365 0 0.00 1 24982 33 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 9004 342 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 49769 375 0 0
sp_qd_handle_on_reserve_upd_sts 9525 9525 0 0.00 0 10668 57 0 0
sp_reserve_write_off 9525 9525 0 0.00 6 12167 166 0 0
sp_supplier_invoice 5274 5274 0 0.00 6 95621 3866 0 0
sp_supplier_order 5272 5272 0 0.00 5 75705 1368 0 0
srv_aggregate_perf_data 90 90 0 0.00 3264 232358 24054 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1212 143081 47737 0 0
srv_find_qd_qs_mism 1047527 1047527 0 0.00 0 17683 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 29 208114 1520 0 0
srv_make_money_saldo 707 707 0 0.00 1 2785 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 150489 713316 345067 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4898 4898 0 0.00 0 13296 186 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 21072 153 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 1800 12 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1113 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 562 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 3187 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 3676 27 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 23950 1980 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 5090 20 0 0
t$perf-norm:sp_client_order 19541 19541 0 0.00 0 11796 17 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 3070 10 0 0
t$perf-norm:sp_pay_from_customer 6683 6683 0 0.00 0 20809 13 0 0
t$perf-norm:sp_pay_to_supplier 5682 5682 0 0.00 0 11192 19 0 0
t$perf-norm:sp_reserve_write_off 9525 9525 0 0.00 0 8609 14 0 0
t$perf-norm:sp_supplier_invoice 5274 5274 0 0.00 0 4688 22 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 4590 21 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 338 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 104 3 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1279 28 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 3918 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10140 9790 350 3.45 0 5778 19 0 350
v_all_customers 35478 35478 0 0.00 0 15 1 0 0
v_all_suppliers 29116 29116 0 0.00 0 18 1 0 0
v_all_wares 39082 39082 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 443 6 0 0
v_cancel_client_order 3326 3326 0 0.00 0 378 2 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1116 4 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1552 4 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 475 4 0 0
v_cancel_supplier_invoice 798 766 32 4.01 0 4370 21 0 32
v_cancel_supplier_order 806 806 0 0.00 0 1731 8 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1828 15 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 309 1 0 0
v_max_id_clo_res 945 945 0 0.00 0 2866 15 0 0
v_max_id_ord_sup 5247 5247 0 0.00 0 5227 4 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 268 3 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 5125 5 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 5538 10 0 0
v_min_id_clo_res 945 945 0 0.00 0 1293 5 0 0
v_min_id_ord_sup 5247 5247 0 0.00 0 5077 10 0 0
v_min_non_paid_invoice 5682 5682 0 0.00 0 7128 18 0 0
v_min_non_paid_realizn 6683 6683 0 0.00 0 8851 17 0 0
v_our_firm 9762 9762 0 0.00 0 16 1 0 0
v_reserve_write_off 19050 19050 0 0.00 0 5788 5 0 0
x_lock_depdocs_on_canc_invoice 11660 11660 0 0.00 0 26570 134 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 8377 461 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 2 75674 1271 0 0
x_make_qstorno_1200_2000 5274 5274 0 0.00 3 91762 3695 0 0
x_make_qstorno_2100_3300 446459 446459 0 0.00 0 49989 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-08-2021 01:38:54 to 30-08-2021 01:38:58.

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
23:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:54 20353 8192000000 10364747136 10681384960 1420212336 14.775 100 92 184 0 1456886528 6395408 30278784
23:10:18 25427 8192000000 10429244800 10759811072 1480309456 15.304 100 88 176 1 1517357056 8547744 28499856
23:11:48 16923 8192000000 10486281600 10830651392 1545397440 15.870 100 81 162 0 1580499088 9799136 25302512
23:13:09 9233 8192000000 10569360496 10925666304 1625199424 16.554 100 80 158 1 1660009056 11195872 23613760
23:14:21 7744 8192000000 10655359872 11026579456 1713344208 17.297 100 85 171 0 1751050080 13229760 24476112
23:15:33 5931 8192000000 10730891472 11107520512 1786863120 17.906 100 94 186 1 1826535760 13753584 25919056
23:16:40 1656 8192000000 10772580064 11152781312 1830444032 18.263 100 91 180 2 1869510464 14268112 24798320
23:17:43 1891 8192000000 10808758112 11198275584 1869999136 18.584 100 89 179 0 1908744048 13475456 25269456
23:18:46 6547 8192000000 10781539936 11205591040 1879713056 18.663 100 41 81 3 1899323888 7792272 11818560
23:19:55 20216 8192000000 10778624416 11214548992 1877426912 18.644 100 26 52 1 1890972240 5739744 7805584
23:21:16 5507 8192000000 10808161392 11233947648 1892571712 18.767 100 31 60 3 1907514320 6769104 8173504
23:22:22 7730 8192000000 10805253376 11241893888 1896395904 18.797 100 22 44 0 1909178352 6370368 6412080
23:23:34 66909 8192000000 10824901936 11254517760 1909452608 18.902 100 29 59 4 1927918672 9517984 8948080
23:25:44 5828 8192000000 10838896544 11269836800 1918230624 18.973 100 38 76 1 1937727696 8722512 10774560
23:26:52 5839 8192000000 10826967984 11251433472 1870947488 18.592 98 27 54 0 1885024464 6040640 8036336
23:27:59 68620 8192000000 10728733488 11155767296 1717333504 17.330 92 31 63 3 1736207568 9618272 9255792
23:30:08 5579 8192000000 10701746464 11115290624 1760738768 17.690 98 24 48 2 1773412048 5860304 6812976
23:31:14 11770 8192000000 10728761216 11152707584 1805669888 18.060 99 24 48 1 1818007888 5137408 7200592
23:32:27 11163 8192000000 10753484736 11177496576 1840526960 18.345 100 40 79 2 1858636416 6167760 11941696
23:33:40 10339 8192000000 10746181408 11172741120 1834225200 18.294 100 30 60 0 1849161280 5948576 8987504
23:34:51 6389 8192000000 10776910752 11196665856 1856102400 18.472 100 34 68 2 1873453728 6439344 10911984
23:35:57 11019 8192000000 10794637216 11221188608 1871336528 18.595 100 31 62 0 1890540048 9913920 9289600
23:37:09 125798 8192000000 10837332912 11263225856 1902678128 18.848 100 34 68 1 1918140624 5076848 10385648
23:40:21 31485 8192000000 10860961328 11298557952 1941210320 19.156 100 41 82 1 1966203152 12541440 12451392
23:41:55 16232 8192000000 10883171072 11317080064 1960570816 19.311 100 52 102 1 1987668240 12680496 14416928
23:43:16 20651 8192000000 10904686304 11336605696 1969918992 19.385 100 46 92 0 1993486480 11086864 12480624
23:44:56 3597 8192000000 10932195040 11358900224 1987049456 19.520 100 56 112 0 2022834032 18926816 16857760
23:46:04 21164 8192000000 10946215056 11378974720 1997056768 19.600 100 49 96 2 2039762176 27361344 15344064
23:47:32 15273 8192000000 10969448992 11403988992 1999992592 19.623 100 48 96 1 2054512832 38617120 15903120
23:48:57 8362 8192000000 10982182608 11411906560 2011350496 19.712 100 55 108 0 2063624128 35956016 16317616
23:50:11 4530 8192000000 10989920512 11413962752 2001681888 19.636 99 74 150 0 2061308560 37250560 22376112
23:51:18 3591 8192000000 10975768272 11393794048 1988142064 19.529 99 91 183 3 2059405984 43655536 27608384
23:52:26 1760 8192000000 10977465776 11392577536 1986976128 19.520 100 77 154 1 2058987056 46939104 25071824
23:53:36 2450 8192000000 10960804512 11369091072 1962917104 19.329 99 88 175 0 2039126832 49645616 26564112
23:54:47 1210 8192000000 10984258624 11391348736 1983523008 19.493 100 86 170 0 2063300896 53521312 26256576
23:55:53 3017 8192000000 10971350080 11379433472 1966775216 19.360 100 85 169 2 2048857472 55423232 26659024
23:56:59 3507 8192000000 10971109952 11375067136 1954113648 19.259 99 87 173 1 2045275120 63538864 27622608
23:58:09 2063 8192000000 10962910896 11375804416 1948115200 19.211 100 85 170 3 2044808032 68652752 28040080
23:59:18 7703 8192000000 10977130768 11388604416 1966761584 19.360 100 72 143 1 2052631312 62380320 23489408
00:00:30 6732 8192000000 10955535152 11381379072 1947750240 19.209 98 64 126 1 2030993808 62290112 20953456
00:01:48 5839 8192000000 10963616304 11398983680 1972062576 19.402 100 46 91 0 2047784560 60554608 15167376
00:02:56 20246 8192000000 10933172976 11379597312 1960931120 19.313 100 28 56 0 2022081984 51206912 9943952
00:04:19 10760 8192000000 10926405776 11386331136 1944235264 19.181 100 25 50 2 2013454240 59413152 9805824
00:05:31 22884 8192000000 10951434640 11397640192 1953094864 19.251 100 32 62 1 2022466976 58587984 10784128
00:06:56 69957 8192000000 10916004320 11359645696 1952404800 19.246 99 23 46 0 1974744480 15673264 6666416
00:09:08 14731 8192000000 10918123376 11376173056 1972633904 19.406 100 24 47 1 1994711968 14779840 7298224
00:10:24 107767 8192000000 10893471584 11361632256 1934866304 19.106 98 19 38 3 1958467568 18146352 5454912
00:13:20 51186 8192000000 10890392240 11356536832 1953040320 19.251 99 17 34 0 1966650672 9289024 4321328
00:15:13 24736 8192000000 10903708144 11347955712 1938530736 19.135 98 32 56 1 1959430320 13371920 7527664
00:17:22 90855 8192000000 10916326416 11374960640 1984251344 19.498 100 29 59 0 2009566736 16145488 9169904
00:19:57 49429 8192000000 10885414560 11362082816 1971974432 19.401 100 19 38 0 1987462208 9989088 5498688
00:21:52 47808 8192000000 10878738288 11345465344 1927951408 19.050 98 17 34 1 1943100128 9558240 5590480
00:24:25 65763 8192000000 10902883776 11358416896 1916758656 18.961 97 14 28 2 1934562272 13820496 3983120
00:27:12 43851 8192000000 10893587456 11344322560 1913335760 18.933 97 39 78 2 1934729312 9538784 11854768
00:28:59 55504 8192000000 10893342960 11348520960 1919124592 18.980 97 29 56 1 1936164704 8797376 8242736
00:31:02 50747 8192000000 10873824800 11349860352 1929525408 19.063 98 18 36 1 1948360800 13640800 5194592
00:32:53 68784 8192000000 10888630592 11359346688 1960094512 19.307 100 10 20 0 1975321600 12609200 2617888
00:35:36 69019 8192000000 10865896800 11341103104 1940829040 19.153 99 16 32 1 1952230688 7397952 4003696
00:37:45 51820 8192000000 10882693248 11358498816 1952460048 19.246 99 5 10 0 1960207232 6745424 1001760
00:39:38 72202 8192000000 10882602800 11358543872 1929330640 19.062 98 14 28 2 1942642912 9535584 3776688
00:42:00 70549 8192000000 10886818304 11364950016 1947440720 19.206 99 26 52 0 1970818192 16002976 7374496
00:44:17 62886 8192000000 10870771616 11345448960 1900595696 18.831 97 11 22 1 1920077312 16680912 2800704
00:46:20 30167 8192000000 10906118496 11346640896 1923116016 19.012 98 57 111 1 1954046096 15420528 15509552
00:47:58 50528 8192000000 10853365616 11321696256 1859814176 18.502 96 12 24 0 1886172224 22966112 3391936
00:49:52 57832 8192000000 10879780032 11323326464 1897866496 18.809 98 36 70 1 1921346256 13904192 9575568
00:52:06 76777 8192000000 10848981056 11331309568 1878716080 18.655 97 16 32 1 1897211136 14023520 4471536
00:55:15 81353 8192000000 10883682528 11338768384 1911567296 18.919 98 31 59 2 1930536944 11065808 7903840
00:57:39 65611 8192000000 10879638800 11349692416 1939958560 19.146 99 16 32 1 1956087312 11092608 5036144
00:59:48 41331 8192000000 10874217264 11353550848 1948116576 19.211 100 11 22 0 1964807984 13763296 2928112
01:01:29 64121 8192000000 10875868816 11357507584 1944300304 19.181 99 7 14 0 1957834896 11952160 1582432
01:03:35 51652 8192000000 10876028160 11356782592 1949691456 19.224 99 15 30 0 1968122416 14579168 3851792
01:06:07 73929 8192000000 10896370672 11373916160 1932788752 19.089 98 10 19 0 1948886272 13817664 2279856
01:08:21 68065 8192000000 10894563440 11359977472 1917707600 18.968 97 18 36 0 1935363312 12377200 5278512
01:10:31 74978 8192000000 10897415328 11372732416 1954095504 19.259 99 5 10 1 1974629632 19137824 1396304
01:12:48 85767 8192000000 10911207072 11377545216 1941078864 19.155 98 17 34 1 1962600960 16944848 4577248
01:15:23 143080 8192000000 10897769536 11359973376 1869472400 18.580 94 6 11 1 1887914576 17214016 1228160
01:18:52 65323 8192000000 10904469936 11351089152 1927476560 19.047 98 28 56 0 1946542064 11121744 7943760
01:20:59 137721 8192000000 10892771408 11361779712 1946100336 19.195 99 20 40 1 1959609232 8005472 5503424
01:24:21 62174 8192000000 10890999712 11378049024 1964466416 19.342 99 8 16 0 1975792704 8632336 2693952
01:26:25 49006 8192000000 10915385728 11389186048 1987526208 19.524 100 12 24 2 2001862784 10455648 3880928
01:28:18 92200 8192000000 10867792512 11342860288 1887270512 18.724 95 18 36 2 1902680496 9923840 5486144
01:30:53 74129 8192000000 10891891872 11369291776 1927376768 19.046 98 18 36 0 1946993824 14528080 5088976
01:33:08 64415 8192000000 10878551424 11356172288 1941926080 19.162 99 10 20 0 1953030144 8286000 2818064
01:35:13 31685 8192000000 10903909136 11369295872 1938774304 19.137 98 30 61 0 1962152576 13764976 9613296
01:36:54 59092 8192000000 9366189264 9654136832 514693680 5.911 26 3 6 0 521587808 6179280 714848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-08-2021 01:39:00 to 30-08-2021 01:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-08-2021 01:39:15 to 30-08-2021 01:39:17.

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          236162
MON$OLDEST_ACTIVE               237497
MON$OLDEST_SNAPSHOT             237497
MON$NEXT_TRANSACTION            237497
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                       2038112
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:733: 
:734: mscs-test-161.budzdorov.ru    Mon Aug 30 01:37:58 2021
:735:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:736: 
:737: 
:738: mscs-test-161.budzdorov.ru    Mon Aug 30 01:37:59 2021
:739:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:740: 
:741: 
:742: mscs-test-161.budzdorov.ru    Mon Aug 30 01:37:59 2021
:743:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:744: 
--- end of diff output ---

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

30.08.21 01:39:18. 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-08-30 01:39:20 No severe PSQL-related problems occured
30.08.21 01:39:22. End of report.