Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16962204 kB
MemAvailable 31402844 kB
Buffers 0 kB
Cached 14606396 kB
SwapCached 3704 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-12-18 10:26:53
DTS_END                         2020-12-18 10:26:53.0620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53890
TRN_ID                          228512
ATT_ID                          632
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: 8800.94 24599 1056113
customer order: creation 160.40 435 19248
customer order: refuse 13.33 313 1600
order to supplier: creation 42.80 1340 5136
order to supplier: removal 3.28 118119 394
invoice (draft): creation 42.31 3377 5077
invoice (draft): removal 50.52 948 6062
invoice accept: apply 39.05 17993 4686
invoice accept: cancel 45.52 847 5462
customer reserve: creation 3658.78 172 439053
customer reserve: removal 4455.27 69 534632
realization accept: apply 77.88 185 9346
realization accept: cancel 90.43 114 10852
payment from customer: creation 55.63 113 6676
payment from customer: removal 5.03 67 604
payment to supplier: creation 46.65 150 5598
payment to supplier: removal 3.48 86 418
service: total inventory turnovers 4.00 2935 480
service: total monetary turnovers 5.62 520 674
service: aggregate perf. data 0.93 12241 111
service: refresh index statistics 0.03 331954 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2020 10:27:55 to 18-12-2020 10:27:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4375
TEST_TIME 2 3952
TEST_TIME 3 4107
TEST_TIME 4 4727
TEST_TIME 5 5561
TEST_TIME 6 5629
TEST_TIME 7 5721
TEST_TIME 8 5541
TEST_TIME 9 5450
TEST_TIME 10 5918
TEST_TIME 11 6152
TEST_TIME 12 6338
TEST_TIME 13 6234
TEST_TIME 14 6346
TEST_TIME 15 6657
TEST_TIME 16 6715
TEST_TIME 17 6996
TEST_TIME 18 6869
TEST_TIME 19 7665
TEST_TIME 20 8146
TEST_TIME 21 8129
TEST_TIME 22 8418
TEST_TIME 23 8446
TEST_TIME 24 8945
TEST_TIME 25 9184
TEST_TIME 26 10133
TEST_TIME 27 11326
TEST_TIME 28 11243
TEST_TIME 29 10927
TEST_TIME 30 10738
TEST_TIME 31 10545
TEST_TIME 32 10315
TEST_TIME 33 10155
TEST_TIME 34 9991
TEST_TIME 35 10182
TEST_TIME 36 10138
TEST_TIME 37 9990
TEST_TIME 38 9744
TEST_TIME 39 9745
TEST_TIME 40 9630
TEST_TIME 41 9640
TEST_TIME 42 9638
TEST_TIME 43 9482
TEST_TIME 44 9561
TEST_TIME 45 9640
TEST_TIME 46 9558
TEST_TIME 47 9474
TEST_TIME 48 9460
TEST_TIME 49 9453
TEST_TIME 50 9564
TEST_TIME 51 9717
TEST_TIME 52 9752
TEST_TIME 53 9723
TEST_TIME 54 9704
TEST_TIME 55 9537
TEST_TIME 56 9453
TEST_TIME 57 9351
TEST_TIME 58 9387
TEST_TIME 59 9326
TEST_TIME 60 9265
TEST_TIME 61 9226
TEST_TIME 62 9209
TEST_TIME 63 9176
TEST_TIME 64 9232
TEST_TIME 65 9263
TEST_TIME 66 9259
TEST_TIME 67 9446
TEST_TIME 68 9464
TEST_TIME 69 9438
TEST_TIME 70 9387
TEST_TIME 71 9488
TEST_TIME 72 9415
TEST_TIME 73 9501
TEST_TIME 74 9480
TEST_TIME 75 9372
TEST_TIME 76 9305
TEST_TIME 77 9254
TEST_TIME 78 9179
TEST_TIME 79 9180
TEST_TIME 80 9158
TEST_TIME 81 9135
TEST_TIME 82 9161
TEST_TIME 83 9159
TEST_TIME 84 9134
TEST_TIME 85 9102
TEST_TIME 86 9152
TEST_TIME 87 9176
TEST_TIME 88 9253
TEST_TIME 89 9250
TEST_TIME 90 9224
TEST_TIME 91 9167
TEST_TIME 92 9155
TEST_TIME 93 9118
TEST_TIME 94 9062
TEST_TIME 95 9015
TEST_TIME 96 9000
TEST_TIME 97 8971
TEST_TIME 98 8958
TEST_TIME 99 8994
TEST_TIME 100 8997
TEST_TIME 101 8968
TEST_TIME 102 8968
TEST_TIME 103 9012
TEST_TIME 104 8995
TEST_TIME 105 9027
TEST_TIME 106 9053
TEST_TIME 107 9068
TEST_TIME 108 9016
TEST_TIME 109 8943
TEST_TIME 110 8900
TEST_TIME 111 8875
TEST_TIME 112 8857
TEST_TIME 113 8828
TEST_TIME 114 8818
TEST_TIME 115 8803
TEST_TIME 116 8792
TEST_TIME 117 8817
TEST_TIME 118 8808
TEST_TIME 119 8779
TEST_TIME 120 8805

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2020 10:27:59 to 18-12-2020 10:28:02.

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 1461412 1461412 0 0.00 0 34692 7 0 0
doc_list_biud 1461412 1461412 0 0.00 0 27281 32 0 0
sp_add_invoice_to_stock 4772 4686 86 1.80 40 174940 17994 0 86
sp_cancel_adding_invoice 5462 5462 0 0.00 4 102317 848 0 0
sp_cancel_client_order 1600 1600 0 0.00 4 10976 313 0 0
sp_cancel_customer_reserve 534632 534632 0 0.00 1 25414 69 0 0
sp_cancel_pay_from_customer 604 604 0 0.00 3 5108 68 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4040 87 0 0
sp_cancel_supplier_invoice 6068 6062 6 0.09 2 27289 949 0 6
sp_cancel_supplier_order 394 394 0 0.00 8 1046285 118119 0 0
sp_cancel_write_off 10852 10852 0 0.00 1 10715 114 0 0
sp_client_order 19248 19248 0 0.00 4 24283 435 0 0
sp_customer_reserve 439053 439053 0 0.00 0 78564 173 0 0
sp_fill_shopping_cart 29457 29457 0 0.00 1 19224 50 0 0
sp_fill_shopping_cart_clo_res 439048 439048 0 0.00 0 28851 20 0 0
sp_get_clo_for_invoice 4681 4681 0 0.00 0 48821 498 0 0
sp_kill_cost_storno 17336 17336 0 0.00 0 8863 55 0 0
sp_kill_qstorno_ret_qs2qd 1086572 1086572 0 0.00 0 27280 37 0 0
sp_kill_qty_storno 573029 573029 0 0.00 0 27281 80 0 0
sp_lock_selected_doc 19416 19416 0 0.00 0 8649 6 0 0
sp_make_cost_storno 26301 26301 0 0.00 0 34684 66 0 0
sp_multiply_rows_for_pdistr 26301 26301 0 0.00 0 20142 34 0 0
sp_multiply_rows_for_qdistr 19248 19248 0 0.00 0 24273 265 0 0
sp_pay_from_customer 6676 6676 0 0.00 4 25890 113 0 0
sp_pay_to_supplier 5598 5598 0 0.00 5 31872 151 0 0
sp_payment_common 12274 12274 0 0.00 1 21102 34 0 0
sp_qd_handle_on_cancel_clo 1600 1600 0 0.00 0 7666 270 0 0
sp_qd_handle_on_invoice_upd_sts 10143 10143 0 0.00 0 26028 365 0 0
sp_qd_handle_on_reserve_upd_sts 9346 9346 0 0.00 0 12420 52 0 0
sp_reserve_write_off 9346 9346 0 0.00 7 26631 185 0 0
sp_supplier_invoice 5077 5077 0 0.00 7 93388 3377 0 0
sp_supplier_order 5136 5136 0 0.00 5 32193 1341 0 0
srv_aggregate_perf_data 111 111 0 0.00 2798 108226 12241 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1147 157732 50740 0 0
srv_find_qd_qs_mism 1010573 1010573 0 0.00 0 22308 6 0 0
srv_make_invnt_saldo 480 480 0 0.00 41 80776 2936 0 0
srv_make_money_saldo 674 674 0 0.00 1 14354 521 0 0
srv_recalc_idx_stat 4 4 0 0.00 72838 566825 331954 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 0 35713 176 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 5724 91 0 0
t$perf-norm:sp_cancel_client_order 1600 1600 0 0.00 0 2149 14 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 3022 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 604 604 0 0.00 0 2198 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1457 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1332 21 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 14485 1483 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 7972 14 0 0
t$perf-norm:sp_client_order 19248 19248 0 0.00 0 24613 16 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 4960 10 0 0
t$perf-norm:sp_pay_from_customer 6676 6676 0 0.00 0 5028 8 0 0
t$perf-norm:sp_pay_to_supplier 5598 5598 0 0.00 0 7320 9 0 0
t$perf-norm:sp_reserve_write_off 9346 9346 0 0.00 0 5148 9 0 0
t$perf-norm:sp_supplier_invoice 5077 5077 0 0.00 0 5286 12 0 0
t$perf-norm:sp_supplier_order 5136 5136 0 0.00 0 5792 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 651 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 4401 75 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 2821 12 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 1359 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9534 9362 172 1.80 0 7994 17 0 172
v_all_customers 35288 35288 0 0.00 0 78 1 0 0
v_all_suppliers 28812 28812 0 0.00 0 30 1 0 0
v_all_wares 38496 38496 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2477 9 0 0
v_cancel_client_order 3200 3200 0 0.00 0 3157 8 0 0
v_cancel_customer_prepayment 1208 1208 0 0.00 0 3228 8 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 878 3 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 2387 12 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 992 15 0 12
v_cancel_supplier_order 788 788 0 0.00 0 716 5 0 0
v_cancel_write_off 1896 1896 0 0.00 0 4168 13 0 0
v_max_id_clo_ord 5134 5134 0 0.00 0 7469 3 0 0
v_max_id_clo_res 931 931 0 0.00 0 1676 11 0 0
v_max_id_ord_sup 5073 5073 0 0.00 0 5024 10 0 0
v_max_non_paid_invoice 1399 1399 0 0.00 0 3358 4 0 0
v_max_non_paid_realizn 3411 3411 0 0.00 0 763 3 0 0
v_min_id_clo_ord 5134 5134 0 0.00 0 5287 9 0 0
v_min_id_clo_res 931 931 0 0.00 0 6041 13 0 0
v_min_id_ord_sup 5073 5073 0 0.00 0 7714 19 0 0
v_min_non_paid_invoice 5598 5598 0 0.00 0 21096 33 0 0
v_min_non_paid_realizn 6676 6676 0 0.00 0 20094 23 0 0
v_our_firm 9738 9738 0 0.00 0 13 1 0 0
v_reserve_write_off 18692 18692 0 0.00 0 4159 4 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 18679 111 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 9215 334 0 0
x_make_qstorno_1000_1200 5136 5136 0 0.00 2 32188 1263 0 0
x_make_qstorno_1200_2000 5077 5077 0 0.00 3 84690 3173 0 0
x_make_qstorno_2100_3300 439053 439053 0 0.00 0 78564 151 0 0

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

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
07:56:56 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:57 14432 8192000000 9718887024 10011811840 800389456 8.900 100 90 181 1 830194016 7671440 22133120
07:59:20 22893 8192000000 9770096944 10075607040 840940144 9.309 100 91 182 0 873998896 11736080 21322672
08:00:45 17239 8192000000 9803529824 10121175040 872464416 9.625 100 82 164 0 911586640 20117760 19004464
08:02:04 17141 8192000000 9865559120 10193608704 911465008 10.012 100 79 155 1 969846624 41688336 16693280
08:03:31 11601 8192000000 9917756320 10259193856 953280544 10.423 100 77 153 2 1028571616 59443008 15848064
08:04:44 7080 8192000000 9956178512 10313826304 986254384 10.745 100 66 132 0 1071958288 72492976 13210928
08:05:54 4331 8192000000 9994953696 10356469760 1018389712 11.056 100 71 142 0 1112126912 79246000 14491200
08:06:59 1998 8192000000 9995400240 10360815616 1047632944 11.338 100 86 170 3 1113184400 48035728 17515728
08:08:03 1531 8192000000 10046955056 10419159040 1066306032 11.517 100 87 172 2 1159004432 75041792 17656608
08:09:05 3464 8192000000 9983574704 10364358656 1072595168 11.577 100 63 127 2 1100186752 12638512 14953072
08:10:09 10973 8192000000 9978813152 10372706304 1077431520 11.623 100 36 72 2 1110632256 24804976 8395760
08:11:26 4954 8192000000 9996458896 10388152320 1086140864 11.706 100 37 73 3 1109612016 14940000 8531152
08:12:32 5132 8192000000 9994077968 10393272320 1094180640 11.782 100 32 64 4 1113206048 11953216 7072192
08:13:38 31549 8192000000 10020427072 10410967040 1095792816 11.798 100 66 135 4 1124886752 14985136 14108800
08:15:10 4409 8192000000 10012940816 10416492544 1103567488 11.871 100 35 70 2 1126383744 15209904 7606352
08:16:18 9036 8192000000 10012802816 10424516608 1091603888 11.758 99 29 57 2 1132258992 34112576 6542528
08:17:29 8387 8192000000 9996976784 10394669056 1080947968 11.657 99 39 80 3 1101833008 11676976 9208064
08:18:39 36370 8192000000 9976448912 10373120000 977324224 10.658 91 28 56 0 995614768 12219088 6071456
08:20:18 8283 8192000000 9902475728 10273005568 943531616 10.328 92 39 79 1 978868736 26841712 8495408
08:21:28 7528 8192000000 9925991632 10300497920 988438816 10.766 97 30 61 0 1021196288 26304736 6452736
08:22:37 66274 8192000000 9944234896 10333376512 1018936448 11.062 98 32 65 2 1055623344 30311104 6375792
08:24:49 9937 8192000000 9977510448 10376130560 1057699088 11.434 100 34 68 6 1088355904 23530208 7126608
08:25:59 6989 8192000000 9985279168 10375737344 1070501472 11.557 100 44 84 3 1101328624 21343488 9483664
08:27:12 7756 8192000000 10013057088 10406993920 1092440528 11.766 100 45 91 3 1122210272 19692656 10077088
08:28:21 29015 8192000000 10037008928 10432954368 1104080528 11.876 100 41 82 0 1143569920 30277664 9211728
08:29:54 17784 8192000000 10067583392 10480816128 1115695648 11.986 100 29 58 3 1173688528 51728800 6264080
08:31:15 3981 8192000000 10080247584 10483970048 1127050672 12.094 100 51 100 0 1197970256 60425104 10494480
08:32:24 18395 8192000000 10134869264 10541035520 1137429200 12.191 100 56 112 1 1238061824 89112112 11520512
08:33:55 38870 8192000000 10158031008 10581651456 1150611920 12.315 100 49 98 3 1262034640 99854800 11567920
08:35:40 7828 8192000000 10205167296 10610356224 1157651104 12.381 100 54 108 3 1306403760 136427648 12325008
08:36:55 2610 8192000000 10211546784 10622812160 1162047696 12.422 99 84 164 2 1323772928 142517984 19207248
08:38:01 3588 8192000000 10241990032 10655145984 1170816704 12.504 100 59 117 2 1345833296 160877456 14139136
08:39:13 5214 8192000000 10276932432 10689712128 1179947312 12.590 100 56 112 1 1375197664 183207392 12042960
08:40:38 9952 8192000000 10324551728 10740674560 1180452320 12.594 100 56 112 1 1428067248 233774816 13840112
08:41:57 1821 8192000000 10351226400 10767110144 1178534416 12.577 100 74 149 0 1450541728 254429744 17577568
08:43:03 1384 8192000000 10416404272 10817343488 1184344784 12.631 100 87 174 0 1509858960 305320592 20193584
08:44:13 3759 8192000000 10436810768 10842206208 1175440960 12.548 100 74 148 0 1532089344 338599152 18049232
08:45:20 1142 8192000000 10459570176 10873470976 1151055728 12.319 99 88 177 3 1548115232 375419168 21640336
08:46:25 7830 8192000000 10410435440 10866962432 1151995456 12.328 99 42 84 1 1513122112 350352512 10774144
08:47:38 17873 8192000000 10439474352 10870349824 1143641904 12.250 100 37 74 0 1541009424 387352352 10015168
08:49:05 19970 8192000000 10435940080 10883358720 1129667136 12.118 99 36 72 0 1532995632 394144528 9183968
08:50:44 21005 8192000000 10459735200 10895028224 1109281632 11.926 99 24 48 0 1536790128 420704528 6803968
08:52:13 14993 8192000000 10383924976 10880782336 1114941680 11.979 100 30 60 1 1512360112 389302272 8116160
08:53:29 73942 8192000000 10076747504 10532139008 1110072640 11.933 99 24 46 1 1166804384 51709536 5022208
08:55:45 33531 8192000000 10049383600 10482294784 1114549232 11.975 99 35 69 3 1147763856 26867136 6347488
08:57:21 57185 8192000000 10076960816 10516328448 1119657056 12.024 99 9 18 0 1173956000 52650944 1648000
08:59:19 97046 8192000000 10142225456 10582413312 1099448048 11.832 97 12 24 0 1189322384 87364112 2510224
09:01:57 86847 8192000000 10078858272 10530488320 1101736848 11.854 97 11 22 0 1176590544 72111088 2742608
09:04:24 93217 8192000000 10058570512 10499653632 1122567392 12.051 100 22 44 2 1143403104 16311104 4524608
09:06:58 73533 8192000000 10098208704 10591805440 1137465632 12.192 100 17 34 1 1198335552 57150416 3719504
09:09:22 95225 8192000000 10103765312 10597068800 1116200592 11.991 98 24 48 1 1213617760 92098464 5318704
09:12:03 118755 8192000000 10136290944 10568396800 1133989360 12.159 99 18 37 0 1198698592 60647984 4061248
09:15:03 67989 8192000000 10179633264 10618142720 1108611232 11.919 96 19 38 1 1217950560 105347280 3992048
09:17:16 82616 8192000000 10077298176 10552651776 1126019104 12.084 99 11 22 0 1170151008 41955984 2175920
09:19:42 20485 8192000000 10106580720 10540802048 1147477200 12.286 100 35 70 0 1182246304 27764800 7004304
09:21:08 146510 8192000000 10115650912 10566799360 1149144384 12.301 100 13 26 0 1179499824 27805616 2549824
09:24:45 65800 8192000000 10080670464 10569023488 1143961488 12.253 99 7 14 0 1197716080 52260864 1493728
09:26:56 66064 8192000000 10097101840 10546524160 1108847696 11.922 96 18 36 1 1183816848 71103488 3865664
09:29:04 37742 8192000000 10107998880 10551201792 1137326960 12.190 99 18 36 2 1200659648 59844688 3488000
09:30:42 49055 8192000000 10113597440 10560610304 1138899632 12.205 98 16 32 0 1203575312 61433936 3241744
09:32:31 45311 8192000000 10150954480 10592645120 1126438400 12.088 98 22 44 1 1214415136 83747504 4229232
09:34:38 108651 8192000000 10087223728 10527559680 1072515168 11.576 93 16 32 2 1108067184 32578976 2973040
09:37:36 64646 8192000000 10083536464 10528247808 1081102240 11.658 95 11 22 0 1121990544 38711184 2177120
09:40:06 157732 8192000000 10049356656 10539028480 1102447952 11.861 98 21 42 1 1147855600 41438880 3968768
09:43:49 75748 8192000000 10063471696 10520092672 1104377824 11.879 98 14 27 0 1161218704 53824240 3016640
09:46:10 56957 8192000000 10039298960 10477527040 1089016880 11.733 97 15 30 0 1132874976 40245792 3612304
09:48:09 86806 8192000000 10086158224 10527817728 1101410528 11.851 97 24 47 2 1169771552 63350848 5010176
09:50:44 59353 8192000000 10140059264 10584350720 1111984704 11.951 98 28 56 0 1206523712 88514192 6024816
09:52:43 115304 8192000000 10116251392 10564345856 1120193328 12.029 99 20 40 1 1240057088 115004624 4859136
09:55:48 21273 8192000000 10059905968 10479755264 1095524912 11.795 97 34 68 0 1120680608 18138864 7016832
09:57:11 45381 8192000000 10148251904 10584866816 1135341600 12.172 100 55 110 0 1237957456 92024864 10590992
10:01:01 74295 8192000000 10072964624 10539433984 1099646832 11.834 98 8 16 0 1128180096 26948368 1584896
10:03:34 53304 8192000000 10095150832 10528829440 1109431536 11.927 99 20 41 1 1168562864 55169856 3961472
10:05:31 106488 8192000000 10047048880 10491777024 1070344912 11.555 95 13 26 0 1116994048 43974016 2675120
10:08:23 23179 8192000000 10108116400 10536898560 1122655568 12.052 99 55 111 1 1187862048 53571680 11634800
10:09:56 32459 8192000000 10061600080 10479185920 1111975136 11.951 99 38 74 0 1147239264 27664192 7599936
10:11:39 45912 8192000000 10067731008 10553806848 1093100240 11.772 98 10 20 0 1145138032 49717440 2320352
10:13:25 72480 8192000000 10061323168 10481229824 1111020240 11.942 99 16 32 2 1154541280 40039376 3481664
10:15:44 51713 8192000000 10056874320 10502516736 1132583520 12.146 100 17 34 2 1169992048 33763680 3644848
10:17:46 77533 8192000000 10057341360 10552356864 1120401872 12.031 99 4 8 0 1160188784 38962064 824848
10:20:04 48151 8192000000 10078029424 10530504704 1123893344 12.064 99 25 51 2 1178292320 49439168 4959808
10:21:56 85340 8192000000 10092658640 10558373888 1114531424 11.975 98 18 36 1 1178471424 60027600 3912400
10:24:26 38924 8192000000 10085762768 10513330176 1110286368 11.935 98 45 89 2 1150021184 30525616 9209200
10:26:06 47821 8192000000 10108590480 10559811584 1087651376 11.720 96 31 62 2 1170609072 77098432 5859264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2020 10:28:09 to 18-12-2020 10:28:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2020 10:28:25 to 18-12-2020 10:28:27.

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          226446
MON$OLDEST_ACTIVE               228644
MON$OLDEST_SNAPSHOT             228644
MON$NEXT_TRANSACTION            228644
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                       2075872
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2298 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2298 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.2298 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Dec 18 10:27:01 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Fri Dec 18 10:27:06 2020
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Fri Dec 18 10:27:07 2020
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
--- end of diff output ---

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

18.12.20 10:28:28. 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-12-18 10:28:30 No severe PSQL-related problems occured
18.12.20 10:28:31. End of report.