Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 16:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 17349348 kB
MemAvailable 31543712 kB
Buffers 0 kB
Cached 14305992 kB
SwapCached 5448 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.7
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 2020-10-18 16:27:01
DTS_END                         2020-10-18 16:27:01.4690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/54170
TRN_ID                          259382
ATT_ID                          740
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: 9918.50 17533 1190220
customer order: creation 192.89 351 23147
customer order: refuse 16.40 259 1968
order to supplier: creation 50.42 1135 6050
order to supplier: removal 3.78 75367 454
invoice (draft): creation 50.09 2573 6011
invoice (draft): removal 56.20 613 6744
invoice accept: apply 45.32 16367 5438
invoice accept: cancel 50.90 665 6108
customer reserve: creation 4141.79 166 497015
customer reserve: removal 4978.76 46 597451
realization accept: apply 94.48 153 11337
realization accept: cancel 93.23 94 11187
payment from customer: creation 65.96 95 7915
payment from customer: removal 6.59 68 791
payment to supplier: creation 54.79 117 6575
payment to supplier: removal 3.54 125 425
service: total inventory turnovers 5.14 1008 617
service: total monetary turnovers 7.28 25 873
service: aggregate perf. data 0.92 19593 110
service: refresh index statistics 0.03 231849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 16:27:51 to 18-10-2020 16:27:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10222
TEST_TIME 2 8382
TEST_TIME 3 7962
TEST_TIME 4 9438
TEST_TIME 5 8952
TEST_TIME 6 9346
TEST_TIME 7 9238
TEST_TIME 8 9296
TEST_TIME 9 9606
TEST_TIME 10 9287
TEST_TIME 11 9106
TEST_TIME 12 8998
TEST_TIME 13 9213
TEST_TIME 14 9005
TEST_TIME 15 9131
TEST_TIME 16 9375
TEST_TIME 17 9873
TEST_TIME 18 10268
TEST_TIME 19 10356
TEST_TIME 20 10169
TEST_TIME 21 10702
TEST_TIME 22 11149
TEST_TIME 23 11909
TEST_TIME 24 12642
TEST_TIME 25 12603
TEST_TIME 26 12350
TEST_TIME 27 12207
TEST_TIME 28 12121
TEST_TIME 29 12037
TEST_TIME 30 11770
TEST_TIME 31 11540
TEST_TIME 32 11436
TEST_TIME 33 11240
TEST_TIME 34 11160
TEST_TIME 35 10997
TEST_TIME 36 11088
TEST_TIME 37 11127
TEST_TIME 38 10992
TEST_TIME 39 10804
TEST_TIME 40 10734
TEST_TIME 41 10545
TEST_TIME 42 10489
TEST_TIME 43 10616
TEST_TIME 44 10621
TEST_TIME 45 10593
TEST_TIME 46 10703
TEST_TIME 47 10685
TEST_TIME 48 10663
TEST_TIME 49 10670
TEST_TIME 50 10728
TEST_TIME 51 10701
TEST_TIME 52 10711
TEST_TIME 53 10779
TEST_TIME 54 10707
TEST_TIME 55 10682
TEST_TIME 56 10537
TEST_TIME 57 10481
TEST_TIME 58 10552
TEST_TIME 59 10461
TEST_TIME 60 10355
TEST_TIME 61 10328
TEST_TIME 62 10248
TEST_TIME 63 10277
TEST_TIME 64 10272
TEST_TIME 65 10242
TEST_TIME 66 10322
TEST_TIME 67 10385
TEST_TIME 68 10326
TEST_TIME 69 10235
TEST_TIME 70 10348
TEST_TIME 71 10384
TEST_TIME 72 10452
TEST_TIME 73 10450
TEST_TIME 74 10394
TEST_TIME 75 10503
TEST_TIME 76 10481
TEST_TIME 77 10392
TEST_TIME 78 10314
TEST_TIME 79 10311
TEST_TIME 80 10348
TEST_TIME 81 10374
TEST_TIME 82 10312
TEST_TIME 83 10260
TEST_TIME 84 10215
TEST_TIME 85 10160
TEST_TIME 86 10162
TEST_TIME 87 10129
TEST_TIME 88 10127
TEST_TIME 89 10117
TEST_TIME 90 10155
TEST_TIME 91 10117
TEST_TIME 92 10110
TEST_TIME 93 10197
TEST_TIME 94 10179
TEST_TIME 95 10167
TEST_TIME 96 10226
TEST_TIME 97 10220
TEST_TIME 98 10190
TEST_TIME 99 10226
TEST_TIME 100 10276
TEST_TIME 101 10235
TEST_TIME 102 10153
TEST_TIME 103 10129
TEST_TIME 104 10074
TEST_TIME 105 10107
TEST_TIME 106 10054
TEST_TIME 107 10017
TEST_TIME 108 10014
TEST_TIME 109 9966
TEST_TIME 110 9939
TEST_TIME 111 9904
TEST_TIME 112 9937
TEST_TIME 113 9902
TEST_TIME 114 9900
TEST_TIME 115 9920
TEST_TIME 116 9884
TEST_TIME 117 9921
TEST_TIME 118 10005
TEST_TIME 119 10000
TEST_TIME 120 9920

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2020 16:27:54 to 18-10-2020 16:27:58.

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 1652896 1652896 0 0.00 0 29379 6 0 0
doc_list_biud 1652895 1652895 0 0.00 0 42190 21 0 0
sp_add_invoice_to_stock 5593 5438 155 2.77 36 199721 16368 0 155
sp_cancel_adding_invoice 6108 6108 0 0.00 4 62753 665 0 0
sp_cancel_client_order 1968 1968 0 0.00 4 9911 260 0 0
sp_cancel_customer_reserve 597451 597451 0 0.00 1 42193 46 0 0
sp_cancel_pay_from_customer 791 791 0 0.00 3 4567 69 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 9579 125 0 0
sp_cancel_supplier_invoice 6762 6744 18 0.26 2 14862 614 0 18
sp_cancel_supplier_order 454 454 0 0.00 5 762784 75368 0 0
sp_cancel_write_off 11187 11187 0 0.00 1 26674 94 0 0
sp_client_order 23147 23147 0 0.00 3 37116 351 0 0
sp_customer_reserve 497015 497015 0 0.00 0 44204 166 0 0
sp_fill_shopping_cart 35203 35203 0 0.00 1 10283 31 0 0
sp_fill_shopping_cart_clo_res 497014 497014 0 0.00 0 13487 12 0 0
sp_get_clo_for_invoice 5436 5436 0 0.00 0 17780 250 0 0
sp_kill_cost_storno 18511 18511 0 0.00 0 8102 39 0 0
sp_kill_qstorno_ret_qs2qd 1213287 1213287 0 0.00 0 42186 25 0 0
sp_kill_qty_storno 640685 640685 0 0.00 0 42189 54 0 0
sp_lock_selected_doc 23212 23212 0 0.00 0 7811 5 0 0
sp_make_cost_storno 31263 31263 0 0.00 0 15423 45 0 0
sp_multiply_rows_for_pdistr 31263 31263 0 0.00 0 6277 23 0 0
sp_multiply_rows_for_qdistr 23147 23147 0 0.00 0 10422 165 0 0
sp_pay_from_customer 7915 7915 0 0.00 4 18471 96 0 0
sp_pay_to_supplier 6575 6575 0 0.00 4 16193 118 0 0
sp_payment_common 14490 14490 0 0.00 1 9981 25 0 0
sp_qd_handle_on_cancel_clo 1968 1968 0 0.00 0 9904 234 0 0
sp_qd_handle_on_invoice_upd_sts 11544 11544 0 0.00 0 19345 235 0 0
sp_qd_handle_on_reserve_upd_sts 11337 11337 0 0.00 0 16176 53 0 0
sp_reserve_write_off 11337 11337 0 0.00 6 16184 154 0 0
sp_supplier_invoice 6011 6011 0 0.00 6 87913 2574 0 0
sp_supplier_order 6050 6050 0 0.00 5 33059 1135 0 0
srv_aggregate_perf_data 110 110 0 0.00 2361 510952 19593 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2277 133190 45444 0 0
srv_find_qd_qs_mism 1138940 1138940 0 0.00 0 29378 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 35 68140 1009 0 0
srv_make_money_saldo 873 873 0 0.00 1 3716 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 117851 335962 231849 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5438 5438 0 0.00 0 19265 136 0 0
t$perf-norm:sp_cancel_adding_invoice 475 475 0 0.00 0 6483 94 0 0
t$perf-norm:sp_cancel_client_order 1968 1968 0 0.00 0 6750 14 0 0
t$perf-norm:sp_cancel_customer_reserve 765 765 0 0.00 0 3882 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 791 791 0 0.00 0 3696 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 339 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 3162 25 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 18393 1265 0 0
t$perf-norm:sp_cancel_write_off 1124 1124 0 0.00 0 1914 9 0 0
t$perf-norm:sp_client_order 23147 23147 0 0.00 0 14232 13 0 0
t$perf-norm:sp_customer_reserve 1122 1122 0 0.00 0 3709 12 0 0
t$perf-norm:sp_pay_from_customer 7915 7915 0 0.00 0 8182 9 0 0
t$perf-norm:sp_pay_to_supplier 6575 6575 0 0.00 0 7535 10 0 0
t$perf-norm:sp_reserve_write_off 11337 11337 0 0.00 0 16929 13 0 0
t$perf-norm:sp_supplier_invoice 6011 6011 0 0.00 0 9021 16 0 0
t$perf-norm:sp_supplier_order 6050 6050 0 0.00 0 7441 12 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 209 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 231 4 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 792 11 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 906 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 11180 10870 310 2.77 0 10341 18 0 310
v_all_customers 41962 41962 0 0.00 0 20 1 0 0
v_all_suppliers 33706 33706 0 0.00 0 23 1 0 0
v_all_wares 46294 46294 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 950 950 0 0.00 0 3513 10 0 0
v_cancel_client_order 3936 3936 0 0.00 0 2114 4 0 0
v_cancel_customer_prepayment 1582 1582 0 0.00 0 2706 8 0 0
v_cancel_customer_reserve 1530 1530 0 0.00 0 1310 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 9544 24 0 0
v_cancel_supplier_invoice 912 876 36 3.94 0 10347 28 0 36
v_cancel_supplier_order 908 908 0 0.00 0 10325 15 0 0
v_cancel_write_off 2248 2248 0 0.00 0 5374 14 0 0
v_max_id_clo_ord 6047 6047 0 0.00 0 4509 3 0 0
v_max_id_clo_res 1122 1122 0 0.00 0 1686 13 0 0
v_max_id_ord_sup 6007 6007 0 0.00 0 1294 1 0 0
v_max_non_paid_invoice 1776 1776 0 0.00 0 946 3 0 0
v_max_non_paid_realizn 4334 4334 0 0.00 0 1269 3 0 0
v_min_id_clo_ord 6047 6047 0 0.00 0 6497 6 0 0
v_min_id_clo_res 1122 1122 0 0.00 0 3283 13 0 0
v_min_id_ord_sup 6007 6007 0 0.00 0 8142 6 0 0
v_min_non_paid_invoice 6575 6575 0 0.00 0 8227 18 0 0
v_min_non_paid_realizn 7915 7915 0 0.00 0 7659 14 0 0
v_our_firm 11494 11494 0 0.00 0 10 1 0 0
v_reserve_write_off 22674 22674 0 0.00 0 3516 6 0 0
x_lock_depdocs_on_canc_invoice 12414 12414 0 0.00 0 9941 89 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 12566 266 0 0
x_make_qstorno_1000_1200 6050 6050 0 0.00 1 33049 1065 0 0
x_make_qstorno_1200_2000 6011 6011 0 0.00 3 87897 2473 0 0
x_make_qstorno_2100_3300 497015 497015 0 0.00 0 39603 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 16:27:59 to 18-10-2020 16:28:03.

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
13:57:05 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:06 24823 8192000000 10376576288 10701504512 1431703552 14.876 100 85 170 0 1464670000 6015408 26951040
13:59:35 29660 8192000000 10461732208 10794807296 1500700288 15.482 100 90 181 0 1538296400 8765920 28830192
14:01:07 17913 8192000000 10493537136 10838761472 1547039488 15.884 100 89 178 1 1585484080 10073888 28370704
14:02:32 9134 8192000000 10563258880 10925109248 1623764128 16.542 100 73 144 1 1656087328 10634800 21688400
14:03:43 8126 8192000000 10659264080 11033772032 1718229504 17.337 100 79 157 2 1752995088 12084832 22680752
14:04:54 7755 8192000000 10713165760 11095719936 1774127248 17.801 100 72 144 0 1808170256 14164432 19878576
14:06:03 2015 8192000000 10773002112 11155550208 1833278928 18.286 100 92 182 1 1873226720 13625680 26322112
14:07:06 2034 8192000000 10787432576 11190177792 1856068864 18.471 100 76 152 0 1889954656 13407328 20478464
14:08:09 20833 8192000000 10765099136 11199639552 1862314512 18.522 100 37 74 0 1881430032 8203984 10911536
14:09:50 7057 8192000000 10787930096 11219996672 1877756912 18.647 100 21 42 3 1890302768 6535168 6010688
14:10:59 6280 8192000000 10802801648 11236204544 1889556928 18.742 100 18 36 1 1900738880 5973824 5208128
14:12:07 11716 8192000000 10809175536 11245936640 1900252880 18.828 100 19 38 1 1912027536 6201648 5573008
14:13:19 39204 8192000000 10861551040 11266273280 1916728016 18.961 100 72 142 5 1946024240 7577952 21718272
14:14:59 5502 8192000000 10833811888 11264761856 1906625328 18.880 100 22 43 1 1922854176 9987568 6241280
14:16:08 30852 8192000000 10801547456 11238989824 1873388144 18.612 98 31 62 0 1888225088 5851360 8985584
14:17:40 7198 8192000000 10731687776 11151798272 1759782176 17.683 95 22 44 1 1771718880 6073392 5863312
14:18:48 9043 8192000000 10693987616 11104727040 1688103264 17.085 94 26 52 0 1701815696 5756944 7955488
14:19:59 49114 8192000000 10728798608 11158073344 1818115808 18.162 100 25 50 3 1830323888 5007888 7200192
14:21:48 8878 8192000000 10731675984 11152957440 1804456576 18.050 99 32 64 2 1820230384 6643264 9130544
14:22:59 7039 8192000000 10768443136 11193659392 1856081840 18.472 100 29 58 2 1870373248 5890912 8400496
14:24:09 20973 8192000000 10781920448 11210715136 1869424960 18.580 100 21 43 0 1881825840 5792320 6608560
14:25:30 21159 8192000000 10786570608 11215499264 1872375808 18.603 100 22 43 2 1884112912 5619648 6117456
14:26:54 22632 8192000000 10808056352 11231207424 1884196272 18.699 100 19 37 1 1895337552 5008736 6132544
14:28:21 2671 8192000000 10853953664 11273187328 1922365040 19.006 100 57 114 0 1947604176 9413296 15825840
14:29:28 86030 8192000000 10868838848 11306901504 1908372816 18.894 98 43 86 0 1936119392 15130880 12615696
14:32:00 19233 8192000000 10885122480 11315847168 1918004224 18.971 98 42 82 3 1941609264 10873152 12731888
14:33:22 6343 8192000000 10916790160 11345162240 1974460896 19.421 100 59 118 0 2008102544 17099408 16542240
14:34:32 3837 8192000000 10910366256 11326799872 1951511456 19.239 98 86 172 1 1997253184 19445664 26296064
14:35:39 3924 8192000000 10889951296 11304865792 1911372416 18.918 98 69 136 2 1953329184 22302992 19653776
14:36:47 5596 8192000000 10856123792 11272122368 1871659792 18.598 98 63 126 3 1916578384 25702496 19216096
14:37:57 7458 8192000000 10827348608 11232858112 1850360688 18.425 98 65 129 1 1899135888 30028368 18746832
14:39:07 2277 8192000000 10898771504 11306332160 1924838288 19.026 100 59 113 1 1979355968 37217328 17300352
14:40:11 5286 8192000000 10900770096 11324379136 1926686752 19.040 100 50 98 2 1986566416 44208048 15671616
14:41:19 8724 8192000000 10917577152 11344662528 1938167936 19.132 99 48 96 1 1994789520 41247696 15373888
14:42:31 16733 8192000000 10904475824 11337023488 1906410752 18.878 98 34 68 1 1967447184 49382352 11654080
14:43:52 9268 8192000000 10917190816 11346591744 1924851216 19.026 99 37 72 2 1990413616 53383792 12178608
14:45:04 12494 8192000000 10921852112 11358613504 1947489600 19.206 100 33 66 1 2013751568 55189392 11072576
14:46:17 17729 8192000000 10921027760 11366895616 1953627328 19.255 100 30 60 1 2010434608 46566992 10240288
14:47:37 36510 8192000000 10899225872 11354812416 1920739504 18.993 99 24 48 0 1981290928 51306032 9245392
14:49:40 18854 8192000000 10903428096 11344576512 1912875088 18.930 99 23 47 0 1964652016 44025504 7751424
14:51:00 83627 8192000000 10865902000 11333279744 1909979648 18.906 99 10 20 0 1930092272 17384496 2728128
14:53:25 48998 8192000000 10886402128 11331534848 1940778416 19.153 100 19 38 1 1962634128 16127024 5728688
14:55:18 133190 8192000000 10879733424 11337211904 1879963776 18.665 96 30 60 0 1902458256 13880640 8613840
14:58:37 87578 8192000000 10896507984 11358318592 1953963056 19.258 99 24 48 2 1969190352 8535072 6692224
15:01:06 20841 8192000000 10922014832 11383148544 1982115744 19.481 100 14 26 1 1995390128 9827136 3447248
15:02:27 35653 8192000000 10919526688 11383681024 1982471680 19.484 100 21 42 1 2003566736 14465632 6629424
15:04:31 28835 8192000000 10904547376 11380539392 1987112272 19.521 100 9 18 1 1999664768 9309776 3242720
15:06:00 32914 8192000000 10914004688 11389759488 1994681312 19.581 100 5 10 0 2005029712 9082304 1266096
15:07:34 25714 8192000000 10926884400 11403202560 2001056784 19.631 100 25 50 3 2019504512 11041552 7406176
15:09:13 67480 8192000000 10927466352 11398979584 1981209184 19.474 99 11 21 1 1997478064 13321664 2947216
15:11:34 50757 8192000000 10921877792 11396816896 1996295008 19.594 100 20 40 3 2012429184 10383408 5750768
15:13:25 45283 8192000000 10904605952 11374174208 1949481520 19.222 98 19 38 1 1964022192 9226240 5314432
15:15:16 32557 8192000000 10893174672 11337375744 1869146336 18.577 95 20 40 2 1882102192 7180624 5775232
15:16:49 35422 8192000000 10859994288 11316789248 1863909504 18.535 96 20 40 0 1880258768 10876288 5472976
15:18:26 26513 8192000000 10842262688 11289620480 1830854112 18.266 95 20 40 1 1843870544 7368576 5647856
15:19:55 84924 8192000000 10856245616 11323183104 1833853600 18.291 95 20 40 0 1851680624 12544304 5282720
15:22:30 27952 8192000000 10857632992 11296665600 1865963488 18.552 97 47 94 2 1891066960 12147312 12956160
15:24:02 69981 8192000000 10825086832 11283042304 1874768992 18.623 98 38 76 0 1899734016 13872512 11092512
15:26:13 72302 8192000000 10827651888 11303944192 1888127616 18.731 99 5 10 0 1899291920 9721728 1442576
15:28:26 83246 8192000000 10849200096 11328339968 1923388400 19.014 100 9 18 1 1941449328 15916496 2144432
15:30:52 33696 8192000000 10848123792 11311558656 1897790512 18.809 98 23 46 0 1922121600 17951520 6379568
15:32:27 34534 8192000000 10871509744 11332198400 1922668816 19.008 99 13 26 1 1940554592 14030160 3855616
15:34:07 69734 8192000000 10873658624 11335016448 1904965568 18.866 98 18 36 1 1920825472 10746912 5112992
15:36:17 21874 8192000000 10891430544 11340177408 1930219296 19.069 99 52 102 2 1959274976 14376448 14679232
15:37:52 69124 8192000000 10893491728 11352145920 1912556816 18.927 98 33 66 2 1938279024 16662448 9059760
15:40:07 88499 8192000000 10922143344 11377172480 1958279696 19.292 100 17 34 1 1972865408 9998528 4587184
15:42:40 78220 8192000000 10893881024 11367747584 1916416320 18.958 97 10 20 1 1939137744 19058864 3662560
15:44:58 77105 8192000000 10929572608 11401728000 1977226080 19.443 100 25 50 1 2001992352 17528080 7238192
15:47:26 61438 8192000000 10896341264 11391754240 1973239744 19.411 100 10 20 0 1995286368 19469472 2577152
15:49:31 98949 8192000000 10916742784 11396562944 1978732688 19.455 100 14 28 1 1997707152 15082288 3892176
15:52:10 63915 8192000000 10901296704 11374657536 1963038432 19.330 99 20 40 0 1981928624 12813328 6076864
15:54:14 60248 8192000000 10903378112 11391295488 1964295840 19.340 99 9 18 1 1978812448 11631696 2884912
15:56:15 71626 8192000000 10903378064 11383988224 1954129104 19.259 98 19 38 1 1969685104 10040800 5515200
15:58:27 69080 8192000000 10917931856 11396689920 1925078032 19.028 96 24 48 1 1944004784 11707984 7218768
16:00:41 16871 8192000000 10921826368 11384262656 1983408224 19.492 100 39 74 1 2002130368 7562032 11160112
16:02:01 28822 8192000000 10919932256 11366879232 1937865664 19.130 98 38 76 2 1959010576 10619840 10525072
16:03:32 64535 8192000000 10885834176 11366662144 1908627232 18.896 97 16 32 1 1931017536 17819280 4571024
16:05:37 35854 8192000000 10892583504 11359948800 1895839392 18.793 96 28 56 1 1922078176 18009248 8229536
16:07:13 84656 8192000000 10874106144 11333353472 1852454880 18.442 94 17 34 1 1866799872 9960304 4384688
16:09:49 98624 8192000000 10838649584 11298729984 1854435312 18.458 96 18 36 1 1866252832 6949728 4867792
16:13:39 57922 8192000000 10853647792 11325321216 1922573936 19.007 100 7 13 0 1937638512 13515648 1548928
16:15:37 53896 8192000000 10832105296 11304407040 1884278032 18.700 98 9 18 2 1895266912 8493056 2495824
16:17:35 56265 8192000000 10821451984 11286802432 1841981056 18.357 96 21 43 0 1861542624 13568448 5993120
16:19:32 25476 8192000000 10839443248 11297517568 1886740576 18.720 99 11 22 0 1899808064 9953840 3113648
16:20:57 60011 8192000000 10863461520 11330146304 1868472640 18.572 97 25 50 1 1889616032 13965152 7178240
16:23:09 62744 8192000000 10889037216 11364208640 1942308304 19.165 100 17 32 2 1969948608 22852608 4787696
16:25:27 30614 8192000000 10861984368 11324342272 1901939888 18.842 98 19 38 0 1921918944 15140640 4838416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-10-2020 16:28:05 to 18-10-2020 16:28:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 155 v_add_invoice_to_stock 335544517
user_exc 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 16:28:20 to 18-10-2020 16:28:22.

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          257545
MON$OLDEST_ACTIVE               259501
MON$OLDEST_SNAPSHOT             259501
MON$NEXT_TRANSACTION            259501
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                       2038944
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Oct 18 16:27:06 2020
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Sun Oct 18 16:27:11 2020
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Oct 18 16:27:11 2020
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
--- end of diff output ---

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

18.10.20 16:28:23. 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
2020-10-18 16:28:26 No severe PSQL-related problems occured
18.10.20 16:28:27. End of report.