Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 19: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 17180096 kB
MemAvailable 31539672 kB
Buffers 0 kB
Cached 14466340 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 19:26:58
DTS_END                         2020-10-18 19:26:58.9320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/56170
TRN_ID                          259547
ATT_ID                          711
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: 9755.78 21641 1170693
customer order: creation 184.10 300 22092
customer order: refuse 15.80 247 1896
order to supplier: creation 50.70 1103 6084
order to supplier: removal 3.50 93171 420
invoice (draft): creation 50.05 3132 6006
invoice (draft): removal 56.10 737 6732
invoice accept: apply 44.60 15288 5352
invoice accept: cancel 50.87 728 6104
customer reserve: creation 4057.15 154 486858
customer reserve: removal 4917.36 51 590083
realization accept: apply 90.31 167 10837
realization accept: cancel 95.28 90 11433
payment from customer: creation 63.95 128 7674
payment from customer: removal 6.73 71 807
payment to supplier: creation 53.08 138 6369
payment to supplier: removal 3.86 56 463
service: total inventory turnovers 4.71 961 565
service: total monetary turnovers 6.73 33 808
service: aggregate perf. data 0.88 18450 106
service: refresh index statistics 0.03 297808 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 19:28:18 to 18-10-2020 19:28:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4741
TEST_TIME 2 5013
TEST_TIME 3 4868
TEST_TIME 4 4884
TEST_TIME 5 5635
TEST_TIME 6 5678
TEST_TIME 7 6125
TEST_TIME 8 6023
TEST_TIME 9 6580
TEST_TIME 10 6655
TEST_TIME 11 6814
TEST_TIME 12 6877
TEST_TIME 13 7119
TEST_TIME 14 7277
TEST_TIME 15 7734
TEST_TIME 16 8028
TEST_TIME 17 8155
TEST_TIME 18 8589
TEST_TIME 19 8643
TEST_TIME 20 9262
TEST_TIME 21 9335
TEST_TIME 22 9556
TEST_TIME 23 9476
TEST_TIME 24 9708
TEST_TIME 25 11018
TEST_TIME 26 11945
TEST_TIME 27 11904
TEST_TIME 28 11814
TEST_TIME 29 11633
TEST_TIME 30 11508
TEST_TIME 31 11563
TEST_TIME 32 11378
TEST_TIME 33 11296
TEST_TIME 34 11417
TEST_TIME 35 11430
TEST_TIME 36 11319
TEST_TIME 37 11086
TEST_TIME 38 10917
TEST_TIME 39 10863
TEST_TIME 40 10687
TEST_TIME 41 10653
TEST_TIME 42 10622
TEST_TIME 43 10593
TEST_TIME 44 10565
TEST_TIME 45 10702
TEST_TIME 46 10535
TEST_TIME 47 10541
TEST_TIME 48 10527
TEST_TIME 49 10406
TEST_TIME 50 10253
TEST_TIME 51 10279
TEST_TIME 52 10276
TEST_TIME 53 10215
TEST_TIME 54 10268
TEST_TIME 55 10231
TEST_TIME 56 10399
TEST_TIME 57 10354
TEST_TIME 58 10319
TEST_TIME 59 10363
TEST_TIME 60 10319
TEST_TIME 61 10385
TEST_TIME 62 10355
TEST_TIME 63 10460
TEST_TIME 64 10462
TEST_TIME 65 10465
TEST_TIME 66 10435
TEST_TIME 67 10302
TEST_TIME 68 10266
TEST_TIME 69 10164
TEST_TIME 70 10205
TEST_TIME 71 10188
TEST_TIME 72 10134
TEST_TIME 73 10191
TEST_TIME 74 10101
TEST_TIME 75 10177
TEST_TIME 76 10195
TEST_TIME 77 10141
TEST_TIME 78 10107
TEST_TIME 79 10116
TEST_TIME 80 10180
TEST_TIME 81 10228
TEST_TIME 82 10188
TEST_TIME 83 10276
TEST_TIME 84 10212
TEST_TIME 85 10221
TEST_TIME 86 10126
TEST_TIME 87 10093
TEST_TIME 88 10107
TEST_TIME 89 10046
TEST_TIME 90 9986
TEST_TIME 91 9987
TEST_TIME 92 9995
TEST_TIME 93 9965
TEST_TIME 94 9979
TEST_TIME 95 9968
TEST_TIME 96 9949
TEST_TIME 97 9990
TEST_TIME 98 9956
TEST_TIME 99 9979
TEST_TIME 100 9986
TEST_TIME 101 10011
TEST_TIME 102 9962
TEST_TIME 103 10055
TEST_TIME 104 10049
TEST_TIME 105 10019
TEST_TIME 106 10017
TEST_TIME 107 10043
TEST_TIME 108 10033
TEST_TIME 109 9959
TEST_TIME 110 9956
TEST_TIME 111 9930
TEST_TIME 112 9904
TEST_TIME 113 9877
TEST_TIME 114 9855
TEST_TIME 115 9807
TEST_TIME 116 9754
TEST_TIME 117 9724
TEST_TIME 118 9678
TEST_TIME 119 9786
TEST_TIME 120 9757

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2020 19:28:22 to 18-10-2020 19:28:25.

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 1623230 1623230 0 0.00 0 23394 6 0 0
doc_list_biud 1623228 1623228 0 0.00 0 53127 24 0 0
sp_add_invoice_to_stock 5489 5352 137 2.49 22 171738 15289 0 137
sp_cancel_adding_invoice 6104 6104 0 0.00 4 76141 729 0 0
sp_cancel_client_order 1896 1896 0 0.00 4 9503 247 0 0
sp_cancel_customer_reserve 590083 590083 0 0.00 1 29474 51 0 0
sp_cancel_pay_from_customer 807 807 0 0.00 3 8314 72 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 2986 56 0 0
sp_cancel_supplier_invoice 6740 6732 8 0.11 1 11026 737 0 8
sp_cancel_supplier_order 420 420 0 0.00 9 1057869 93172 0 0
sp_cancel_write_off 11433 11433 0 0.00 1 53162 91 0 0
sp_client_order 22092 22092 0 0.00 4 31942 301 0 0
sp_customer_reserve 486858 486858 0 0.00 0 80494 154 0 0
sp_fill_shopping_cart 34155 34155 0 0.00 1 22229 35 0 0
sp_fill_shopping_cart_clo_res 486853 486853 0 0.00 0 18618 9 0 0
sp_get_clo_for_invoice 5347 5347 0 0.00 0 28827 240 0 0
sp_kill_cost_storno 18807 18807 0 0.00 0 21527 48 0 0
sp_kill_qstorno_ret_qs2qd 1198751 1198751 0 0.00 0 53125 28 0 0
sp_kill_qty_storno 632850 632850 0 0.00 0 53127 60 0 0
sp_lock_selected_doc 22373 22373 0 0.00 0 9382 5 0 0
sp_make_cost_storno 30227 30227 0 0.00 0 23355 51 0 0
sp_multiply_rows_for_pdistr 30225 30225 0 0.00 0 15000 27 0 0
sp_multiply_rows_for_qdistr 22092 22092 0 0.00 0 27337 129 0 0
sp_pay_from_customer 7674 7674 0 0.00 4 23530 128 0 0
sp_pay_to_supplier 6369 6369 0 0.00 4 21099 138 0 0
sp_payment_common 14043 14043 0 0.00 1 23520 44 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 0 9497 223 0 0
sp_qd_handle_on_invoice_upd_sts 11449 11449 0 0.00 0 29054 291 0 0
sp_qd_handle_on_reserve_upd_sts 10837 10837 0 0.00 0 12813 44 0 0
sp_reserve_write_off 10837 10837 0 0.00 6 23444 167 0 0
sp_supplier_invoice 6006 6006 0 0.00 6 84617 3133 0 0
sp_supplier_order 6084 6084 0 0.00 5 87775 1104 0 0
srv_aggregate_perf_data 106 106 0 0.00 2838 166967 18450 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1778 127055 49357 0 0
srv_find_qd_qs_mism 1120396 1120396 0 0.00 0 20179 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 47 33831 961 0 0
srv_make_money_saldo 808 808 0 0.00 0 7316 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 204001 396095 297808 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5352 5352 0 0.00 0 20867 172 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 9406 122 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 5465 11 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 1361 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 807 807 0 0.00 0 1586 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 239 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 7144 28 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 17547 1494 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 878 3 0 0
t$perf-norm:sp_client_order 22092 22092 0 0.00 0 13353 17 0 0
t$perf-norm:sp_customer_reserve 1097 1097 0 0.00 0 3970 10 0 0
t$perf-norm:sp_pay_from_customer 7674 7674 0 0.00 0 7112 12 0 0
t$perf-norm:sp_pay_to_supplier 6369 6369 0 0.00 0 2922 7 0 0
t$perf-norm:sp_reserve_write_off 10837 10837 0 0.00 0 5603 10 0 0
t$perf-norm:sp_supplier_invoice 6006 6006 0 0.00 0 6635 18 0 0
t$perf-norm:sp_supplier_order 6084 6084 0 0.00 0 5502 12 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 126 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 59 2 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 4285 30 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1175 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 12 6 0 0
v_add_invoice_to_stock 10960 10686 274 2.50 0 12093 24 0 274
v_all_customers 39614 39614 0 0.00 0 23 1 0 0
v_all_suppliers 33168 33168 0 0.00 0 16 1 0 0
v_all_wares 44180 44180 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 3115 12 0 0
v_cancel_client_order 3792 3792 0 0.00 0 2835 6 0 0
v_cancel_customer_prepayment 1614 1614 0 0.00 0 897 5 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 10153 11 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 476 3 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 4225 23 0 16
v_cancel_supplier_order 840 840 0 0.00 0 13204 27 0 0
v_cancel_write_off 2060 2060 0 0.00 0 11938 28 0 0
v_max_id_clo_ord 6083 6083 0 0.00 0 882 1 0 0
v_max_id_clo_res 1097 1097 0 0.00 0 4368 16 0 0
v_max_id_ord_sup 5982 5982 0 0.00 0 3426 3 0 0
v_max_non_paid_invoice 1850 1850 0 0.00 0 923 3 0 0
v_max_non_paid_realizn 4262 4262 0 0.00 0 10361 7 0 0
v_min_id_clo_ord 6083 6083 0 0.00 0 6148 7 0 0
v_min_id_clo_res 1097 1097 0 0.00 0 780 4 0 0
v_min_id_ord_sup 5982 5982 0 0.00 0 8100 6 0 0
v_min_non_paid_invoice 6369 6369 0 0.00 0 18257 24 0 0
v_min_non_paid_realizn 7674 7674 0 0.00 0 18032 32 0 0
v_our_firm 11390 11390 0 0.00 0 16 1 0 0
v_reserve_write_off 21674 21674 0 0.00 0 11164 10 0 0
x_lock_depdocs_on_canc_invoice 12420 12420 0 0.00 0 29056 106 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 10322 343 0 0
x_make_qstorno_1000_1200 6084 6084 0 0.00 2 87754 1030 0 0
x_make_qstorno_1200_2000 6006 6006 0 0.00 2 84273 3014 0 0
x_make_qstorno_2100_3300 486858 486858 0 0.00 0 80493 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 19:28:26 to 18-10-2020 19:28:30.

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
16:57:02 4 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:03 33154 8192000000 10372757856 10695229440 1420428768 14.777 100 90 180 1 1456735872 6058416 30248688
16:59:41 19668 8192000000 10434369216 10769125376 1487975296 15.371 100 84 168 0 1522492336 7456320 27060720
17:01:06 13057 8192000000 10495418832 10840838144 1552225744 15.929 100 84 167 1 1587286960 9344576 25716640
17:02:23 13900 8192000000 10590183424 10946584576 1642980112 16.705 100 84 166 1 1679011792 11333200 24698480
17:03:42 7296 8192000000 10653933552 11028029440 1710678352 17.274 100 75 150 1 1744753744 12663536 21411856
17:04:52 5027 8192000000 10717698944 11095384064 1774160176 17.801 100 81 162 1 1808912016 11511088 23240752
17:05:59 1484 8192000000 10772064624 11159879680 1828501568 18.247 100 93 187 4 1869913696 15375008 26037120
17:07:01 945 8192000000 10815098368 11202473984 1873435600 18.612 100 92 183 2 1912936864 13025392 26475872
17:08:03 10418 8192000000 10781277216 11213856768 1880534256 18.669 100 42 83 2 1900565664 8122992 11908416
17:09:13 18736 8192000000 10791520768 11224322048 1884229872 18.699 100 12 24 0 1893355744 5598832 3527040
17:10:33 53541 8192000000 10859853392 11258601472 1904174816 18.860 100 52 105 2 1924917328 5316720 15425792
17:12:26 4307 8192000000 10829806720 11258019840 1905409968 18.870 100 31 62 3 1920741184 6784640 8546576
17:13:32 8039 8192000000 10819004608 11257081856 1905469104 18.870 100 24 48 1 1918735744 6272624 6994016
17:14:40 39499 8192000000 10806967264 11246256128 1866340272 18.555 98 16 32 3 1876690048 4970288 5379488
17:16:23 6517 8192000000 10795951904 11231510528 1882148000 18.682 100 24 48 2 1895423328 6087648 7187680
17:17:34 6692 8192000000 10728040288 11138949120 1718360512 17.339 93 28 55 2 1731543472 4736272 8446688
17:18:44 53193 8192000000 10706122912 11115429888 1758943984 17.676 98 31 63 0 1775273712 5115232 11214496
17:20:43 4213 8192000000 10730453808 11134140416 1797627472 17.994 100 40 80 4 1816580224 6798480 12154272
17:21:51 5279 8192000000 10749470432 11171627008 1832261104 18.278 100 33 64 1 1848316320 6670816 9384400
17:22:56 10227 8192000000 10757473056 11188830208 1843386528 18.368 100 8 16 0 1850673424 5102208 2184688
17:24:07 51656 8192000000 10770216112 11204141056 1860420080 18.507 100 21 43 0 1876092800 9451264 6221456
17:26:00 8228 8192000000 10784278000 11213885440 1867490080 18.564 100 27 54 1 1881330832 5828496 8012256
17:27:13 19585 8192000000 10827275472 11238383616 1877283072 18.643 99 57 113 4 1899670656 5522992 16864592
17:28:36 8003 8192000000 10856965520 11275710464 1922203520 19.004 100 52 104 2 1945997760 8950752 14843488
17:29:46 41645 8192000000 10839790416 11271925760 1889845216 18.745 98 44 88 3 1909947328 6953040 13149072
17:31:31 4463 8192000000 10876436896 11293061120 1930293696 19.069 99 63 125 1 1961375184 13383936 17697552
17:32:41 9708 8192000000 10887093568 11312521216 1937003104 19.123 99 59 118 2 1969657712 15380752 17273856
17:33:54 16515 8192000000 10903519488 11326431232 1940734000 19.153 99 50 100 0 1974796048 19930736 14131312
17:35:29 9968 8192000000 10903365040 11324637184 1936287104 19.117 99 60 118 0 1976158320 22736224 17134992
17:36:44 4808 8192000000 10928122384 11351486464 1971752560 19.399 100 64 129 2 2018839168 28003936 19082672
17:37:52 2099 8192000000 10951254592 11368030208 1984529536 19.501 100 84 168 0 2041939872 30565040 26845296
17:38:58 5119 8192000000 10931308320 11356786688 1948813488 19.217 99 65 128 1 2005846496 36818560 20214448
17:40:06 3602 8192000000 10921744560 11318546432 1918011168 18.971 98 92 184 1 1989725920 44179712 27535040
17:41:18 4347 8192000000 10967535360 11380027392 1974200128 19.419 100 77 153 0 2045404592 47114544 24089920
17:42:26 2300 8192000000 10968408016 11374399488 1967960336 19.369 100 85 169 0 2047796496 53480640 26355520
17:43:35 1777 8192000000 10952050368 11359903744 1951173216 19.236 100 74 142 1 2033682784 59249600 23259968
17:44:41 8408 8192000000 10957582416 11376652288 1955708896 19.272 100 57 112 2 2029990304 56202864 18078544
17:45:56 3803 8192000000 10985812800 11406991360 1978581232 19.453 100 61 122 0 2064925888 65229232 21115424
17:47:02 24952 8192000000 10947855168 11396771840 1969204720 19.379 100 31 62 2 2034640672 55402560 10033392
17:48:29 32685 8192000000 10975443712 11424038912 1982775712 19.487 100 32 63 1 2052100384 57954640 11370032
17:50:02 24334 8192000000 10968658240 11432804352 1969991184 19.385 100 25 51 0 2055281120 75330192 9959744
17:51:41 43996 8192000000 10920429616 11370582016 1939384592 19.142 99 19 37 1 1998153008 52025152 6743264
17:54:32 104653 8192000000 10904843248 11372957696 1915556240 18.951 98 9 18 0 1939501424 22020128 1925056
17:57:21 64327 8192000000 10888513632 11355734016 1894815904 18.785 96 20 40 1 1911192176 11443424 4932848
17:59:27 83619 8192000000 10904415136 11375046656 1893533776 18.774 96 10 20 1 1907683504 11945232 2204496
18:01:51 106435 8192000000 10903097264 11357057024 1920943760 18.994 97 18 37 0 1937748816 11793328 5011728
18:04:38 45506 8192000000 10917120560 11370049536 1977764992 19.447 100 47 93 1 1998224192 7296720 13162480
18:06:37 82993 8192000000 10941852448 11400609792 1992303120 19.562 100 30 58 0 2011664016 11406576 7954320
18:09:03 32487 8192000000 10957952704 11397607424 1963481328 19.334 98 33 66 1 1984809136 11996688 9331120
18:10:36 21244 8192000000 10924701392 11393830912 1975147456 19.426 99 49 98 3 2006019520 16921968 13950096
18:12:22 66465 8192000000 10897562032 11380256768 1971309296 19.396 100 9 18 0 1989809680 16080512 2419872
18:14:28 127055 8192000000 10870976528 11346190336 1902069008 18.843 96 15 30 0 1920373088 13910864 4393216
18:17:52 76007 8192000000 10889427392 11368415232 1899194576 18.820 97 8 16 0 1915975424 14621984 2158864
18:20:09 58875 8192000000 10860418176 11334705152 1850908432 18.430 95 14 28 1 1868435632 13478592 4048608
18:22:15 43545 8192000000 10891582640 11378552832 1952906784 19.250 100 13 26 0 1973134144 16115168 4112192
18:23:59 46576 8192000000 10858291760 11326488576 1842367040 18.360 94 21 42 1 1860725776 13021552 5337184
18:26:08 31861 8192000000 10851603552 11312734208 1834335264 18.295 94 24 48 0 1852283008 11138656 6809088
18:27:40 78318 8192000000 10874240464 11341803520 1863671728 18.533 96 10 20 0 1876876160 10794224 2410208
18:30:00 75598 8192000000 10857033664 11321180160 1817371696 18.156 93 11 21 1 1830653984 10445312 2836976
18:32:16 65462 8192000000 10913740208 11353907200 1938412400 19.134 99 26 52 0 1952912528 7200272 7299856
18:34:37 49929 8192000000 10874975152 11351691264 1925621088 19.032 99 11 22 1 1946243696 17593520 3029088
18:36:28 71781 8192000000 10874720816 11362541568 1941560736 19.159 100 7 14 2 1963378896 19983488 1834672
18:39:14 82418 8192000000 10898866384 11371683840 1939841408 19.145 99 8 16 0 1954223536 11766832 2615296
18:41:36 64081 8192000000 10881007424 11346128896 1892765168 18.768 96 11 22 0 1912949600 16942560 3241872
18:43:55 25986 8192000000 10884851600 11343495168 1936698080 19.120 99 24 48 1 1951885808 8719360 6468368
18:45:21 76413 8192000000 10883233920 11361107968 1926781200 19.041 98 31 62 0 1954167040 18599152 8786688
18:47:39 79013 8192000000 10914962880 11377545216 1954229344 19.260 99 21 42 0 1972076208 12200048 5646816
18:49:58 71805 8192000000 10894352864 11359088640 1913717248 18.936 97 14 28 1 1934940656 17010048 4213360
18:52:20 83428 8192000000 10873876032 11340402688 1923478320 19.015 99 27 54 0 1945178384 13755264 7944800
18:54:44 89445 8192000000 10882283968 11356782592 1903521936 18.855 97 10 20 1 1926386768 19846688 3018144
18:57:19 64472 8192000000 10876406192 11364298752 1932750704 19.089 99 7 14 1 1950943008 16403792 1788512
18:59:24 55635 8192000000 10864194864 11349012480 1913758704 18.937 98 5 10 0 1927022656 12136176 1127776
19:02:24 58850 8192000000 10886822912 11364327424 1918430496 18.974 97 18 35 1 1930260976 6964736 4865744
19:04:25 17782 8192000000 10887538176 11343720448 1929309040 19.061 98 45 85 6 1948457184 7210208 11937936
19:05:50 68519 8192000000 10882362016 11357564928 1900180384 18.828 97 10 19 1 1917020736 14208768 2631584
19:07:59 55115 8192000000 10905177376 11370012672 1912494096 18.927 97 26 52 0 1930517584 10689200 7334288
19:10:07 83917 8192000000 10889862112 11345506304 1878857376 18.656 96 28 56 1 1896706720 10032208 7817136
19:12:35 67905 8192000000 10868014912 11330093056 1892055232 18.762 97 23 46 1 1908679520 10576400 6047888
19:14:43 105237 8192000000 10865343936 11335393280 1903245168 18.852 98 14 26 1 1918625824 11514176 3866480
19:17:40 33569 8192000000 10878707744 11356303360 1952836640 19.249 100 17 34 0 1967182816 9757840 4588336
19:19:15 64805 8192000000 10870751264 11354025984 1957864512 19.289 100 6 12 0 1966611760 7201072 1546176
19:21:22 70039 8192000000 10878638048 11350036480 1919003744 18.979 98 12 24 0 1934329776 11856736 3469296
19:23:36 87586 8192000000 10881291488 11370872832 1938761856 19.137 99 12 24 0 1959519648 17278224 3479568
19:26:10 49167 8192000000 10877543728 11348922368 1903013264 18.851 97 13 25 1 1915114400 7749472 4351664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2020 19:28:32 to 18-10-2020 19:28:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 137 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-10-2020 19:28:48 to 18-10-2020 19:28:49.

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          258613
MON$OLDEST_ACTIVE               259752
MON$OLDEST_SNAPSHOT             259752
MON$NEXT_TRANSACTION            259752
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                       2059680
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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Sun Oct 18 19:27:02 2020
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
--- end of diff output ---

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

18.10.20 19:28:50. 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 19:28:53 No severe PSQL-related problems occured
18.10.20 19:28:54. End of report.