Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 04: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 16823180 kB
MemAvailable 31508728 kB
Buffers 20 kB
Cached 14756812 kB
SwapCached 5432 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 04:26:51
DTS_END                         2020-10-18 04:26:51.8180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/51024
TRN_ID                          235718
ATT_ID                          656
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: 8899.40 21913 1067928
customer order: creation 170.43 421 20451
customer order: refuse 14.45 321 1734
order to supplier: creation 45.41 1378 5449
order to supplier: removal 3.18 127390 381
invoice (draft): creation 45.74 3391 5489
invoice (draft): removal 48.35 1030 5802
invoice accept: apply 41.58 16500 4990
invoice accept: cancel 43.99 905 5279
customer reserve: creation 3858.32 161 462998
customer reserve: removal 4344.18 75 521302
realization accept: apply 80.69 183 9683
realization accept: cancel 78.56 131 9427
payment from customer: creation 57.82 132 6938
payment from customer: removal 5.87 120 704
payment to supplier: creation 46.95 128 5634
payment to supplier: removal 3.58 89 430
service: total inventory turnovers 3.50 3725 420
service: total monetary turnovers 5.84 587 701
service: aggregate perf. data 0.93 15509 111
service: refresh index statistics 0.04 266093 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1823
TEST_TIME 2 2002
TEST_TIME 3 2517
TEST_TIME 4 3352
TEST_TIME 5 3790
TEST_TIME 6 4229
TEST_TIME 7 4389
TEST_TIME 8 4279
TEST_TIME 9 4512
TEST_TIME 10 4657
TEST_TIME 11 4714
TEST_TIME 12 5118
TEST_TIME 13 5279
TEST_TIME 14 5565
TEST_TIME 15 5959
TEST_TIME 16 6744
TEST_TIME 17 7159
TEST_TIME 18 7160
TEST_TIME 19 7348
TEST_TIME 20 7512
TEST_TIME 21 8030
TEST_TIME 22 8305
TEST_TIME 23 8766
TEST_TIME 24 8734
TEST_TIME 25 8883
TEST_TIME 26 9263
TEST_TIME 27 9773
TEST_TIME 28 10871
TEST_TIME 29 11216
TEST_TIME 30 11143
TEST_TIME 31 11195
TEST_TIME 32 10998
TEST_TIME 33 10869
TEST_TIME 34 10788
TEST_TIME 35 10571
TEST_TIME 36 10461
TEST_TIME 37 10305
TEST_TIME 38 10216
TEST_TIME 39 10143
TEST_TIME 40 10169
TEST_TIME 41 10188
TEST_TIME 42 10229
TEST_TIME 43 10224
TEST_TIME 44 10123
TEST_TIME 45 10003
TEST_TIME 46 10033
TEST_TIME 47 9941
TEST_TIME 48 9924
TEST_TIME 49 9918
TEST_TIME 50 9918
TEST_TIME 51 9851
TEST_TIME 52 9925
TEST_TIME 53 9975
TEST_TIME 54 9925
TEST_TIME 55 9970
TEST_TIME 56 9968
TEST_TIME 57 9997
TEST_TIME 58 9987
TEST_TIME 59 9979
TEST_TIME 60 9817
TEST_TIME 61 9737
TEST_TIME 62 9616
TEST_TIME 63 9567
TEST_TIME 64 9542
TEST_TIME 65 9535
TEST_TIME 66 9540
TEST_TIME 67 9463
TEST_TIME 68 9524
TEST_TIME 69 9527
TEST_TIME 70 9532
TEST_TIME 71 9460
TEST_TIME 72 9610
TEST_TIME 73 9645
TEST_TIME 74 9590
TEST_TIME 75 9663
TEST_TIME 76 9626
TEST_TIME 77 9623
TEST_TIME 78 9520
TEST_TIME 79 9510
TEST_TIME 80 9428
TEST_TIME 81 9340
TEST_TIME 82 9303
TEST_TIME 83 9335
TEST_TIME 84 9296
TEST_TIME 85 9260
TEST_TIME 86 9245
TEST_TIME 87 9195
TEST_TIME 88 9175
TEST_TIME 89 9204
TEST_TIME 90 9251
TEST_TIME 91 9317
TEST_TIME 92 9331
TEST_TIME 93 9341
TEST_TIME 94 9355
TEST_TIME 95 9381
TEST_TIME 96 9289
TEST_TIME 97 9222
TEST_TIME 98 9196
TEST_TIME 99 9130
TEST_TIME 100 9078
TEST_TIME 101 9064
TEST_TIME 102 9025
TEST_TIME 103 9035
TEST_TIME 104 8995
TEST_TIME 105 9009
TEST_TIME 106 9000
TEST_TIME 107 9028
TEST_TIME 108 9015
TEST_TIME 109 9019
TEST_TIME 110 9025
TEST_TIME 111 9083
TEST_TIME 112 9093
TEST_TIME 113 9147
TEST_TIME 114 9074
TEST_TIME 115 9016
TEST_TIME 116 8975
TEST_TIME 117 8975
TEST_TIME 118 8948
TEST_TIME 119 8925
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2020 04:28:11 to 18-10-2020 04:28:15.

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 1496401 1496401 0 0.00 0 26318 7 0 0
doc_list_biud 1496400 1496400 0 0.00 0 71214 33 0 0
sp_add_invoice_to_stock 5107 4990 117 2.29 28 178950 16501 0 117
sp_cancel_adding_invoice 5279 5279 0 0.00 5 72952 906 0 0
sp_cancel_client_order 1734 1734 0 0.00 4 22860 322 0 0
sp_cancel_customer_reserve 521302 521302 0 0.00 1 71215 75 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 12254 121 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5399 90 0 0
sp_cancel_supplier_invoice 5813 5802 11 0.18 3 17383 1030 0 11
sp_cancel_supplier_order 381 381 0 0.00 7 1316126 127390 0 0
sp_cancel_write_off 9427 9427 0 0.00 1 10016 131 0 0
sp_client_order 20451 20451 0 0.00 4 18449 421 0 0
sp_customer_reserve 462998 462998 0 0.00 0 34691 161 0 0
sp_fill_shopping_cart 31369 31369 0 0.00 1 17174 45 0 0
sp_fill_shopping_cart_clo_res 462996 462996 0 0.00 0 33121 18 0 0
sp_get_clo_for_invoice 4988 4988 0 0.00 0 34599 394 0 0
sp_kill_cost_storno 15840 15840 0 0.00 0 12248 61 0 0
sp_kill_qstorno_ret_qs2qd 1058214 1058214 0 0.00 0 71181 41 0 0
sp_kill_qty_storno 558596 558596 0 0.00 0 71214 86 0 0
sp_lock_selected_doc 20325 20325 0 0.00 0 9774 6 0 0
sp_make_cost_storno 27243 27243 0 0.00 0 17226 57 0 0
sp_multiply_rows_for_pdistr 27243 27243 0 0.00 0 12056 34 0 0
sp_multiply_rows_for_qdistr 20451 20451 0 0.00 0 18438 249 0 0
sp_pay_from_customer 6938 6938 0 0.00 4 13643 132 0 0
sp_pay_to_supplier 5634 5634 0 0.00 5 16312 129 0 0
sp_payment_common 12572 12572 0 0.00 1 11234 27 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 22828 285 0 0
sp_qd_handle_on_invoice_upd_sts 10267 10267 0 0.00 0 43737 345 0 0
sp_qd_handle_on_reserve_upd_sts 9683 9683 0 0.00 0 8822 53 0 0
sp_reserve_write_off 9683 9683 0 0.00 7 17018 183 0 0
sp_supplier_invoice 5489 5489 0 0.00 6 103787 3392 0 0
sp_supplier_order 5449 5449 0 0.00 6 44392 1378 0 0
srv_aggregate_perf_data 111 111 0 0.00 2887 273233 15510 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2319 131813 44733 0 0
srv_find_qd_qs_mism 1020656 1020656 0 0.00 0 26318 6 0 0
srv_make_invnt_saldo 420 420 0 0.00 49 118930 3725 0 0
srv_make_money_saldo 701 701 0 0.00 0 17205 588 0 0
srv_recalc_idx_stat 5 5 0 0.00 61029 477812 266093 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 46997 155 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 4632 82 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 4209 17 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 3700 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 3265 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 5203 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 556 12 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 16827 1655 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 3418 8 0 0
t$perf-norm:sp_client_order 20451 20451 0 0.00 0 8942 17 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 2278 11 0 0
t$perf-norm:sp_pay_from_customer 6938 6938 0 0.00 0 7630 14 0 0
t$perf-norm:sp_pay_to_supplier 5634 5634 0 0.00 0 3696 10 0 0
t$perf-norm:sp_reserve_write_off 9683 9683 0 0.00 0 26159 11 0 0
t$perf-norm:sp_supplier_invoice 5489 5489 0 0.00 0 4240 16 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 6809 13 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 366 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2798 45 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 1529 9 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 917 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10210 9976 234 2.29 0 5179 19 0 234
v_all_customers 37366 37366 0 0.00 0 14 1 0 0
v_all_suppliers 29914 29914 0 0.00 0 13 1 0 0
v_all_wares 40890 40890 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 5631 21 0 0
v_cancel_client_order 3468 3468 0 0.00 0 7099 9 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3320 10 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 368 3 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3929 13 0 0
v_cancel_supplier_invoice 760 738 22 2.89 0 1177 13 0 22
v_cancel_supplier_order 762 762 0 0.00 0 3023 13 0 0
v_cancel_write_off 1948 1948 0 0.00 0 1730 14 0 0
v_max_id_clo_ord 5444 5444 0 0.00 0 1709 3 0 0
v_max_id_clo_res 942 942 0 0.00 0 2367 16 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 5198 6 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 143 2 0 0
v_max_non_paid_realizn 3662 3662 0 0.00 0 5791 5 0 0
v_min_id_clo_ord 5444 5444 0 0.00 0 9208 8 0 0
v_min_id_clo_res 942 942 0 0.00 0 8367 25 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 7395 9 0 0
v_min_non_paid_invoice 5634 5634 0 0.00 0 10834 18 0 0
v_min_non_paid_realizn 6938 6938 0 0.00 0 11227 20 0 0
v_our_firm 10076 10076 0 0.00 0 16 1 0 0
v_reserve_write_off 19354 19354 0 0.00 0 8624 5 0 0
x_lock_depdocs_on_canc_invoice 10712 10712 0 0.00 0 20148 109 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 11478 367 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 2 44378 1301 0 0
x_make_qstorno_1200_2000 5489 5489 0 0.00 3 103777 3219 0 0
x_make_qstorno_2100_3300 462998 462998 0 0.00 0 34688 141 0 0

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

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
01:56:55 7 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
01:57:55 25484 8192000000 9717842352 10010746880 793371232 8.829 100 90 181 0 823518256 7824336 22322688
01:59:32 13332 8192000000 9759671792 10065821696 837707984 9.277 100 88 176 0 871493104 13103744 20681376
02:00:49 20924 8192000000 9802462288 10118008832 862452016 9.525 100 82 162 3 901461392 20401232 18608144
02:02:12 13313 8192000000 9858308576 10187264000 904357344 9.941 100 81 162 0 961711984 39637792 17716848
02:03:30 6786 8192000000 9922937792 10262282240 951156000 10.402 100 85 170 2 1036876000 67948256 17771744
02:04:45 5295 8192000000 9961013056 10315173888 994510208 10.825 100 78 156 0 1073926848 63827808 15588832
02:05:54 1738 8192000000 9994666896 10355539968 1027677664 11.146 100 86 171 0 1105919984 60486208 17756112
02:06:58 1779 8192000000 10005168368 10371051520 1050880432 11.369 100 97 196 3 1123978048 52320272 20777344
02:08:01 23847 8192000000 9982192656 10378424320 1063483968 11.490 100 35 69 1 1135043712 64483376 7076368
02:09:28 2753 8192000000 10005641840 10389639168 1077336240 11.622 100 53 107 3 1119239248 29945632 11957376
02:10:32 4800 8192000000 9990779088 10386026496 1086036080 11.705 100 35 70 0 1107674944 13921280 7717584
02:11:39 61119 8192000000 10014470480 10425835520 1089930368 11.742 100 36 71 1 1120476144 22834896 7710880
02:13:49 7589 8192000000 10019473920 10425888768 1095003776 11.790 100 32 65 2 1124294752 23009632 6281344
02:15:00 5537 8192000000 10006296896 10403311616 1098448384 11.823 100 28 55 2 1117914384 12085760 7380240
02:16:07 7059 8192000000 10017539792 10431229952 1084831360 11.693 99 25 50 1 1121125120 30663904 5629856
02:17:16 62003 8192000000 9979145840 10385735680 1031203296 11.180 95 20 40 2 1060460112 25123536 4133280
02:19:22 5110 8192000000 9955948048 10334007296 994407808 10.824 95 39 78 1 1030987568 28319888 8259872
02:20:29 9108 8192000000 9940083216 10330857472 981918736 10.703 96 39 74 1 1007766032 17600256 8247040
02:21:40 7473 8192000000 9927448512 10297671680 1019879232 11.071 100 21 40 0 1059373616 34636608 4857776
02:22:51 38567 8192000000 9940895808 10332102656 1015911088 11.033 98 16 33 1 1044273984 24700016 3662880
02:24:30 6806 8192000000 9989107648 10375598080 1045439568 11.317 100 51 102 2 1078572608 21001344 12131696
02:25:39 8372 8192000000 9966875968 10364932096 1055954528 11.418 100 30 59 1 1088997680 25724240 7318912
02:26:49 8167 8192000000 9977084432 10372919296 1076156736 11.611 100 38 76 2 1092473808 7746512 8570560
02:28:02 18018 8192000000 10014066288 10408972288 1089893888 11.742 100 55 111 3 1122876176 21783120 11199168
02:29:27 10897 8192000000 10059229488 10459451392 1101136144 11.848 100 57 115 0 1169357696 56538368 11683184
02:30:43 9991 8192000000 10107677584 10509504512 1118464496 12.012 100 56 110 1 1220405248 90585408 11355344
02:31:56 12995 8192000000 10115884256 10519789568 1129059328 12.112 100 49 96 2 1230273360 91321120 9892912
02:33:15 17944 8192000000 10145980784 10549940224 1144399840 12.257 100 41 80 2 1258845984 105400560 9045584
02:34:39 22226 8192000000 10209922432 10621607936 1157968816 12.384 100 50 101 3 1327052688 157643728 11440144
02:36:09 4580 8192000000 10270151120 10672439296 1164779776 12.448 100 65 127 3 1371919328 192773200 14366352
02:37:16 2756 8192000000 10321054016 10720833536 1169465472 12.492 100 74 146 3 1422568144 237060576 16042096
02:38:25 3209 8192000000 10326570208 10741694464 1154225280 12.349 99 75 150 3 1430750480 259512688 17012512
02:39:39 6224 8192000000 10345112240 10751827968 1163842560 12.439 100 66 129 1 1451328608 271754784 15731264
02:40:49 11174 8192000000 10384031616 10796228608 1153432816 12.342 99 57 114 1 1486750336 319499872 13817648
02:42:07 4134 8192000000 10318162592 10754453504 1138240864 12.199 100 77 152 2 1426950896 270778592 17931440
02:43:25 5594 8192000000 10338993296 10742460416 1146584208 12.277 100 60 120 0 1438027280 277273664 14169408
02:44:44 7376 8192000000 10356525920 10766020608 1129765120 12.119 98 52 102 3 1443436112 300983456 12687536
02:45:57 3461 8192000000 10397428656 10807586816 1138382784 12.200 99 58 118 1 1493417472 340809552 14225136
02:47:05 2316 8192000000 10388560944 10815143936 1122302944 12.049 99 65 130 0 1481417600 342808704 16305952
02:48:11 10937 8192000000 10390022464 10833014784 1136202752 12.180 100 43 86 0 1503820704 356692768 10925184
02:49:27 4694 8192000000 10415160512 10846879744 1146195744 12.274 100 47 94 0 1514899184 357161696 11541744
02:50:38 11339 8192000000 10413074080 10832437248 1137437552 12.191 100 33 66 0 1507996064 361899424 8659088
02:52:00 17215 8192000000 10411836432 10859171840 1116201936 11.991 99 30 60 1 1520879264 396287360 8389968
02:53:19 19275 8192000000 10371423680 10903347200 1114871072 11.979 99 23 46 2 1500049536 378732224 6446240
02:54:42 21883 8192000000 10079326880 10624536576 1099827520 11.836 99 14 28 0 1279246128 175659088 3759520
02:56:05 64195 8192000000 10054026496 10518175744 1083373216 11.680 97 22 43 5 1166388336 78498496 4516624
02:58:11 80685 8192000000 10089196544 10503147520 1107052048 11.904 98 26 52 1 1163410512 51189248 5169216
03:00:40 106848 8192000000 10079189504 10506870784 1125297152 12.077 100 30 59 3 1181135072 49694224 6143696
03:03:29 44872 8192000000 10134149792 10574430208 1123051760 12.056 99 19 38 1 1200833744 73425616 4356368
03:05:17 102239 8192000000 10137631136 10609836032 1084814272 11.693 95 20 40 0 1217401584 127927168 4660144
03:08:19 52227 8192000000 10096719344 10573840384 1141130176 12.226 100 10 20 0 1193725344 50461488 2133680
03:11:20 81615 8192000000 10082504112 10562695168 1146871280 12.280 100 7 14 0 1180395088 32080864 1442944
03:14:32 23798 8192000000 10079920624 10517004288 1154177120 12.349 100 28 57 5 1177843600 17891472 5775008
03:16:05 34735 8192000000 10136885408 10589646848 1160286784 12.406 100 29 58 1 1224172080 57949344 5935952
03:17:53 44778 8192000000 10140716384 10587025408 1131568224 12.136 98 20 40 0 1229791616 93548800 4674592
03:19:39 21539 8192000000 10096673680 10543521792 1155542160 12.361 100 35 70 0 1203547648 41219936 6785552
03:21:03 65181 8192000000 10123120464 10575650816 1136514704 12.183 98 37 74 4 1211519344 67238256 7766384
03:23:09 55222 8192000000 10100570144 10578014208 1097434768 11.813 95 13 26 0 1143501344 43233344 2833232
03:25:17 88341 8192000000 10067223168 10514354176 1125152080 12.076 98 25 50 0 1161835952 31215632 5468240
03:28:03 55869 8192000000 10097796304 10542612480 1126135504 12.085 98 15 30 2 1186686752 57579312 2971936
03:29:59 53023 8192000000 10083480048 10568536064 1125062048 12.075 99 8 16 0 1193033360 66371568 1599744
03:31:52 57073 8192000000 10069545456 10523082752 1060527056 11.462 93 17 32 1 1128176080 64418816 3230208
03:33:53 43514 8192000000 10072592832 10509209600 1107572992 11.909 98 25 50 1 1167257248 54192672 5491584
03:35:39 40999 8192000000 10090930752 10524069888 1057879152 11.436 93 15 30 1 1118354288 57017504 3457632
03:37:20 92665 8192000000 10118920240 10616987648 1134722816 12.166 100 27 54 1 1243601760 102746544 6132400
03:40:06 57764 8192000000 10097067056 10512601088 1088463104 11.728 96 18 35 0 1146539632 54639440 3437088
03:42:05 36149 8192000000 10088090880 10491834368 1077039088 11.619 96 36 72 0 1126468288 42629520 6799680
03:43:42 126137 8192000000 10076510032 10525130752 1110137856 11.934 99 31 62 0 1153467264 36924160 6405248
03:46:50 39337 8192000000 10077026336 10543841280 1113040576 11.961 100 7 14 0 1169969648 55331008 1598064
03:48:40 52649 8192000000 10090097152 10560905216 1100232512 11.840 99 10 20 0 1169629616 67322048 2075056
03:50:32 89142 8192000000 10050188416 10483089408 1095981168 11.799 98 17 34 2 1138434320 39077632 3375520
03:53:02 70516 8192000000 10119257680 10550513664 1123942144 12.064 99 20 40 3 1186666144 58512960 4211040
03:55:20 86955 8192000000 10135712976 10624102400 1134246448 12.161 100 21 41 1 1227229200 88576384 4406368
03:57:54 45346 8192000000 10054779744 10511671296 1108363136 11.917 98 21 42 0 1142110256 29594064 4153056
03:59:44 38912 8192000000 10109039136 10543816704 1115862928 11.988 98 17 34 4 1145899392 26598896 3437568
04:01:28 131813 8192000000 10059958496 10519949312 1128347360 12.106 99 21 42 0 1165221280 32391280 4482640
04:04:49 44502 8192000000 10068604384 10524360704 1139559696 12.211 100 7 14 0 1181032560 39950848 1522016
04:07:18 73130 8192000000 10093937488 10584461312 1124252288 12.067 98 21 42 0 1196724336 67853184 4618864
04:09:31 83194 8192000000 10108422464 10550972416 1119495072 12.022 98 21 42 0 1195253376 71028432 4729872
04:11:58 76497 8192000000 10074992432 10533359616 1106256944 11.897 97 32 64 0 1178473712 65426624 6790144
04:14:15 94481 8192000000 10065973456 10505641984 1090558480 11.748 96 17 34 2 1165509168 71343344 3607344
04:16:57 52731 8192000000 10083207296 10576699392 1131788544 12.138 100 38 76 1 1206008064 66247152 7972368
04:18:51 71804 8192000000 10068863872 10542891008 1122143248 12.047 100 9 18 1 1188506960 64474816 1888896
04:21:05 88493 8192000000 10059019456 10517626880 1115235616 11.982 99 16 32 0 1159930032 41216496 3477920
04:24:31 31413 8192000000 10034159648 10474954752 1089696272 11.740 98 5 10 0 1121166256 30648128 821856
04:26:04 26035 8192000000 10044827440 10484027392 1096140688 11.801 99 28 56 2 1143791920 41599184 6052048

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 04:28:21 to 18-10-2020 04:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 117 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 04:28:37 to 18-10-2020 04:28:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234016
MON$OLDEST_ACTIVE               236160
MON$OLDEST_SNAPSHOT             236160
MON$NEXT_TRANSACTION            236160
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-30 07:30:08.6030 GMT
MON$PAGES                       2099840
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             784
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:121: 
:122: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:54 2020
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:56 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:56 2020
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:56 2020
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:57 2020
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:57 2020
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:57 2020
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:58 2020
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Sun Oct 18 04:26:58 2020
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

18.10.20 04:28:40. There are 3 logs in /var/tmp/logs-oltp40 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 04:28:42 No severe PSQL-related problems occured
18.10.20 04:28:43. End of report.