Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.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 17051796 kB
MemAvailable 31461024 kB
Buffers 20 kB
Cached 14552128 kB
SwapCached 3064 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-29 04:26:52
DTS_END                         2020-10-29 04:26:52.8690
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/51084
TRN_ID                          229177
ATT_ID                          602
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: 8644.98 25670 1037397
customer order: creation 165.08 444 19810
customer order: refuse 13.69 324 1643
order to supplier: creation 43.75 1374 5250
order to supplier: removal 3.25 124853 390
invoice (draft): creation 43.84 3731 5261
invoice (draft): removal 48.24 1020 5789
invoice accept: apply 39.46 17662 4735
invoice accept: cancel 43.81 941 5257
customer reserve: creation 3646.23 172 437547
customer reserve: removal 4321.94 76 518633
realization accept: apply 78.88 177 9466
realization accept: cancel 74.53 118 8944
payment from customer: creation 56.28 114 6754
payment from customer: removal 5.44 74 653
payment to supplier: creation 46.39 123 5567
payment to supplier: removal 3.37 88 404
service: total inventory turnovers 3.93 3444 471
service: total monetary turnovers 5.87 614 704
service: aggregate perf. data 0.96 15320 115
service: refresh index statistics 0.03 342725 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2020 04:27:54 to 29-10-2020 04:27:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3257
TEST_TIME 2 3056
TEST_TIME 3 3884
TEST_TIME 4 4331
TEST_TIME 5 4707
TEST_TIME 6 4837
TEST_TIME 7 4549
TEST_TIME 8 4963
TEST_TIME 9 5151
TEST_TIME 10 5212
TEST_TIME 11 5330
TEST_TIME 12 5591
TEST_TIME 13 5981
TEST_TIME 14 6150
TEST_TIME 15 6359
TEST_TIME 16 6716
TEST_TIME 17 6733
TEST_TIME 18 7056
TEST_TIME 19 7073
TEST_TIME 20 7921
TEST_TIME 21 8025
TEST_TIME 22 8266
TEST_TIME 23 8402
TEST_TIME 24 8255
TEST_TIME 25 8442
TEST_TIME 26 9074
TEST_TIME 27 9756
TEST_TIME 28 11103
TEST_TIME 29 10794
TEST_TIME 30 10519
TEST_TIME 31 10451
TEST_TIME 32 10238
TEST_TIME 33 10068
TEST_TIME 34 9864
TEST_TIME 35 9947
TEST_TIME 36 9811
TEST_TIME 37 9710
TEST_TIME 38 9589
TEST_TIME 39 9480
TEST_TIME 40 9306
TEST_TIME 41 9210
TEST_TIME 42 9191
TEST_TIME 43 9027
TEST_TIME 44 9078
TEST_TIME 45 8969
TEST_TIME 46 9002
TEST_TIME 47 9072
TEST_TIME 48 9121
TEST_TIME 49 9147
TEST_TIME 50 9095
TEST_TIME 51 9178
TEST_TIME 52 9258
TEST_TIME 53 9196
TEST_TIME 54 9166
TEST_TIME 55 9315
TEST_TIME 56 9291
TEST_TIME 57 9160
TEST_TIME 58 9048
TEST_TIME 59 8966
TEST_TIME 60 8851
TEST_TIME 61 8789
TEST_TIME 62 8709
TEST_TIME 63 8787
TEST_TIME 64 8835
TEST_TIME 65 8883
TEST_TIME 66 8830
TEST_TIME 67 8867
TEST_TIME 68 8829
TEST_TIME 69 8873
TEST_TIME 70 8838
TEST_TIME 71 8889
TEST_TIME 72 9011
TEST_TIME 73 8958
TEST_TIME 74 9075
TEST_TIME 75 9043
TEST_TIME 76 9144
TEST_TIME 77 9031
TEST_TIME 78 8937
TEST_TIME 79 8881
TEST_TIME 80 8826
TEST_TIME 81 8816
TEST_TIME 82 8814
TEST_TIME 83 8798
TEST_TIME 84 8761
TEST_TIME 85 8828
TEST_TIME 86 8885
TEST_TIME 87 8896
TEST_TIME 88 8938
TEST_TIME 89 8914
TEST_TIME 90 8984
TEST_TIME 91 8971
TEST_TIME 92 9029
TEST_TIME 93 9017
TEST_TIME 94 8928
TEST_TIME 95 8879
TEST_TIME 96 8837
TEST_TIME 97 8800
TEST_TIME 98 8834
TEST_TIME 99 8824
TEST_TIME 100 8790
TEST_TIME 101 8815
TEST_TIME 102 8829
TEST_TIME 103 8794
TEST_TIME 104 8765
TEST_TIME 105 8790
TEST_TIME 106 8809
TEST_TIME 107 8800
TEST_TIME 108 8839
TEST_TIME 109 8885
TEST_TIME 110 8843
TEST_TIME 111 8780
TEST_TIME 112 8739
TEST_TIME 113 8702
TEST_TIME 114 8664
TEST_TIME 115 8640
TEST_TIME 116 8615
TEST_TIME 117 8649
TEST_TIME 118 8666
TEST_TIME 119 8653
TEST_TIME 120 8649

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2020 04:27:58 to 29-10-2020 04:28:01.

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 1444012 1444012 0 0.00 0 20065 6 0 0
doc_list_biud 1444010 1444010 0 0.00 0 78233 34 0 0
sp_add_invoice_to_stock 4790 4735 55 1.14 45 156541 17663 0 55
sp_cancel_adding_invoice 5257 5257 0 0.00 6 74693 941 0 0
sp_cancel_client_order 1643 1643 0 0.00 4 15428 324 0 0
sp_cancel_customer_reserve 518633 518633 0 0.00 1 78235 76 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 4307 74 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 2 9052 88 0 0
sp_cancel_supplier_invoice 5802 5789 13 0.22 3 39597 1020 0 13
sp_cancel_supplier_order 390 390 0 0.00 10 1037080 124854 0 0
sp_cancel_write_off 8944 8944 0 0.00 1 10166 118 0 0
sp_client_order 19810 19810 0 0.00 4 21521 444 0 0
sp_customer_reserve 437547 437547 0 0.00 0 44265 172 0 0
sp_fill_shopping_cart 30306 30306 0 0.00 1 12515 47 0 0
sp_fill_shopping_cart_clo_res 437536 437536 0 0.00 0 23176 21 0 0
sp_get_clo_for_invoice 4724 4724 0 0.00 0 25895 492 0 0
sp_kill_cost_storno 15258 15258 0 0.00 0 4679 57 0 0
sp_kill_qstorno_ret_qs2qd 1052389 1052389 0 0.00 0 78225 41 0 0
sp_kill_qty_storno 554846 554846 0 0.00 0 78233 87 0 0
sp_lock_selected_doc 19694 19694 0 0.00 0 9046 9 0 0
sp_make_cost_storno 26511 26511 0 0.00 0 15019 56 0 0
sp_multiply_rows_for_pdistr 26511 26511 0 0.00 0 9391 30 0 0
sp_multiply_rows_for_qdistr 19810 19810 0 0.00 0 17697 236 0 0
sp_pay_from_customer 6754 6754 0 0.00 4 17135 115 0 0
sp_pay_to_supplier 5567 5567 0 0.00 5 14841 124 0 0
sp_payment_common 12321 12321 0 0.00 1 12462 22 0 0
sp_qd_handle_on_cancel_clo 1643 1643 0 0.00 0 15417 292 0 0
sp_qd_handle_on_invoice_upd_sts 9981 9981 0 0.00 0 23619 377 0 0
sp_qd_handle_on_reserve_upd_sts 9466 9466 0 0.00 0 10512 52 0 0
sp_reserve_write_off 9466 9466 0 0.00 6 21370 177 0 0
sp_supplier_invoice 5261 5261 0 0.00 7 86880 3731 0 0
sp_supplier_order 5250 5250 0 0.00 6 25421 1374 0 0
srv_aggregate_perf_data 115 115 0 0.00 2819 299355 15321 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 773 135616 44991 0 0
srv_find_qd_qs_mism 992753 992753 0 0.00 0 20064 5 0 0
srv_make_invnt_saldo 471 471 0 0.00 63 146155 3445 0 0
srv_make_money_saldo 704 704 0 0.00 1 16740 614 0 0
srv_recalc_idx_stat 4 4 0 0.00 224281 431206 342725 0 0
t$perf-abend:sp_add_invoice_to_stock 55 55 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4735 4735 0 0.00 0 16357 131 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 1722 67 0 0
t$perf-norm:sp_cancel_client_order 1643 1643 0 0.00 0 2650 12 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 2298 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 1940 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 622 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1272 11 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 16454 1390 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 1055 4 0 0
t$perf-norm:sp_client_order 19810 19810 0 0.00 0 13251 16 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 1245 5 0 0
t$perf-norm:sp_pay_from_customer 6754 6754 0 0.00 0 15169 9 0 0
t$perf-norm:sp_pay_to_supplier 5567 5567 0 0.00 0 5043 7 0 0
t$perf-norm:sp_reserve_write_off 9466 9466 0 0.00 0 8116 11 0 0
t$perf-norm:sp_supplier_invoice 5261 5261 0 0.00 0 11828 19 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 5928 15 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 456 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1146 19 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1602 10 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 504 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9558 9448 110 1.15 0 5712 17 0 110
v_all_customers 35564 35564 0 0.00 0 179 1 0 0
v_all_suppliers 28924 28924 0 0.00 0 178 1 0 0
v_all_wares 39620 39620 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2810 15 0 0
v_cancel_client_order 3286 3286 0 0.00 0 6183 9 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 2383 7 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 2041 7 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2393 8 0 0
v_cancel_supplier_invoice 784 758 26 3.31 0 6914 21 0 26
v_cancel_supplier_order 780 780 0 0.00 0 675 4 0 0
v_cancel_write_off 1942 1942 0 0.00 0 3761 17 0 0
v_max_id_clo_ord 5242 5242 0 0.00 0 658 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 13863 35 0 0
v_max_id_ord_sup 5252 5252 0 0.00 0 6487 11 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 1269 3 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 1669 3 0 0
v_min_id_clo_ord 5242 5242 0 0.00 0 4908 8 0 0
v_min_id_clo_res 944 944 0 0.00 0 9849 16 0 0
v_min_id_ord_sup 5252 5252 0 0.00 0 3898 6 0 0
v_min_non_paid_invoice 5567 5567 0 0.00 0 12457 11 0 0
v_min_non_paid_realizn 6754 6754 0 0.00 0 9013 16 0 0
v_our_firm 10028 10028 0 0.00 0 16 1 0 0
v_reserve_write_off 18932 18932 0 0.00 0 5063 5 0 0
x_lock_depdocs_on_canc_invoice 10667 10667 0 0.00 0 14705 117 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 14310 393 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 1 25401 1291 0 0
x_make_qstorno_1200_2000 5261 5261 0 0.00 3 86327 3550 0 0
x_make_qstorno_2100_3300 437547 437547 0 0.00 0 44264 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2020 04:28:02 to 29-10-2020 04:28:06.

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 1 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
01:57:57 19982 8192000000 9709132976 10002092032 790054912 8.795 100 83 166 1 818243888 7673552 20515424
01:59:25 34820 8192000000 9753716976 10057588736 824483840 9.144 100 84 169 1 856992160 12481040 20027280
02:01:07 15169 8192000000 9805687008 10126614528 869558864 9.596 100 82 161 1 913640000 26011168 18069968
02:02:29 12289 8192000000 9877196224 10209304576 909320896 9.991 100 76 152 0 977074144 51053840 16699408
02:03:43 7621 8192000000 9887752304 10238734336 942969040 10.322 100 75 150 0 1007278000 48653936 15655024
02:04:54 4427 8192000000 9973063648 10325164032 988765152 10.769 100 83 166 0 1084824592 79253840 16805600
02:06:00 3571 8192000000 10006205616 10366091264 1019807216 11.070 100 89 179 1 1117269648 79796416 17666016
02:07:05 2409 8192000000 10009578240 10377408512 1046683552 11.329 100 90 178 0 1124188832 58463040 19042240
02:08:08 1425 8192000000 10035478208 10405437440 1060961872 11.466 100 93 183 2 1145316448 65916416 18438160
02:09:10 5168 8192000000 10006666576 10396372992 1064067392 11.495 100 53 106 1 1110496464 35825424 10603648
02:10:16 6523 8192000000 9977113232 10369941504 1074739328 11.597 100 34 68 1 1095229872 13529424 6961120
02:11:23 5687 8192000000 9991542128 10384863232 1080429744 11.652 100 33 65 0 1108998304 21080992 7487568
02:12:28 49501 8192000000 9998660992 10410360832 1083484416 11.681 100 38 76 1 1110885408 18567952 8833040
02:14:19 11024 8192000000 10014035088 10422362112 1090361504 11.746 100 34 68 4 1124624960 27130992 7132464
02:15:31 16130 8192000000 10010599552 10421780480 1092393792 11.765 100 30 59 2 1121059648 22635584 6030272
02:16:47 11539 8192000000 10001755856 10409140224 1095246240 11.793 100 27 54 2 1120377680 18061856 7069584
02:18:01 43465 8192000000 9984620976 10374303744 1051638864 11.376 97 37 74 0 1084900912 25059312 8202736
02:19:44 7733 8192000000 9951723152 10345938944 1013767888 11.012 97 51 103 1 1051229392 25992288 11469216
02:20:53 12176 8192000000 9930233408 10315190272 966820800 10.556 94 38 76 4 994439456 19097632 8521024
02:22:11 9766 8192000000 9951247376 10337546240 1007258816 10.949 98 40 81 3 1031552176 15659024 8634336
02:23:30 28863 8192000000 9953292048 10349486080 1027727904 11.147 99 27 53 4 1075730368 42785440 5217024
02:25:06 7514 8192000000 9974992992 10369351680 1055571120 11.414 100 39 78 1 1086134448 21837552 8725776
02:26:14 10367 8192000000 9999482368 10399703040 1082099632 11.667 100 45 91 4 1111390112 18678080 10612400
02:27:31 5496 8192000000 10020628528 10411118592 1095423680 11.794 100 58 114 1 1131928432 24720768 11783984
02:28:40 32071 8192000000 10051051760 10451341312 1105806304 11.893 100 43 85 1 1174366640 59559504 9000832
02:30:29 3599 8192000000 10079285936 10476056576 1124434464 12.069 100 52 104 1 1185994368 50722064 10837840
02:31:35 5247 8192000000 10126210272 10526408704 1138649984 12.203 100 62 122 1 1237221680 85841504 12730192
02:32:53 4120 8192000000 10149637040 10555793408 1146581568 12.277 100 45 88 2 1255519936 99128400 9809968
02:34:00 21995 8192000000 10222314576 10627497984 1156116080 12.367 100 57 114 2 1315422048 146660880 12645088
02:35:26 9702 8192000000 10224680640 10635558912 1164941424 12.450 100 54 104 0 1335585744 158629408 12014912
02:36:43 5509 8192000000 10270144272 10675351552 1172410000 12.519 100 75 148 0 1375630496 186766176 16454320
02:37:54 9144 8192000000 10291792128 10712571904 1178256768 12.574 100 60 120 0 1410755424 218688160 13810496
02:39:08 2851 8192000000 10332861088 10739302400 1177526880 12.567 100 77 154 1 1439072016 242544640 19000496
02:40:15 18704 8192000000 10341819632 10775465984 1185810592 12.644 100 59 118 1 1448879712 248751744 14317376
02:41:40 3984 8192000000 10356906352 10768244736 1179650480 12.587 99 65 127 1 1453158176 259034176 14473520
02:42:49 772 8192000000 10395527280 10811387904 1199626560 12.773 100 88 175 2 1495118544 274447056 21044928
02:43:53 1522 8192000000 10424716064 10833448960 1191004288 12.693 100 80 160 2 1523099424 313176112 18919024
02:45:00 2009 8192000000 10408429744 10815365120 1177947248 12.571 100 81 159 1 1505874032 309191872 18734912
02:46:07 3837 8192000000 10424115200 10839785472 1148172496 12.292 98 73 146 0 1517086944 350440656 18473792
02:47:27 5367 8192000000 10349571392 10764886016 1150554832 12.315 100 45 89 0 1455512720 293240352 11717536
02:48:37 9138 8192000000 10423413248 10838974464 1156475408 12.370 100 51 99 1 1499620976 330964688 12180880
02:50:05 3475 8192000000 10416013472 10829541376 1152545600 12.333 100 47 94 0 1512914336 348641024 11727712
02:51:10 10934 8192000000 10465073792 10911514624 1139421040 12.210 100 39 78 0 1556839552 406822848 10595664
02:52:29 28848 8192000000 10421841328 10917515264 1121423792 12.040 99 28 56 1 1501054496 371880032 7750672
02:54:15 35589 8192000000 10055070880 10618605568 1083255776 11.678 97 13 26 2 1217333904 131100256 2977872
02:55:54 33864 8192000000 10031347104 10447908864 1091339936 11.755 100 38 74 3 1123261664 23474816 8446912
02:57:28 28417 8192000000 10053667744 10505895936 1108471184 11.918 100 11 22 0 1161430048 51037312 1921552
02:58:57 76779 8192000000 10079600768 10524602368 1055485920 11.413 94 15 30 0 1108143392 49783632 2873840
03:02:22 70497 8192000000 10040429040 10492592128 1071250080 11.564 96 15 31 0 1114292640 39759168 3283392
03:04:40 104492 8192000000 10027046784 10487533568 1092588288 11.767 99 17 34 0 1154371904 58249360 3534256
03:07:34 54892 8192000000 10055718912 10489012224 1100994160 11.847 99 18 36 0 1140722800 35821232 3907408
03:10:50 88805 8192000000 10130567648 10563702784 1133977888 12.159 100 21 42 0 1226800880 88272800 4550192
03:13:34 67673 8192000000 10079929696 10535395328 1125621088 12.080 99 22 44 2 1188331616 57741920 4968608
03:15:44 54779 8192000000 10084986864 10516934656 1137511408 12.192 100 27 54 0 1166267376 23477856 5278112
03:17:39 103120 8192000000 10082901056 10518323200 1143678224 12.250 100 14 28 0 1201231792 54542784 3010784
03:20:25 76783 8192000000 10112063104 10593095680 1127245808 12.095 98 10 20 0 1232160528 102908064 2006656
03:22:42 135616 8192000000 10080927424 10533867520 1132545840 12.145 99 28 56 1 1173239344 34731392 5962112
03:26:04 64764 8192000000 10097226928 10545868800 1139532784 12.211 99 10 20 0 1199409744 57791728 2085232
03:28:19 47696 8192000000 10130042320 10587250688 1152651392 12.334 100 12 23 0 1226055520 70956768 2447360
03:30:26 74698 8192000000 10105655888 10571112448 1144622272 12.259 99 15 30 1 1228406224 80442592 3341360
03:32:42 70209 8192000000 10080774416 10525442048 1130941152 12.130 98 17 34 2 1184974304 50517984 3515168
03:35:04 63431 8192000000 10090297136 10523410432 1083921568 11.685 94 19 38 0 1143391712 54980496 4489648
03:37:10 49895 8192000000 10140366128 10597437440 1131358720 12.134 99 25 50 1 1230122400 93433280 5330400
03:39:16 30950 8192000000 10099026768 10532323328 1122422352 12.050 98 26 51 1 1188046144 59939904 5683888
03:40:50 40216 8192000000 10090941088 10508316672 1093831136 11.779 96 27 54 1 1132121792 32571376 5719280
03:42:36 91133 8192000000 10058386224 10529857536 1109833280 11.931 98 14 28 1 1153582464 40774896 2974288
03:45:08 52412 8192000000 10049812256 10488442880 1096466048 11.804 97 12 24 0 1141907520 42837232 2604240
03:47:00 41668 8192000000 10080360960 10544234496 1110017200 11.933 99 9 18 0 1165834992 54052400 1765392
03:49:18 26910 8192000000 10105653984 10541944832 1097113152 11.810 97 24 49 0 1190283920 87459584 5711184
03:50:45 72466 8192000000 10124338336 10598293504 1126949200 12.093 99 23 43 3 1259270688 126702112 5619376
03:52:58 64273 8192000000 10099947424 10566586368 1084281056 11.688 96 49 98 0 1167505904 73450224 9774624
03:55:11 48413 8192000000 10104635712 10533650432 1101474384 11.852 98 22 44 2 1150755024 44749616 4531024
03:57:00 62224 8192000000 10084994832 10527084544 1073786384 11.588 95 31 62 0 1124642560 44282816 6573360
03:59:02 103431 8192000000 10040604208 10507898880 1106974016 11.904 99 15 30 0 1146683376 36894672 2814688
04:01:54 54360 8192000000 10066101504 10510512128 1110789520 11.940 99 5 10 0 1134626448 23023776 813152
04:03:49 62733 8192000000 10059514128 10521460736 1113322896 11.964 99 16 32 0 1164323824 48004752 2996176
04:05:59 28191 8192000000 10080789328 10495303680 1132092176 12.141 100 37 74 0 1168227792 28816464 7319152
04:07:28 95192 8192000000 10058575824 10534199296 1117079392 11.999 98 17 34 0 1178943744 58672656 3191696
04:10:03 64413 8192000000 10119166672 10571714560 1135381552 12.172 99 29 58 2 1184661888 43671312 5609024
04:12:11 42254 8192000000 10092664928 10552274944 1135089648 12.169 99 24 48 1 1185582928 45677824 4815456
04:13:59 39790 8192000000 10078294736 10502164480 1131773664 12.138 99 23 45 1 1159638960 23327808 4537488
04:15:44 103154 8192000000 10079324448 10544939008 1144376544 12.257 100 20 40 1 1185401920 37143776 3881600
04:19:01 64432 8192000000 10043239264 10498109440 1117697296 12.005 98 6 12 0 1141008256 22039952 1271008
04:21:13 62819 8192000000 10084190208 10530050048 1097725792 11.816 96 17 34 1 1149709168 48109920 3873456
04:23:16 62964 8192000000 10055350000 10534690816 1094052832 11.781 97 14 28 0 1149628032 52369232 3205968
04:25:19 29613 8192000000 10109087728 10545905664 1105176848 11.887 97 33 66 0 1152676880 40580320 6919712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-10-2020 04:28:08 to 29-10-2020 04:28:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 55 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2020 04:28:23 to 29-10-2020 04:28:25.

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          226817
MON$OLDEST_ACTIVE               229540
MON$OLDEST_SNAPSHOT             229540
MON$NEXT_TRANSACTION            229540
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                       2074080
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             768
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2240 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2240 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.2240 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Thu Oct 29 04:26:58 2020
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
--- end of diff output ---

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

29.10.20 04:28:26. 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-29 04:28:29 No severe PSQL-related problems occured
29.10.20 04:28:30. End of report.