Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.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 16852180 kB
MemAvailable 31503944 kB
Buffers 20 kB
Cached 14730028 kB
SwapCached 5436 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-20 04:26:53
DTS_END                         2020-10-20 04:26:53.0810
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/54978
TRN_ID                          234303
ATT_ID                          588
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: 9199.52 25801 1103942
customer order: creation 166.49 395 19979
customer order: refuse 14.17 302 1700
order to supplier: creation 44.69 1230 5363
order to supplier: removal 3.51 117477 421
invoice (draft): creation 43.92 3376 5270
invoice (draft): removal 52.54 924 6305
invoice accept: apply 41.48 17334 4977
invoice accept: cancel 47.80 914 5736
customer reserve: creation 3822.95 170 458754
customer reserve: removal 4672.02 71 560642
realization accept: apply 82.64 156 9917
realization accept: cancel 80.91 119 9709
payment from customer: creation 58.55 89 7026
payment from customer: removal 5.71 72 685
payment to supplier: creation 48.13 120 5775
payment to supplier: removal 3.17 76 380
service: total inventory turnovers 3.93 3158 471
service: total monetary turnovers 6.08 430 729
service: aggregate perf. data 0.83 17200 99
service: refresh index statistics 0.03 352405 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 04:27:34 to 20-10-2020 04:27:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1119
TEST_TIME 2 2390
TEST_TIME 3 3244
TEST_TIME 4 3760
TEST_TIME 5 3730
TEST_TIME 6 4485
TEST_TIME 7 4644
TEST_TIME 8 4623
TEST_TIME 9 4862
TEST_TIME 10 4828
TEST_TIME 11 4922
TEST_TIME 12 5262
TEST_TIME 13 5712
TEST_TIME 14 6118
TEST_TIME 15 6495
TEST_TIME 16 7085
TEST_TIME 17 8020
TEST_TIME 18 7834
TEST_TIME 19 7891
TEST_TIME 20 8416
TEST_TIME 21 8595
TEST_TIME 22 8757
TEST_TIME 23 9003
TEST_TIME 24 10167
TEST_TIME 25 11634
TEST_TIME 26 11455
TEST_TIME 27 11813
TEST_TIME 28 11616
TEST_TIME 29 11536
TEST_TIME 30 11434
TEST_TIME 31 11126
TEST_TIME 32 11010
TEST_TIME 33 10819
TEST_TIME 34 10678
TEST_TIME 35 10526
TEST_TIME 36 10432
TEST_TIME 37 10440
TEST_TIME 38 10538
TEST_TIME 39 10374
TEST_TIME 40 10235
TEST_TIME 41 10120
TEST_TIME 42 10242
TEST_TIME 43 10273
TEST_TIME 44 10439
TEST_TIME 45 10300
TEST_TIME 46 10384
TEST_TIME 47 10463
TEST_TIME 48 10442
TEST_TIME 49 10398
TEST_TIME 50 10475
TEST_TIME 51 10502
TEST_TIME 52 10350
TEST_TIME 53 10172
TEST_TIME 54 10105
TEST_TIME 55 10035
TEST_TIME 56 9922
TEST_TIME 57 9870
TEST_TIME 58 9749
TEST_TIME 59 9698
TEST_TIME 60 9736
TEST_TIME 61 9774
TEST_TIME 62 9749
TEST_TIME 63 9686
TEST_TIME 64 9648
TEST_TIME 65 9888
TEST_TIME 66 9941
TEST_TIME 67 9890
TEST_TIME 68 9916
TEST_TIME 69 9851
TEST_TIME 70 9807
TEST_TIME 71 9872
TEST_TIME 72 9795
TEST_TIME 73 9755
TEST_TIME 74 9655
TEST_TIME 75 9607
TEST_TIME 76 9514
TEST_TIME 77 9464
TEST_TIME 78 9472
TEST_TIME 79 9405
TEST_TIME 80 9434
TEST_TIME 81 9397
TEST_TIME 82 9392
TEST_TIME 83 9452
TEST_TIME 84 9403
TEST_TIME 85 9395
TEST_TIME 86 9357
TEST_TIME 87 9438
TEST_TIME 88 9459
TEST_TIME 89 9520
TEST_TIME 90 9451
TEST_TIME 91 9359
TEST_TIME 92 9353
TEST_TIME 93 9342
TEST_TIME 94 9338
TEST_TIME 95 9299
TEST_TIME 96 9355
TEST_TIME 97 9341
TEST_TIME 98 9333
TEST_TIME 99 9367
TEST_TIME 100 9363
TEST_TIME 101 9363
TEST_TIME 102 9377
TEST_TIME 103 9387
TEST_TIME 104 9443
TEST_TIME 105 9455
TEST_TIME 106 9431
TEST_TIME 107 9348
TEST_TIME 108 9281
TEST_TIME 109 9247
TEST_TIME 110 9232
TEST_TIME 111 9214
TEST_TIME 112 9196
TEST_TIME 113 9215
TEST_TIME 114 9186
TEST_TIME 115 9210
TEST_TIME 116 9211
TEST_TIME 117 9226
TEST_TIME 118 9241
TEST_TIME 119 9201
TEST_TIME 120 9206

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2020 04:27:38 to 20-10-2020 04:27:41.

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 1527899 1527899 0 0.00 0 17666 7 0 0
doc_list_biud 1527899 1527899 0 0.00 0 88690 32 0 0
sp_add_invoice_to_stock 5140 4977 163 3.17 31 267084 17334 0 163
sp_cancel_adding_invoice 5736 5736 0 0.00 5 96033 915 0 0
sp_cancel_client_order 1700 1700 0 0.00 5 9711 302 0 0
sp_cancel_customer_reserve 560642 560642 0 0.00 1 48239 71 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 6070 72 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 6936 77 0 0
sp_cancel_supplier_invoice 6321 6305 16 0.25 2 88692 924 0 16
sp_cancel_supplier_order 421 421 0 0.00 9 1141235 117478 0 0
sp_cancel_write_off 9709 9709 0 0.00 1 9436 120 0 0
sp_client_order 19979 19979 0 0.00 4 27921 395 0 0
sp_customer_reserve 458754 458754 0 0.00 0 151405 171 0 0
sp_fill_shopping_cart 30599 30599 0 0.00 1 28713 40 0 0
sp_fill_shopping_cart_clo_res 458738 458738 0 0.00 0 22973 17 0 0
sp_get_clo_for_invoice 4961 4961 0 0.00 0 78605 405 0 0
sp_kill_cost_storno 16510 16510 0 0.00 0 7328 58 0 0
sp_kill_qstorno_ret_qs2qd 1137719 1137719 0 0.00 0 88688 38 0 0
sp_kill_qty_storno 599391 599391 0 0.00 0 88688 81 0 0
sp_lock_selected_doc 20686 20686 0 0.00 0 10522 6 0 0
sp_make_cost_storno 27679 27679 0 0.00 0 13253 48 0 0
sp_multiply_rows_for_pdistr 27679 27679 0 0.00 0 6834 26 0 0
sp_multiply_rows_for_qdistr 19979 19979 0 0.00 0 27912 217 0 0
sp_pay_from_customer 7026 7026 0 0.00 4 8975 89 0 0
sp_pay_to_supplier 5775 5775 0 0.00 5 13866 121 0 0
sp_payment_common 12801 12801 0 0.00 1 8959 13 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 8475 267 0 0
sp_qd_handle_on_invoice_upd_sts 10697 10697 0 0.00 0 22665 369 0 0
sp_qd_handle_on_reserve_upd_sts 9917 9917 0 0.00 0 19720 47 0 0
sp_reserve_write_off 9917 9917 0 0.00 6 19765 156 0 0
sp_supplier_invoice 5270 5270 0 0.00 8 128465 3376 0 0
sp_supplier_order 5363 5363 0 0.00 6 43032 1231 0 0
srv_aggregate_perf_data 99 99 0 0.00 2757 117916 17200 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1817 135456 49194 0 0
srv_find_qd_qs_mism 1056726 1056726 0 0.00 0 17665 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 60 103835 3158 0 0
srv_make_money_saldo 729 729 0 0.00 1 17627 430 0 0
srv_recalc_idx_stat 4 4 0 0.00 279844 432039 352406 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 15773 148 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 1 2842 88 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 3628 13 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 1798 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 663 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 843 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 33038 91 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 19801 1452 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1339 6 0 0
t$perf-norm:sp_client_order 19979 19979 0 0.00 0 17902 16 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 3030 9 0 0
t$perf-norm:sp_pay_from_customer 7026 7026 0 0.00 0 12824 9 0 0
t$perf-norm:sp_pay_to_supplier 5775 5775 0 0.00 0 2413 6 0 0
t$perf-norm:sp_reserve_write_off 9917 9917 0 0.00 0 16703 15 0 0
t$perf-norm:sp_supplier_invoice 5270 5270 0 0.00 0 2709 12 0 0
t$perf-norm:sp_supplier_order 5363 5363 0 0.00 0 18268 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 127 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 919 18 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 4154 27 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 1641 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 171 47 0 0
v_add_invoice_to_stock 10242 9916 326 3.18 0 15343 17 0 326
v_all_customers 36946 36946 0 0.00 0 52 1 0 0
v_all_suppliers 29714 29714 0 0.00 0 17 1 0 0
v_all_wares 39958 39958 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 6741 13 0 0
v_cancel_client_order 3400 3400 0 0.00 0 9509 10 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 6063 12 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 566 3 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1289 4 0 0
v_cancel_supplier_invoice 886 854 32 3.61 0 479 13 0 32
v_cancel_supplier_order 842 842 0 0.00 0 295 3 0 0
v_cancel_write_off 2026 2026 0 0.00 0 3202 14 0 0
v_max_id_clo_ord 5361 5361 0 0.00 0 116 1 0 0
v_max_id_clo_res 948 948 0 0.00 0 6555 15 0 0
v_max_id_ord_sup 5259 5259 0 0.00 0 11031 9 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 922 2 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 3339 3 0 0
v_min_id_clo_ord 5361 5361 0 0.00 0 3323 7 0 0
v_min_id_clo_res 948 948 0 0.00 0 4926 18 0 0
v_min_id_ord_sup 5259 5259 0 0.00 0 22612 13 0 0
v_min_non_paid_invoice 5775 5775 0 0.00 0 2718 7 0 0
v_min_non_paid_realizn 7026 7026 0 0.00 0 8951 8 0 0
v_our_firm 9742 9742 0 0.00 0 16 1 0 0
v_reserve_write_off 19834 19834 0 0.00 0 7903 5 0 0
x_lock_depdocs_on_canc_invoice 11614 11614 0 0.00 0 11898 104 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 50421 505 0 0
x_make_qstorno_1000_1200 5363 5363 0 0.00 2 43023 1164 0 0
x_make_qstorno_1200_2000 5270 5270 0 0.00 2 125726 3211 0 0
x_make_qstorno_2100_3300 458754 458754 0 0.00 0 151403 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 04:27:42 to 20-10-2020 04:27:46.

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:56 9 8192000000 8799180224 8810516480 5199328 0.063 2 1 2 0 5298480 63296 35856
01:57:57 32961 8192000000 9737820560 10039484416 811392832 9.012 100 91 182 0 841843408 8340304 22110272
01:59:42 22641 8192000000 9780982048 10091634688 848561392 9.386 100 87 174 1 883657328 14698416 20397520
02:01:11 14961 8192000000 9827574304 10154450944 885674960 9.756 100 76 151 1 932219792 29884080 16660752
02:02:33 10449 8192000000 9887658992 10223857664 933114336 10.225 100 79 159 1 996284688 46316176 16854176
02:03:50 7497 8192000000 9944819008 10289709056 958757472 10.477 100 82 164 1 1055420176 79774016 16888688
02:05:02 3542 8192000000 9978904448 10335256576 1000646080 10.885 100 86 172 1 1090420784 71985808 17788896
02:06:07 2377 8192000000 10015465264 10384224256 1028330720 11.152 100 86 172 1 1129924768 84633392 16960656
02:07:11 1177 8192000000 10023577360 10393759744 1057602000 11.434 100 90 177 0 1135405920 58658272 19145648
02:08:13 1342 8192000000 10034143440 10412531712 1074677296 11.597 100 85 169 0 1146308816 54871536 16759984
02:09:15 10290 8192000000 9985696224 10387296256 1075403296 11.604 100 39 77 1 1109047296 24957456 8686544
02:10:25 7960 8192000000 9989451472 10389286912 1081933824 11.666 100 33 64 3 1100713520 12124960 6654736
02:11:36 7553 8192000000 9997256736 10397736960 1089373120 11.737 100 30 60 1 1112625536 16513200 6739216
02:12:45 31156 8192000000 10006170928 10404872192 1091724560 11.759 100 42 83 4 1115161584 14414256 9022768
02:14:17 6532 8192000000 10013865632 10415001600 1097508368 11.814 100 30 58 2 1130270848 26847344 5915136
02:15:24 8976 8192000000 10014425760 10421284864 1084077360 11.686 99 31 62 1 1115443344 24201136 7164848
02:16:34 13658 8192000000 9980212512 10378452992 1059323568 11.450 97 29 58 1 1080274272 15353280 5597424
02:17:49 17255 8192000000 9964718912 10369609728 1032921504 11.197 96 14 28 1 1060344224 24179248 3243472
02:19:06 7235 8192000000 9960959696 10339721216 1005430976 10.931 96 36 72 2 1038996848 24897696 8668176
02:20:15 5366 8192000000 9928415568 10302210048 1011393888 10.989 100 52 102 2 1044387648 22558000 10435760
02:21:23 61221 8192000000 9936636672 10318753792 995293008 10.833 96 32 65 2 1014167664 11867632 7007024
02:23:24 5708 8192000000 9969414464 10349297664 1046601392 11.328 100 48 94 2 1073496688 16893056 10002240
02:24:30 7767 8192000000 9975739520 10381266944 1064512304 11.500 100 40 80 0 1100708480 26836576 9359600
02:25:38 9819 8192000000 9992406144 10394591232 1073315504 11.584 100 26 52 0 1098794592 19546032 5933056
02:26:48 120255 8192000000 10043178800 10444058624 1102235168 11.859 100 39 78 0 1146885712 36384240 8266304
02:29:58 35482 8192000000 10098335152 10510819328 1129589472 12.117 100 32 64 5 1205973808 69444480 6939856
02:31:38 13354 8192000000 10182586176 10592923648 1150788304 12.317 100 47 94 3 1283771968 122687728 10295936
02:33:03 12099 8192000000 10209643008 10619080704 1166900592 12.468 100 50 100 0 1312018112 134533152 10584368
02:34:22 6284 8192000000 10250703648 10654928896 1175967760 12.553 100 62 123 2 1349148048 159423440 13756848
02:35:36 9338 8192000000 10313929008 10728869888 1183385424 12.622 100 52 104 1 1419337216 224084512 11867280
02:36:50 6304 8192000000 10359826064 10769694720 1196971824 12.748 100 70 140 1 1462465088 249363008 16130256
02:38:00 1815 8192000000 10418457408 10825703424 1196793520 12.747 100 88 174 0 1517267472 300691152 19782800
02:39:07 2591 8192000000 10443686352 10867941376 1193479904 12.716 100 65 125 1 1547456144 338605536 15370704
02:40:13 4832 8192000000 10505139712 10921672704 1199275136 12.770 100 77 150 4 1601772768 384976752 17520880
02:41:29 4234 8192000000 10496905376 10919006208 1193886656 12.720 100 83 168 1 1610227968 397115840 19225472
02:42:41 4640 8192000000 10477405632 10899337216 1188939888 12.673 99 74 148 0 1576502064 369569232 17992944
02:43:47 19369 8192000000 10393884608 10833911808 1161598400 12.418 98 35 70 0 1495839568 325365744 8875424
02:45:14 7967 8192000000 10494979696 10927427584 1170541328 12.502 99 45 89 0 1588026960 406124176 11361456
02:46:33 12672 8192000000 10483733632 10908827648 1156903744 12.374 99 33 66 0 1566293168 400722384 8667040
02:47:51 14937 8192000000 10505968448 10939899904 1150442960 12.314 100 32 64 1 1612761664 453459440 8859264
02:49:07 13838 8192000000 10499565808 10948493312 1113798704 11.968 99 27 54 1 1607593552 485787040 8007808
02:50:59 7522 8192000000 10289446288 10786877440 1102530000 11.862 99 31 62 0 1434899136 324760560 7608576
02:52:12 36733 8192000000 10126857680 10549977088 1125214176 12.076 100 28 55 1 1208388784 77557376 5617232
02:53:49 69707 8192000000 10033137824 10453954560 1035628688 11.223 92 26 48 2 1066398400 25997552 4772160
02:56:06 18082 8192000000 10055946080 10472435712 1114924656 11.979 99 57 106 1 1142472032 16792592 10754784
02:57:26 105811 8192000000 10069343424 10536894464 1121793696 12.044 100 25 50 2 1190782080 63532336 5456048
03:00:26 123839 8192000000 10120381264 10545729536 1090176880 11.744 95 43 85 4 1183739104 84099056 9463168
03:03:31 31154 8192000000 10145038096 10614263808 1132901344 12.149 100 26 52 0 1242201552 103580208 5720000
03:05:11 51874 8192000000 10109087664 10551750656 1122879168 12.054 99 11 22 1 1204101104 78900800 2321136
03:07:03 50091 8192000000 10146982768 10585800704 1120148560 12.028 98 15 30 1 1214893232 91197712 3546960
03:08:55 24127 8192000000 10158121504 10585051136 1148185840 12.292 100 40 80 3 1251246400 94361552 8699008
03:10:24 39662 8192000000 10052727376 10498707456 1114977056 11.980 99 28 56 0 1146041104 24628672 6435376
03:12:04 77878 8192000000 10101769808 10576384000 1103287104 11.869 97 13 26 2 1168430544 62590368 2553072
03:14:23 49374 8192000000 10092809152 10563231744 1126130000 12.085 99 24 48 2 1189173984 58178928 4865056
03:16:20 50336 8192000000 10077937104 10521538560 1109457776 11.927 97 10 21 0 1145963248 34652576 1852896
03:18:10 102726 8192000000 10061057088 10501988352 1125168928 12.076 99 18 36 3 1150553792 21810848 3574016
03:21:55 73894 8192000000 10096821248 10552246272 1134299488 12.162 99 9 17 0 1167704000 31666976 1737536
03:24:10 45188 8192000000 10107223584 10559696896 1122105424 12.047 98 17 33 1 1190357040 64122880 4128736
03:26:03 53695 8192000000 10088676112 10567098368 1114149040 11.972 98 14 27 0 1186814400 69913968 2751392
03:27:57 39291 8192000000 10097519168 10543120384 1127626992 12.099 99 17 34 2 1174775568 43294656 3853920
03:29:37 30032 8192000000 10162656592 10593124352 1114705008 11.977 97 28 56 0 1216641456 95247584 6688864
03:31:40 55716 8192000000 10084977056 10546253824 1129519664 12.117 100 12 24 1 1230206336 97935504 2751168
03:33:37 36245 8192000000 10105672512 10528620544 1102169312 11.858 98 41 82 1 1158556720 48318544 8068864
03:35:21 54363 8192000000 10078460144 10500722688 1078578592 11.634 96 35 70 1 1139020784 53662400 6779792
03:37:20 70551 8192000000 10046420448 10485874688 1080335776 11.651 96 19 38 3 1113763104 29660656 3766672
03:39:33 78383 8192000000 10055714032 10486960128 1103842960 11.874 99 37 72 2 1153330016 41843184 7643872
03:42:25 78923 8192000000 10066129184 10511908864 1081539520 11.662 97 10 20 0 1145953888 62318704 2095664
03:44:53 79037 8192000000 10050672320 10515722240 1108419520 11.917 100 7 14 0 1179189104 69300448 1469136
03:47:14 42379 8192000000 10057228176 10491920384 1086130960 11.706 97 23 44 3 1133151552 41807504 5213088
03:48:58 90078 8192000000 10046799536 10498125824 1075141568 11.601 97 24 48 3 1133087616 53403088 4542960
03:51:32 41460 8192000000 10109881968 10533027840 1104364528 11.879 99 31 62 1 1183101184 72364096 6372560
03:53:17 82380 8192000000 10112436976 10580131840 1116732592 11.996 99 27 54 0 1201784320 79219376 5832352
03:56:33 135456 8192000000 10083751392 10564182016 1128469312 12.107 100 23 46 2 1201488208 68573248 4445648
03:59:49 58912 8192000000 10066665664 10531078144 1138951200 12.206 100 15 28 2 1165644592 24222480 2470912
04:01:50 53297 8192000000 10116262464 10614034432 1123243184 12.058 98 15 30 3 1218971328 92462640 3265504
04:04:03 117132 8192000000 10092173872 10578718720 1130997264 12.131 99 17 33 0 1191806880 57759984 3049632
04:07:01 77265 8192000000 10098734480 10539520000 1085244976 11.697 96 13 26 0 1126880208 38949152 2686080
04:09:19 17592 8192000000 10075880704 10508464128 1125568816 12.080 100 25 50 2 1161565280 31096432 4900032
04:10:37 80124 8192000000 10091842864 10582159360 1112733040 11.958 98 10 20 0 1183932432 68718992 2480400
04:12:58 127627 8192000000 10103492800 10551173120 1131555440 12.136 99 14 27 2 1189298064 54560496 3182128
04:16:10 28000 8192000000 10077470256 10562244608 1135287280 12.171 100 4 8 0 1165854992 29802032 765680
04:18:29 49489 8192000000 10082651056 10516119552 1096889024 11.808 97 27 54 1 1152361376 50304224 5168128
04:20:19 74466 8192000000 10088105056 10566553600 1093176096 11.773 97 25 51 1 1174044592 75658224 5210272
04:22:53 65256 8192000000 10061688624 10499407872 1084754960 11.693 96 14 28 0 1122983712 35396384 2832368
04:25:05 35612 8192000000 10091571136 10503720960 1086657072 11.711 96 26 52 0 1115451168 23491856 5302240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-10-2020 04:27:48 to 20-10-2020 04:28:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 163 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2020 04:28:03 to 20-10-2020 04:28:05.

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          233350
MON$OLDEST_ACTIVE               236209
MON$OLDEST_SNAPSHOT             236209
MON$NEXT_TRANSACTION            236209
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                       2097472
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2228 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.2228 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Tue Oct 20 04:26:53 2020
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Tue Oct 20 04:26:59 2020
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
--- end of diff output ---

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

20.10.20 04:28:06. 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-20 04:28:08 No severe PSQL-related problems occured
20.10.20 04:28:09. End of report.