Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2020 07:35
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 16776132 kB
MemAvailable 31524948 kB
Buffers 0 kB
Cached 14861384 kB
SwapCached 4452 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-22 07:35:23
DTS_END                         2020-10-22 07:35:23.6430
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/60774
TRN_ID                          236189
ATT_ID                          617
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: 9044.67 21097 1085361
customer order: creation 171.71 418 20605
customer order: refuse 14.57 336 1748
order to supplier: creation 45.19 1457 5423
order to supplier: removal 3.43 125556 411
invoice (draft): creation 45.81 3576 5497
invoice (draft): removal 51.12 996 6134
invoice accept: apply 41.47 17573 4976
invoice accept: cancel 46.49 893 5579
customer reserve: creation 3822.65 173 458718
customer reserve: removal 4509.96 75 541195
realization accept: apply 83.13 161 9976
realization accept: cancel 81.58 131 9790
payment from customer: creation 59.83 106 7180
payment from customer: removal 5.64 86 677
payment to supplier: creation 48.03 118 5763
payment to supplier: removal 3.29 82 395
service: total inventory turnovers 4.04 3398 485
service: total monetary turnovers 5.88 614 706
service: aggregate perf. data 0.83 21570 99
service: refresh index statistics 0.03 244624 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2020 07:36:13 to 22-10-2020 07:36:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8071
TEST_TIME 2 6022
TEST_TIME 3 6528
TEST_TIME 4 5948
TEST_TIME 5 6029
TEST_TIME 6 6061
TEST_TIME 7 6075
TEST_TIME 8 5989
TEST_TIME 9 6060
TEST_TIME 10 6023
TEST_TIME 11 6125
TEST_TIME 12 6129
TEST_TIME 13 6430
TEST_TIME 14 6427
TEST_TIME 15 6636
TEST_TIME 16 6697
TEST_TIME 17 6929
TEST_TIME 18 7005
TEST_TIME 19 7138
TEST_TIME 20 7419
TEST_TIME 21 7761
TEST_TIME 22 7888
TEST_TIME 23 8025
TEST_TIME 24 8704
TEST_TIME 25 8746
TEST_TIME 26 9256
TEST_TIME 27 9723
TEST_TIME 28 10246
TEST_TIME 29 10984
TEST_TIME 30 10927
TEST_TIME 31 10764
TEST_TIME 32 10640
TEST_TIME 33 10759
TEST_TIME 34 10540
TEST_TIME 35 10269
TEST_TIME 36 10210
TEST_TIME 37 10089
TEST_TIME 38 10185
TEST_TIME 39 10121
TEST_TIME 40 10009
TEST_TIME 41 9955
TEST_TIME 42 9810
TEST_TIME 43 9706
TEST_TIME 44 9669
TEST_TIME 45 9532
TEST_TIME 46 9526
TEST_TIME 47 9819
TEST_TIME 48 9968
TEST_TIME 49 9918
TEST_TIME 50 9964
TEST_TIME 51 9966
TEST_TIME 52 9862
TEST_TIME 53 9818
TEST_TIME 54 9997
TEST_TIME 55 10024
TEST_TIME 56 10113
TEST_TIME 57 10003
TEST_TIME 58 9850
TEST_TIME 59 9777
TEST_TIME 60 9690
TEST_TIME 61 9640
TEST_TIME 62 9548
TEST_TIME 63 9510
TEST_TIME 64 9484
TEST_TIME 65 9443
TEST_TIME 66 9442
TEST_TIME 67 9394
TEST_TIME 68 9390
TEST_TIME 69 9420
TEST_TIME 70 9481
TEST_TIME 71 9459
TEST_TIME 72 9453
TEST_TIME 73 9423
TEST_TIME 74 9484
TEST_TIME 75 9615
TEST_TIME 76 9604
TEST_TIME 77 9502
TEST_TIME 78 9434
TEST_TIME 79 9367
TEST_TIME 80 9318
TEST_TIME 81 9279
TEST_TIME 82 9264
TEST_TIME 83 9246
TEST_TIME 84 9229
TEST_TIME 85 9271
TEST_TIME 86 9266
TEST_TIME 87 9221
TEST_TIME 88 9214
TEST_TIME 89 9274
TEST_TIME 90 9377
TEST_TIME 91 9380
TEST_TIME 92 9392
TEST_TIME 93 9379
TEST_TIME 94 9326
TEST_TIME 95 9316
TEST_TIME 96 9298
TEST_TIME 97 9298
TEST_TIME 98 9270
TEST_TIME 99 9259
TEST_TIME 100 9267
TEST_TIME 101 9256
TEST_TIME 102 9264
TEST_TIME 103 9238
TEST_TIME 104 9299
TEST_TIME 105 9338
TEST_TIME 106 9392
TEST_TIME 107 9343
TEST_TIME 108 9266
TEST_TIME 109 9209
TEST_TIME 110 9167
TEST_TIME 111 9175
TEST_TIME 112 9181
TEST_TIME 113 9129
TEST_TIME 114 9124
TEST_TIME 115 9113
TEST_TIME 116 9088
TEST_TIME 117 9085
TEST_TIME 118 9095
TEST_TIME 119 9096
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-10-2020 07:36:16 to 22-10-2020 07:36:20.

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 1511010 1511010 0 0.00 0 20231 6 0 0
doc_list_biud 1511010 1511010 0 0.00 0 53679 34 0 0
sp_add_invoice_to_stock 5111 4976 135 2.64 45 224898 17573 0 135
sp_cancel_adding_invoice 5579 5579 0 0.00 5 53332 894 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 7998 337 0 0
sp_cancel_customer_reserve 541195 541195 0 0.00 1 53683 76 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 5923 87 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 4827 82 0 0
sp_cancel_supplier_invoice 6145 6134 11 0.17 2 21258 997 0 11
sp_cancel_supplier_order 411 411 0 0.00 8 1318256 125556 0 0
sp_cancel_write_off 9790 9790 0 0.00 1 10047 131 0 0
sp_client_order 20605 20605 0 0.00 4 22341 418 0 0
sp_customer_reserve 458718 458718 0 0.00 0 111076 173 0 0
sp_fill_shopping_cart 31509 31509 0 0.00 1 16832 42 0 0
sp_fill_shopping_cart_clo_res 458709 458709 0 0.00 0 28150 16 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 49909 390 0 0
sp_kill_cost_storno 16441 16441 0 0.00 0 6491 57 0 0
sp_kill_qstorno_ret_qs2qd 1098725 1098725 0 0.00 0 53643 41 0 0
sp_kill_qty_storno 579800 579800 0 0.00 0 53678 86 0 0
sp_lock_selected_doc 20760 20760 0 0.00 0 8687 5 0 0
sp_make_cost_storno 27886 27886 0 0.00 0 11499 49 0 0
sp_multiply_rows_for_pdistr 27886 27886 0 0.00 0 7332 30 0 0
sp_multiply_rows_for_qdistr 20605 20605 0 0.00 0 22333 226 0 0
sp_pay_from_customer 7180 7180 0 0.00 4 13591 107 0 0
sp_pay_to_supplier 5763 5763 0 0.00 5 12913 119 0 0
sp_payment_common 12943 12943 0 0.00 1 12899 18 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 7992 291 0 0
sp_qd_handle_on_invoice_upd_sts 10546 10546 0 0.00 0 28316 353 0 0
sp_qd_handle_on_reserve_upd_sts 9976 9976 0 0.00 0 12199 44 0 0
sp_reserve_write_off 9976 9976 0 0.00 7 12213 162 0 0
sp_supplier_invoice 5497 5497 0 0.00 8 137663 3577 0 0
sp_supplier_order 5423 5423 0 0.00 5 99117 1457 0 0
srv_aggregate_perf_data 99 99 0 0.00 2638 446502 21570 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1363 236402 45930 0 0
srv_find_qd_qs_mism 1038405 1038405 0 0.00 0 20230 5 0 0
srv_make_invnt_saldo 485 485 0 0.00 39 105090 3399 0 0
srv_make_money_saldo 706 706 0 0.00 1 12377 615 0 0
srv_recalc_idx_stat 4 4 0 0.00 84261 411670 244624 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4977 4977 0 0.00 0 8051 147 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 0 1613 62 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 1901 10 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 5008 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 395 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1042 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 630 11 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 16721 1503 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 2906 8 0 0
t$perf-norm:sp_client_order 20605 20605 0 0.00 0 22271 20 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 5255 8 0 0
t$perf-norm:sp_pay_from_customer 7180 7180 0 0.00 0 16244 13 0 0
t$perf-norm:sp_pay_to_supplier 5763 5763 0 0.00 0 14362 11 0 0
t$perf-norm:sp_reserve_write_off 9976 9976 0 0.00 0 10335 13 0 0
t$perf-norm:sp_supplier_invoice 5497 5497 0 0.00 0 6334 16 0 0
t$perf-norm:sp_supplier_order 5423 5423 0 0.00 0 1350 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 555 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 395 13 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 3602 14 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 1084 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10198 9928 270 2.64 0 4528 17 0 270
v_all_customers 37730 37730 0 0.00 0 54 1 0 0
v_all_suppliers 30292 30292 0 0.00 0 193 1 0 0
v_all_wares 41208 41208 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 3632 12 0 0
v_cancel_client_order 3496 3496 0 0.00 0 6777 13 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 5138 14 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 367 3 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4807 10 0 0
v_cancel_supplier_invoice 810 788 22 2.71 0 3252 17 0 22
v_cancel_supplier_order 822 822 0 0.00 0 5549 14 0 0
v_cancel_write_off 2078 2078 0 0.00 0 5058 17 0 0
v_max_id_clo_ord 5416 5416 0 0.00 0 934 2 0 0
v_max_id_clo_res 957 957 0 0.00 0 1821 16 0 0
v_max_id_ord_sup 5487 5487 0 0.00 0 8866 8 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 1904 3 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 1029 2 0 0
v_min_id_clo_ord 5416 5416 0 0.00 0 2995 7 0 0
v_min_id_clo_res 957 957 0 0.00 0 2022 4 0 0
v_min_id_ord_sup 5487 5487 0 0.00 0 8867 9 0 0
v_min_non_paid_invoice 5763 5763 0 0.00 0 12896 11 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 9716 9 0 0
v_our_firm 10278 10278 0 0.00 0 18 1 0 0
v_reserve_write_off 19952 19952 0 0.00 0 6697 7 0 0
x_lock_depdocs_on_canc_invoice 11319 11319 0 0.00 0 10289 113 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3229 300 0 0
x_make_qstorno_1000_1200 5423 5423 0 0.00 2 99079 1376 0 0
x_make_qstorno_1200_2000 5497 5497 0 0.00 3 137451 3418 0 0
x_make_qstorno_2100_3300 458718 458718 0 0.00 0 107734 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2020 07:36:21 to 22-10-2020 07:36:25.

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
05:05:27 1 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
05:06:28 27633 8192000000 9717738624 10011889664 795874624 8.854 100 89 178 0 825751056 8113088 21763344
05:08:02 11833 8192000000 9758825344 10064093184 830373312 9.203 100 84 167 0 862225584 12162976 19689296
05:09:19 30723 8192000000 9812853568 10131607552 872061504 9.621 100 92 184 1 913587696 20645632 20880560
05:10:54 15321 8192000000 9873182272 10205601792 909145120 9.989 100 80 160 0 976725696 50254928 17325648
05:12:16 13924 8192000000 9918261024 10259165184 950032944 10.391 100 82 164 2 1027115184 60042368 17039872
05:13:32 4684 8192000000 9959052736 10312171520 986012928 10.743 100 85 168 0 1075973008 72342976 17617104
05:14:39 2892 8192000000 9997382992 10355765248 1021034464 11.082 100 89 177 3 1111686544 73304352 17347728
05:15:43 1626 8192000000 10014845184 10379997184 1041440960 11.279 100 91 182 4 1128179392 67773920 18964512
05:16:46 3813 8192000000 10018505024 10406383616 1058895440 11.446 100 55 110 0 1143552912 74074880 10582592
05:17:52 10933 8192000000 9979498272 10362015744 1061573312 11.472 100 62 123 1 1089312848 14407456 13332080
05:19:03 8462 8192000000 9986755120 10387238912 1071328704 11.565 100 29 58 5 1109353024 30941888 7082432
05:20:15 5938 8192000000 9987742864 10384265216 1080262736 11.650 100 40 78 1 1102786672 13480128 9043808
05:21:24 12003 8192000000 10000069696 10404540416 1090709216 11.749 100 24 46 0 1110870432 14317712 5843504
05:22:37 72940 8192000000 9999552096 10415661056 1071354496 11.565 98 39 78 2 1090846192 10758144 8733552
05:24:52 8318 8192000000 9994993424 10405150720 1072848576 11.579 98 37 75 2 1095717920 13975008 8894336
05:26:02 6146 8192000000 9991248912 10382241792 1069913088 11.551 99 43 85 2 1097290352 18331216 9046048
05:27:11 4687 8192000000 9971707440 10359742464 1048632128 11.348 98 40 80 2 1070941584 13764176 8545280
05:28:18 34887 8192000000 9929548944 10317131776 925763152 10.153 88 18 34 1 958935184 29797856 3374176
05:29:54 5001 8192000000 9941010944 10309394432 1002104160 10.899 98 54 105 5 1042389824 27848720 12436944
05:31:01 5165 8192000000 9938834624 10324406272 1008702768 10.963 98 45 90 4 1034372848 15737696 9932384
05:32:08 5043 8192000000 9969131584 10351894528 1053722688 11.396 100 45 89 3 1076613536 12236928 10653920
05:33:17 20504 8192000000 9977801568 10380152832 1058734064 11.444 100 21 42 2 1087895472 25196432 3964976
05:34:42 6108 8192000000 9987498336 10385063936 1068291104 11.536 100 49 98 3 1095838448 16484432 11062912
05:35:56 8232 8192000000 10037647760 10427854848 1095470288 11.795 100 60 120 2 1139103712 32435520 11197904
05:37:11 50038 8192000000 10063522960 10464296960 1104956816 11.885 100 47 94 3 1173720496 58502816 10260864
05:39:08 13219 8192000000 10079570544 10479206400 1119134288 12.019 100 45 89 1 1187498704 58987760 9376656
05:40:27 12182 8192000000 10131954848 10534862848 1139778912 12.213 100 54 108 0 1228963632 77500832 11683888
05:41:43 16678 8192000000 10155302864 10570006528 1150228704 12.312 100 43 86 0 1264095376 104987296 8879376
05:43:03 10089 8192000000 10187310624 10596859904 1161299968 12.415 100 54 108 1 1293126880 120025712 11801200
05:44:19 3230 8192000000 10214437600 10619875328 1154774224 12.354 99 65 130 2 1309873760 141127968 13971568
05:45:27 2636 8192000000 10265567168 10672173056 1164377680 12.444 100 65 126 0 1370597744 192180080 14039984
05:46:34 2576 8192000000 10262604496 10662682624 1164657952 12.447 100 66 125 3 1363782336 184835184 14289200
05:47:47 4052 8192000000 10317323792 10715549696 1169466048 12.492 99 78 155 4 1413586528 226687040 17433440
05:48:58 4442 8192000000 10339321632 10738847744 1174754544 12.541 99 88 170 3 1429266624 235832320 18679760
05:50:14 2635 8192000000 10390392032 10797805568 1174611776 12.540 99 73 144 1 1484423376 293139008 16672592
05:51:27 2691 8192000000 10407250928 10805293056 1171249824 12.509 99 84 168 3 1506435248 315758416 19427008
05:52:34 5136 8192000000 10428407072 10838073344 1183876400 12.626 100 73 146 0 1525897216 324479952 17540864
05:53:45 1698 8192000000 10460558336 10859646976 1181711536 12.606 100 96 191 2 1556881952 352482608 22687808
05:54:50 2654 8192000000 10496156624 10903048192 1181091712 12.600 100 81 161 0 1594606416 393644144 19870560
05:56:00 10589 8192000000 10440439536 10885468160 1123730256 12.062 97 47 94 1 1554180560 418361936 12088368
05:57:21 1361 8192000000 10490682752 10891694080 1139636080 12.212 99 73 146 0 1589991472 432685856 17669536
05:58:25 6944 8192000000 10524028880 10950508544 1135211184 12.170 99 48 94 0 1627090480 478924048 12955248
05:59:43 11784 8192000000 10450208096 10893389824 1139144448 12.207 100 36 72 1 1553089872 404381264 9564160
06:00:59 17201 8192000000 10426618096 10887548928 1113701216 11.967 99 27 54 0 1521841168 400352496 7787456
06:02:19 22819 8192000000 10332700448 10851360768 1098593088 11.824 99 23 46 2 1472912400 367866480 6452832
06:03:53 56396 8192000000 10040334144 10494607360 1093606080 11.777 99 14 29 0 1171690080 74505776 3578224
06:05:49 44022 8192000000 10073326912 10496151552 1077419792 11.623 96 20 41 1 1109935616 28718640 3797184
06:07:36 39876 8192000000 10105005504 10547871744 1106894640 11.903 99 40 75 2 1197528832 81771088 8863104
06:09:18 236402 8192000000 10070388064 10519683072 1062553008 11.481 96 32 64 3 1131921312 62460864 6907440
06:14:18 66365 8192000000 10049191440 10493292544 1116874864 11.997 99 19 38 1 1161061728 40593088 3593776
06:16:27 38184 8192000000 10072929632 10515533824 1139102976 12.207 100 9 18 0 1183564384 42507216 1954192
06:18:35 45329 8192000000 10168844256 10624573440 1153317360 12.341 100 18 35 2 1269439520 111480752 4641408
06:20:37 74674 8192000000 10233828192 10678784000 1142971968 12.243 99 15 30 0 1284402048 137728304 3701776
06:22:56 66273 8192000000 10085149456 10568491008 1117838512 12.007 98 14 28 0 1181497824 60917280 2742032
06:25:03 42680 8192000000 10080569968 10560106496 1133929488 12.158 99 25 50 1 1195227312 56018832 5278992
06:26:56 43591 8192000000 10091450288 10519871488 1130524192 12.126 98 30 59 3 1170910240 34085392 6300656
06:28:41 32364 8192000000 10143249360 10623680512 1134967552 12.168 99 31 62 2 1255288944 113788544 6532848
06:30:16 88489 8192000000 10121503008 10590564352 1135471696 12.173 99 12 24 0 1188749440 50800416 2477328
06:33:01 120017 8192000000 10083540896 10549223424 1126329904 12.087 98 21 42 0 1162598992 31564768 4704320
06:36:02 42277 8192000000 10087162176 10563354624 1128198304 12.104 99 8 16 0 1183034944 53175488 1661152
06:38:24 56597 8192000000 10043768912 10494844928 1119760688 12.025 98 11 22 1 1149317376 27572144 1984544
06:40:21 31817 8192000000 10035074352 10461597696 1093207792 11.773 97 27 54 0 1126329136 28142688 4978656
06:42:02 40711 8192000000 10087556992 10509787136 1129941024 12.121 100 37 74 1 1186029056 47758384 8329648
06:43:52 88194 8192000000 10078953040 10520707072 1103518528 11.871 97 24 48 2 1166973488 58121536 5333424
06:46:21 89729 8192000000 10079389632 10526470144 1058431312 11.441 94 19 38 0 1094430368 32337664 3661392
06:48:53 47691 8192000000 10095153616 10572636160 1071017232 11.562 95 31 62 2 1158522976 81282704 6223040
06:50:46 68700 8192000000 10051467120 10518994944 1024976384 11.120 92 21 40 0 1083470128 54373264 4120480
06:53:02 96846 8192000000 10055998416 10531418112 1111431808 11.946 100 14 28 0 1158386192 44079408 2874976
06:55:51 39528 8192000000 10067715440 10538901504 1096415888 11.804 98 14 28 1 1168127856 68653472 3058496
06:57:56 54511 8192000000 10080072304 10521812992 1101935168 11.856 98 19 37 3 1175112112 69401408 3775536
06:59:54 60460 8192000000 10118703424 10553200640 1113671824 11.967 99 35 70 1 1203187888 81702256 7813808
07:01:57 42162 8192000000 10113856256 10541682688 1103611344 11.872 98 17 34 0 1185425296 77961136 3852816
07:03:39 53741 8192000000 10147467088 10608119808 1121145712 12.038 99 20 40 3 1251309408 125640768 4522928
07:05:35 64233 8192000000 10059212640 10492833792 1099183936 11.830 97 12 24 1 1134500496 32973248 2343312
07:07:42 82058 8192000000 10071758672 10521075712 1113166336 11.962 98 18 36 0 1165046416 48326144 3553936
07:10:05 65880 8192000000 10066037936 10539823104 1128585280 12.108 100 5 10 0 1164164256 34947296 631680
07:12:22 26979 8192000000 10099801632 10545815552 1136764976 12.185 100 30 58 2 1184769504 41753296 6251232
07:13:56 52821 8192000000 10098566720 10546802688 1130868640 12.130 99 29 59 3 1182760848 45372496 6519712
07:15:57 42057 8192000000 10111751840 10554351616 1131604928 12.136 99 32 64 2 1191146336 52410256 7131152
07:17:41 43627 8192000000 10113468512 10540212224 1130207904 12.123 98 27 54 5 1180839568 44948928 5682736
07:19:26 120230 8192000000 10112287600 10589433856 1090830368 11.751 95 25 50 0 1150752000 54795168 5126464
07:22:26 125378 8192000000 10127218592 10572238848 1131022144 12.131 98 19 38 1 1198122112 63381136 3718832
07:25:49 84076 8192000000 10082523264 10610180096 1130052768 12.122 99 10 20 2 1221084816 88742592 2289456
07:28:21 52428 8192000000 10062948960 10514792448 1123657984 12.062 99 20 40 1 1166325712 38163552 4504176
07:30:17 68724 8192000000 10059552656 10521092096 1079817568 11.646 95 17 36 0 1111215600 28073552 3324480
07:32:28 57728 8192000000 10066213440 10522087424 1099802384 11.836 98 16 32 0 1137348832 34150400 3396048
07:34:31 32747 8192000000 10084937296 10504171520 1090505232 11.747 97 26 52 0 1126074496 30186688 5382576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2020 07:36:27 to 22-10-2020 07:36:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 135 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 22-10-2020 07:36:43 to 22-10-2020 07:36:45.

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          235379
MON$OLDEST_ACTIVE               237104
MON$OLDEST_SNAPSHOT             237104
MON$NEXT_TRANSACTION            237104
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                       2112704
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             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2232 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2232 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2232 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.2232 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Oct 22 07:35:27 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

22.10.20 07:36:46. 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-22 07:36:48 No severe PSQL-related problems occured
22.10.20 07:36:50. End of report.