Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 07:36
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 16752340 kB
MemAvailable 31545600 kB
Buffers 0 kB
Cached 14945920 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-28 07:35:22
DTS_END                         2020-10-28 07:35:22.7330
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/37658
TRN_ID                          226201
ATT_ID                          634
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: 8820.70 23136 1058484
customer order: creation 162.82 374 19538
customer order: refuse 14.60 359 1752
order to supplier: creation 43.49 1337 5219
order to supplier: removal 3.05 140508 366
invoice (draft): creation 43.53 3562 5223
invoice (draft): removal 51.69 987 6203
invoice accept: apply 39.48 17151 4738
invoice accept: cancel 46.18 939 5542
customer reserve: creation 3573.80 170 428856
customer reserve: removal 4550.23 74 546028
realization accept: apply 80.01 199 9601
realization accept: cancel 90.36 119 10843
payment from customer: creation 55.56 121 6667
payment from customer: removal 5.20 81 624
payment to supplier: creation 47.26 143 5671
payment to supplier: removal 3.33 78 400
service: total inventory turnovers 3.41 4038 409
service: total monetary turnovers 5.68 666 682
service: aggregate perf. data 0.98 13572 118
service: refresh index statistics 0.03 278234 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 07:37:07 to 28-10-2020 07:37:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2555
TEST_TIME 2 3352
TEST_TIME 3 3857
TEST_TIME 4 3951
TEST_TIME 5 3846
TEST_TIME 6 4138
TEST_TIME 7 3999
TEST_TIME 8 4183
TEST_TIME 9 4201
TEST_TIME 10 4416
TEST_TIME 11 4844
TEST_TIME 12 4914
TEST_TIME 13 5012
TEST_TIME 14 5184
TEST_TIME 15 5519
TEST_TIME 16 5686
TEST_TIME 17 5732
TEST_TIME 18 5709
TEST_TIME 19 6099
TEST_TIME 20 6608
TEST_TIME 21 6650
TEST_TIME 22 6855
TEST_TIME 23 7496
TEST_TIME 24 7871
TEST_TIME 25 7860
TEST_TIME 26 8026
TEST_TIME 27 8180
TEST_TIME 28 8050
TEST_TIME 29 8431
TEST_TIME 30 10149
TEST_TIME 31 9925
TEST_TIME 32 9702
TEST_TIME 33 9656
TEST_TIME 34 9623
TEST_TIME 35 9589
TEST_TIME 36 9510
TEST_TIME 37 9371
TEST_TIME 38 9296
TEST_TIME 39 9200
TEST_TIME 40 9193
TEST_TIME 41 9302
TEST_TIME 42 9247
TEST_TIME 43 9213
TEST_TIME 44 9378
TEST_TIME 45 9424
TEST_TIME 46 9416
TEST_TIME 47 9447
TEST_TIME 48 9443
TEST_TIME 49 9404
TEST_TIME 50 9230
TEST_TIME 51 9141
TEST_TIME 52 9011
TEST_TIME 53 8973
TEST_TIME 54 8991
TEST_TIME 55 9028
TEST_TIME 56 9022
TEST_TIME 57 8944
TEST_TIME 58 8952
TEST_TIME 59 8863
TEST_TIME 60 8948
TEST_TIME 61 8864
TEST_TIME 62 8943
TEST_TIME 63 8923
TEST_TIME 64 8950
TEST_TIME 65 8916
TEST_TIME 66 8991
TEST_TIME 67 8971
TEST_TIME 68 8973
TEST_TIME 69 9012
TEST_TIME 70 9043
TEST_TIME 71 9178
TEST_TIME 72 9221
TEST_TIME 73 9264
TEST_TIME 74 9298
TEST_TIME 75 9334
TEST_TIME 76 9231
TEST_TIME 77 9135
TEST_TIME 78 9100
TEST_TIME 79 9116
TEST_TIME 80 9068
TEST_TIME 81 9029
TEST_TIME 82 8967
TEST_TIME 83 8883
TEST_TIME 84 8903
TEST_TIME 85 8844
TEST_TIME 86 8937
TEST_TIME 87 8948
TEST_TIME 88 8932
TEST_TIME 89 8951
TEST_TIME 90 8978
TEST_TIME 91 9000
TEST_TIME 92 9088
TEST_TIME 93 9037
TEST_TIME 94 9057
TEST_TIME 95 9097
TEST_TIME 96 9110
TEST_TIME 97 9097
TEST_TIME 98 9010
TEST_TIME 99 8981
TEST_TIME 100 8940
TEST_TIME 101 8887
TEST_TIME 102 8833
TEST_TIME 103 8836
TEST_TIME 104 8855
TEST_TIME 105 8827
TEST_TIME 106 8848
TEST_TIME 107 8830
TEST_TIME 108 8836
TEST_TIME 109 8854
TEST_TIME 110 8829
TEST_TIME 111 8901
TEST_TIME 112 9000
TEST_TIME 113 9058
TEST_TIME 114 9030
TEST_TIME 115 9062
TEST_TIME 116 8993
TEST_TIME 117 8933
TEST_TIME 118 8931
TEST_TIME 119 8886
TEST_TIME 120 8822

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2020 07:37:10 to 28-10-2020 07:37:13.

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 1456127 1456127 0 0.00 0 19899 7 0 0
doc_list_biud 1456115 1456115 0 0.00 0 41403 35 0 0
sp_add_invoice_to_stock 4888 4738 150 3.06 28 203887 17151 0 150
sp_cancel_adding_invoice 5542 5542 0 0.00 4 95010 939 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 18957 360 0 0
sp_cancel_customer_reserve 546028 546028 0 0.00 1 41406 75 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 3537 81 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 2561 79 0 0
sp_cancel_supplier_invoice 6215 6203 12 0.19 2 19764 987 0 12
sp_cancel_supplier_order 366 366 0 0.00 10 1273450 140508 0 0
sp_cancel_write_off 10843 10843 0 0.00 1 9891 119 0 0
sp_client_order 19538 19538 0 0.00 4 23638 375 0 0
sp_customer_reserve 428856 428856 0 0.00 0 122614 170 0 0
sp_fill_shopping_cart 29974 29974 0 0.00 1 18596 43 0 0
sp_fill_shopping_cart_clo_res 428855 428855 0 0.00 0 20455 16 0 0
sp_get_clo_for_invoice 4735 4735 0 0.00 0 23225 346 0 0
sp_kill_cost_storno 17409 17409 0 0.00 0 4417 60 0 0
sp_kill_qstorno_ret_qs2qd 1109468 1109468 0 0.00 0 35433 40 0 0
sp_kill_qty_storno 585061 585061 0 0.00 0 41402 87 0 0
sp_lock_selected_doc 19860 19860 0 0.00 0 7385 4 0 0
sp_make_cost_storno 26674 26674 0 0.00 0 8764 60 0 0
sp_multiply_rows_for_pdistr 26665 26665 0 0.00 0 6762 31 0 0
sp_multiply_rows_for_qdistr 19538 19538 0 0.00 0 23587 203 0 0
sp_pay_from_customer 6667 6667 0 0.00 4 17638 122 0 0
sp_pay_to_supplier 5671 5671 0 0.00 4 17838 143 0 0
sp_payment_common 12336 12336 0 0.00 1 7122 25 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 18949 332 0 0
sp_qd_handle_on_invoice_upd_sts 10274 10274 0 0.00 0 40497 419 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 30073 68 0 0
sp_reserve_write_off 9601 9601 0 0.00 7 30203 200 0 0
sp_supplier_invoice 5223 5223 0 0.00 5 99334 3562 0 0
sp_supplier_order 5219 5219 0 0.00 5 60204 1337 0 0
srv_aggregate_perf_data 118 118 0 0.00 2581 189651 13573 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1468 154157 48839 0 0
srv_find_qd_qs_mism 1013911 1013911 0 0.00 0 19757 5 0 0
srv_make_invnt_saldo 409 409 0 0.00 34 129704 4038 0 0
srv_make_money_saldo 682 682 0 0.00 1 13585 667 0 0
srv_recalc_idx_stat 4 4 0 0.00 192748 401332 278234 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4738 4738 0 0.00 0 12563 165 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 4864 89 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 2318 12 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 2149 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 274 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2911 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 4852 30 0 0
t$perf-norm:sp_cancel_supplier_order 366 366 0 0.00 0 18780 1790 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1647 6 0 0
t$perf-norm:sp_client_order 19538 19538 0 0.00 0 14161 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1134 4 0 0
t$perf-norm:sp_pay_from_customer 6667 6667 0 0.00 0 3296 10 0 0
t$perf-norm:sp_pay_to_supplier 5671 5671 0 0.00 0 3276 7 0 0
t$perf-norm:sp_reserve_write_off 9601 9601 0 0.00 0 19916 15 0 0
t$perf-norm:sp_supplier_invoice 5223 5223 0 0.00 0 4067 20 0 0
t$perf-norm:sp_supplier_order 5219 5219 0 0.00 0 6931 18 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 698 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 714 22 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 694 9 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 816 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9754 9454 300 3.07 0 6274 18 0 300
v_all_customers 35920 35920 0 0.00 0 27 1 0 0
v_all_suppliers 29156 29156 0 0.00 0 126 1 0 0
v_all_wares 39076 39076 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 605 6 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3257 7 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 1866 10 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 4101 12 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2463 10 0 0
v_cancel_supplier_invoice 846 822 24 2.83 0 2238 15 0 24
v_cancel_supplier_order 732 732 0 0.00 0 872 4 0 0
v_cancel_write_off 1912 1912 0 0.00 0 2605 16 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 108 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 3915 11 0 0
v_max_id_ord_sup 5218 5218 0 0.00 0 8531 7 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 2841 6 0 0
v_max_non_paid_realizn 3487 3487 0 0.00 0 668 2 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 5367 8 0 0
v_min_id_clo_res 974 974 0 0.00 0 4474 16 0 0
v_min_id_ord_sup 5218 5218 0 0.00 0 5035 5 0 0
v_min_non_paid_invoice 5669 5669 0 0.00 0 6166 16 0 0
v_min_non_paid_realizn 6666 6666 0 0.00 0 5581 18 0 0
v_our_firm 9516 9516 0 0.00 0 17 1 0 0
v_reserve_write_off 19190 19190 0 0.00 0 3982 7 0 0
x_lock_depdocs_on_canc_invoice 11334 11334 0 0.00 0 33870 128 0 0
x_lock_depdocs_on_canc_sup_ord 366 366 0 0.00 0 7529 393 0 0
x_make_qstorno_1000_1200 5219 5219 0 0.00 2 60000 1257 0 0
x_make_qstorno_1200_2000 5223 5223 0 0.00 2 89981 3400 0 0
x_make_qstorno_2100_3300 428856 428856 0 0.00 0 122596 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 07:37:14 to 28-10-2020 07:37:18.

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:26 2 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
05:06:27 32645 8192000000 9719480656 10020106240 800729328 8.904 100 85 168 1 828724992 8077664 19918000
05:08:04 16448 8192000000 9771611184 10077798400 840751472 9.307 100 90 180 4 875325984 13713008 20861504
05:09:28 21254 8192000000 9808728368 10130210816 870985632 9.610 100 86 172 0 917724672 27570272 19168768
05:10:52 10441 8192000000 9866564352 10198499328 902349136 9.922 100 71 140 3 973691424 56108640 15233648
05:12:08 9751 8192000000 9904631872 10248257536 948049200 10.372 100 81 161 1 1019134208 54247536 16837472
05:13:19 3730 8192000000 9960753152 10310389760 991657104 10.798 100 85 169 0 1077942352 69152672 17132576
05:14:26 3152 8192000000 9987437904 10348011520 1024221616 11.113 100 89 177 0 1097584944 55542992 17820336
05:15:31 2619 8192000000 10042639424 10408681472 1046298288 11.325 100 92 184 0 1150115488 84906496 18910704
05:16:36 1617 8192000000 10045649760 10415300608 1062563616 11.481 100 94 188 0 1153107488 70548752 19995120
05:17:39 27500 8192000000 10014772400 10401181696 1071990560 11.571 100 62 121 2 1125872992 40909232 12973200
05:19:08 5937 8192000000 10008036608 10407366656 1079890288 11.646 100 52 104 0 1126320448 35429104 11001056
05:20:15 42253 8192000000 9997625872 10387152896 1082005936 11.667 100 64 129 1 1106823072 10589152 14227984
05:21:58 7726 8192000000 10008737552 10416427008 1082296288 11.669 100 32 64 2 1119461088 30823600 6341200
05:23:09 7428 8192000000 10017614560 10420215808 1089342960 11.736 100 38 76 2 1124871072 27206576 8321536
05:24:18 7207 8192000000 10003308144 10406285312 1093523696 11.776 100 30 58 2 1119741888 20469296 5748896
05:25:26 43074 8192000000 10014536320 10400022528 1085052432 11.696 99 64 132 2 1113150560 14800352 13297776
05:27:10 7053 8192000000 9992373808 10397806592 1052285568 11.383 98 36 72 1 1090741456 31074384 7381504
05:28:19 6864 8192000000 9958571424 10351788032 1009179696 10.967 96 37 73 2 1034953056 18122592 7650768
05:29:27 8698 8192000000 9948351904 10339786752 997104304 10.850 97 42 85 2 1032602384 25795344 9702736
05:30:38 39816 8192000000 9922131392 10294403072 980201984 10.686 96 39 78 0 1004523952 16577872 7744096
05:32:18 7173 8192000000 9941960656 10329153536 990243872 10.784 97 37 74 3 1018839088 21196416 7398800
05:33:28 5336 8192000000 9973041296 10349920256 1044691536 11.310 100 49 99 2 1081380416 25329488 11359392
05:34:34 8509 8192000000 9972683280 10362228736 1057844240 11.436 100 31 62 2 1087550976 22704528 7002208
05:35:45 44716 8192000000 9998718880 10394021888 1085088320 11.696 100 49 94 3 1111502256 16944272 9469664
05:37:33 46028 8192000000 10037073872 10447806464 1101447984 11.851 100 41 80 1 1139438880 29654176 8336720
05:39:21 27016 8192000000 10085140592 10491342848 1123806816 12.063 100 37 74 1 1190717536 59047424 7863296
05:40:55 15491 8192000000 10126667776 10529374208 1136808496 12.185 100 53 103 1 1234656192 85685904 12161792
05:42:14 16847 8192000000 10143798848 10550403072 1154456096 12.351 100 47 94 1 1255801616 90683504 10662016
05:43:36 9476 8192000000 10188020528 10601222144 1165238800 12.452 100 54 108 1 1305679488 128104608 12336080
05:44:50 8656 8192000000 10244693680 10650980352 1171793824 12.514 100 51 102 1 1355517552 172208640 11515088
05:46:02 11441 8192000000 10249374288 10674933760 1172442176 12.520 100 69 131 2 1349687664 161954624 15290864
05:47:21 7227 8192000000 10270977760 10693595136 1177949408 12.571 100 56 110 2 1380252480 190097024 12206048
05:48:37 11644 8192000000 10284614064 10697183232 1175394480 12.547 100 54 108 2 1394206768 206875024 11937264
05:50:01 2791 8192000000 10307424128 10715754496 1182491280 12.613 100 64 126 0 1410274928 213565504 14218144
05:51:09 1467 8192000000 10347321648 10745974784 1186692864 12.653 100 77 154 2 1448174880 244283920 17198096
05:52:15 3153 8192000000 10361632432 10760183808 1163745360 12.438 99 89 173 1 1446999792 263782576 19471856
05:53:25 6767 8192000000 10405519936 10809954304 1169587536 12.493 99 80 160 1 1499790832 311767600 18435696
05:54:39 2976 8192000000 10429255504 10843496448 1178208448 12.573 100 85 170 0 1531346672 333067328 20070896
05:55:49 6048 8192000000 10398014960 10807709696 1170888176 12.505 100 68 136 0 1496960976 309260368 16812432
05:57:04 2955 8192000000 10440129216 10844479488 1175126736 12.545 100 86 170 2 1541366912 345541968 20698208
05:58:14 5314 8192000000 10431420864 10893725696 1164457488 12.445 100 46 91 0 1560884896 384506560 11920848
05:59:27 21414 8192000000 10329118240 10765025280 1139097824 12.207 99 29 58 1 1427492112 280685824 7708464
06:00:58 25887 8192000000 10352278560 10790965248 1127308880 12.096 98 28 56 1 1436789040 302596080 6884080
06:02:27 20472 8192000000 10382104800 10837778432 1131169104 12.132 99 26 50 3 1464558320 326501696 6887520
06:03:51 21967 8192000000 10425277488 10856865792 1093379312 11.775 98 22 44 1 1512129008 412023024 6726672
06:05:14 82442 8192000000 10055891520 10518401024 1111774912 11.949 99 13 27 0 1310953920 196013056 3165952
06:07:37 69629 8192000000 10109536448 10581852160 1128461488 12.107 99 26 51 1 1188191792 54117072 5613232
06:09:47 65799 8192000000 10052441488 10520350720 1112797808 11.959 98 4 8 0 1148315392 34942160 575424
06:11:54 80419 8192000000 10087673840 10524966912 1136214032 12.180 100 18 36 1 1167983216 27931376 3837808
06:14:17 74413 8192000000 10095121072 10546331648 1126286384 12.086 98 24 48 6 1189494784 58504528 4703872
06:16:34 76687 8192000000 10073019968 10501582848 1102908128 11.865 97 16 32 1 1135848736 29510400 3430208
06:18:53 67032 8192000000 10109922816 10621906944 1124466128 12.069 99 12 24 0 1247045792 119870592 2709072
06:21:06 76451 8192000000 10075734656 10561646592 1104925728 11.884 97 11 22 0 1161106608 53705056 2475824
06:23:33 71724 8192000000 10065312992 10534522880 1142801648 12.242 100 8 16 1 1178941184 34985776 1153760
06:25:47 94087 8192000000 10084518432 10514673664 1133729136 12.157 99 38 76 0 1177356848 35618432 8009280
06:28:25 64174 8192000000 10094060592 10565341184 1137378336 12.191 99 18 36 0 1187676768 46312848 3985584
06:30:30 32648 8192000000 10109272080 10564616192 1143489680 12.248 99 21 42 0 1204655712 56908800 4257232
06:32:15 135008 8192000000 10140834784 10608799744 1125233088 12.076 97 13 26 0 1209038528 81246240 2559200
06:35:38 45303 8192000000 10094746768 10546405376 1134933424 12.168 98 14 28 1 1193346064 55878288 2534352
06:38:03 42663 8192000000 10075773232 10531536896 1140613600 12.221 99 14 28 1 1191544720 47861440 3069680
06:39:46 38218 8192000000 10090679456 10523332608 1150044160 12.310 100 34 66 1 1196691664 39708208 6939296
06:41:24 80583 8192000000 10129338624 10609303552 1127601664 12.099 98 12 23 1 1187476176 57203264 2671248
06:43:47 69560 8192000000 10153194768 10596204544 1101310144 11.850 96 23 46 1 1218843760 112479312 5054304
06:45:58 41361 8192000000 10067145456 10521702400 1038339536 11.249 92 16 32 0 1127608144 85239040 4029568
06:47:40 75182 8192000000 10061098896 10485723136 1038980672 11.255 94 19 38 3 1141340896 98166704 4193520
06:49:56 127183 8192000000 10052898928 10513584128 1081844336 11.665 97 18 36 2 1127797456 41826992 4126128
06:53:44 56094 8192000000 10036963328 10506293248 1078450352 11.633 97 16 32 0 1148466304 66782464 3233488
06:55:45 47544 8192000000 10028617856 10461798400 1085418944 11.699 98 15 28 1 1115657568 27224592 3014032
06:57:38 42751 8192000000 10080091056 10526695424 1106891552 11.903 99 12 24 0 1174759008 64737184 3130272
06:59:44 65542 8192000000 10096425248 10523799552 1080038592 11.648 97 13 26 1 1162876544 79957920 2880032
07:01:52 61874 8192000000 10046983616 10529972224 1065602640 11.510 96 17 32 1 1133801168 64682752 3515776
07:04:01 27765 8192000000 10103132576 10527342592 1122392768 12.050 100 28 56 1 1190882880 62148832 6341280
07:05:35 94173 8192000000 10118823376 10555834368 1093358448 11.775 96 18 35 2 1154518160 57836176 3323536
07:08:13 23576 8192000000 10058213920 10484060160 1123480128 12.060 99 40 80 2 1151021232 19150528 8390576
07:09:38 22395 8192000000 10112465264 10539094016 1115853440 11.988 99 47 94 0 1198955344 73636720 9465184
07:11:02 16807 8192000000 10110036640 10551869440 1113897392 11.969 98 37 74 0 1197455168 76203856 7353920
07:12:26 105161 8192000000 10049401136 10475393024 1110999168 11.942 98 27 55 2 1145982816 29590000 5393648
07:15:16 62101 8192000000 10049155728 10497277952 1125134800 12.075 99 12 24 0 1154002880 26420304 2447776
07:17:18 50745 8192000000 10057375232 10518806528 1113199024 11.963 98 8 16 2 1137094464 22365648 1529792
07:19:10 66174 8192000000 10085459168 10565292032 1145798224 12.270 100 11 22 1 1189784448 41639072 2347152
07:21:17 66796 8192000000 10107930528 10558234624 1155422464 12.360 100 16 32 0 1200806144 41790688 3592992
07:23:28 78642 8192000000 10158502288 10648788992 1128745568 12.110 97 22 44 0 1217589712 83751648 5092496
07:25:51 89045 8192000000 10171568624 10648600576 1143006288 12.244 99 14 27 1 1269625904 123336256 3283360
07:28:30 82378 8192000000 10136238000 10612166656 1156974912 12.375 100 9 18 1 1225630608 66642816 2012880
07:30:54 154157 8192000000 10070590816 10538848256 1123425952 12.059 97 13 26 1 1193735760 67581136 2728672
07:34:30 32694 8192000000 10044850160 10521432064 1104012192 11.876 97 2 4 1 1123800384 19566960 221232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2020 07:37:20 to 28-10-2020 07:37:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 150 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 07:37:36 to 28-10-2020 07:37:38.

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          225648
MON$OLDEST_ACTIVE               226330
MON$OLDEST_SNAPSHOT             226330
MON$NEXT_TRANSACTION            226330
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                       2118080
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             761
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 ---
--- end of diff output ---

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

28.10.20 07:37:39. 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-28 07:37:41 No severe PSQL-related problems occured
28.10.20 07:37:42. End of report.