Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2020 04: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 16574252 kB
MemAvailable 31287416 kB
Buffers 20 kB
Cached 14910716 kB
SwapCached 4820 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-28 04:26:53
DTS_END                         2020-12-28 04:26:53.8350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40266
TRN_ID                          238837
ATT_ID                          672
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: 8947.56 21420 1073707
customer order: creation 171.86 391 20623
customer order: refuse 14.19 290 1703
order to supplier: creation 45.52 1436 5462
order to supplier: removal 3.34 108978 401
invoice (draft): creation 45.65 3662 5478
invoice (draft): removal 48.60 869 5832
invoice accept: apply 41.51 16938 4981
invoice accept: cancel 44.13 1006 5295
customer reserve: creation 3867.12 165 464054
customer reserve: removal 4376.42 67 525170
realization accept: apply 83.19 181 9983
realization accept: cancel 79.72 112 9566
payment from customer: creation 58.56 129 7027
payment from customer: removal 5.55 119 666
payment to supplier: creation 48.18 117 5781
payment to supplier: removal 3.23 130 388
service: total inventory turnovers 3.68 3494 441
service: total monetary turnovers 6.07 597 728
service: aggregate perf. data 1.03 14129 124
service: refresh index statistics 0.03 275584 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2020 04:27:27 to 28-12-2020 04:27:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6872
TEST_TIME 2 4803
TEST_TIME 3 4753
TEST_TIME 4 4397
TEST_TIME 5 5236
TEST_TIME 6 5302
TEST_TIME 7 5459
TEST_TIME 8 5811
TEST_TIME 9 5971
TEST_TIME 10 6104
TEST_TIME 11 6233
TEST_TIME 12 6249
TEST_TIME 13 6434
TEST_TIME 14 6838
TEST_TIME 15 7032
TEST_TIME 16 7504
TEST_TIME 17 7738
TEST_TIME 18 7850
TEST_TIME 19 7695
TEST_TIME 20 7834
TEST_TIME 21 7627
TEST_TIME 22 7941
TEST_TIME 23 8249
TEST_TIME 24 8423
TEST_TIME 25 8605
TEST_TIME 26 8761
TEST_TIME 27 9153
TEST_TIME 28 9181
TEST_TIME 29 9461
TEST_TIME 30 10301
TEST_TIME 31 10544
TEST_TIME 32 10365
TEST_TIME 33 10607
TEST_TIME 34 10581
TEST_TIME 35 10514
TEST_TIME 36 10422
TEST_TIME 37 10195
TEST_TIME 38 9973
TEST_TIME 39 9980
TEST_TIME 40 9963
TEST_TIME 41 9894
TEST_TIME 42 9890
TEST_TIME 43 9768
TEST_TIME 44 9744
TEST_TIME 45 9645
TEST_TIME 46 9533
TEST_TIME 47 9643
TEST_TIME 48 9605
TEST_TIME 49 9636
TEST_TIME 50 9617
TEST_TIME 51 9571
TEST_TIME 52 9544
TEST_TIME 53 9579
TEST_TIME 54 9559
TEST_TIME 55 9500
TEST_TIME 56 9419
TEST_TIME 57 9451
TEST_TIME 58 9410
TEST_TIME 59 9410
TEST_TIME 60 9510
TEST_TIME 61 9478
TEST_TIME 62 9440
TEST_TIME 63 9474
TEST_TIME 64 9490
TEST_TIME 65 9584
TEST_TIME 66 9549
TEST_TIME 67 9440
TEST_TIME 68 9370
TEST_TIME 69 9314
TEST_TIME 70 9229
TEST_TIME 71 9143
TEST_TIME 72 9113
TEST_TIME 73 9121
TEST_TIME 74 9064
TEST_TIME 75 9069
TEST_TIME 76 9019
TEST_TIME 77 9021
TEST_TIME 78 9054
TEST_TIME 79 9058
TEST_TIME 80 9094
TEST_TIME 81 9085
TEST_TIME 82 9062
TEST_TIME 83 9101
TEST_TIME 84 9122
TEST_TIME 85 9199
TEST_TIME 86 9098
TEST_TIME 87 9029
TEST_TIME 88 8954
TEST_TIME 89 8958
TEST_TIME 90 8935
TEST_TIME 91 8892
TEST_TIME 92 8872
TEST_TIME 93 8887
TEST_TIME 94 8899
TEST_TIME 95 8935
TEST_TIME 96 8929
TEST_TIME 97 8971
TEST_TIME 98 8967
TEST_TIME 99 9039
TEST_TIME 100 9083
TEST_TIME 101 9032
TEST_TIME 102 9062
TEST_TIME 103 9101
TEST_TIME 104 9131
TEST_TIME 105 9135
TEST_TIME 106 9053
TEST_TIME 107 8994
TEST_TIME 108 8958
TEST_TIME 109 8956
TEST_TIME 110 8916
TEST_TIME 111 8923
TEST_TIME 112 8923
TEST_TIME 113 8915
TEST_TIME 114 8925
TEST_TIME 115 8914
TEST_TIME 116 8906
TEST_TIME 117 8916
TEST_TIME 118 8892
TEST_TIME 119 8935
TEST_TIME 120 8952

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2020 04:27:30 to 28-12-2020 04:27:34.

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 1502674 1502674 0 0.00 0 25481 7 0 0
doc_list_biud 1502674 1502674 0 0.00 0 58226 30 0 0
sp_add_invoice_to_stock 5122 4981 141 2.75 39 197378 16939 0 141
sp_cancel_adding_invoice 5295 5295 0 0.00 5 65309 1006 0 0
sp_cancel_client_order 1703 1703 0 0.00 5 11726 291 0 0
sp_cancel_customer_reserve 525170 525170 0 0.00 1 53867 68 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 9737 119 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 7996 130 0 0
sp_cancel_supplier_invoice 5842 5832 10 0.17 2 58257 870 0 10
sp_cancel_supplier_order 401 401 0 0.00 4 952034 108979 0 0
sp_cancel_write_off 9566 9566 0 0.00 1 10538 113 0 0
sp_client_order 20623 20623 0 0.00 4 31648 392 0 0
sp_customer_reserve 464054 464054 0 0.00 0 62456 165 0 0
sp_fill_shopping_cart 31552 31552 0 0.00 1 14662 41 0 0
sp_fill_shopping_cart_clo_res 464049 464049 0 0.00 0 21968 15 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 39281 360 0 0
sp_kill_cost_storno 15915 15915 0 0.00 0 6524 59 0 0
sp_kill_qstorno_ret_qs2qd 1066139 1066139 0 0.00 0 58224 36 0 0
sp_kill_qty_storno 562923 562923 0 0.00 0 58224 78 0 0
sp_lock_selected_doc 20684 20684 0 0.00 0 6686 4 0 0
sp_make_cost_storno 27764 27764 0 0.00 0 25262 59 0 0
sp_multiply_rows_for_pdistr 27764 27764 0 0.00 0 25192 31 0 0
sp_multiply_rows_for_qdistr 20623 20623 0 0.00 0 14461 188 0 0
sp_pay_from_customer 7027 7027 0 0.00 4 25201 129 0 0
sp_pay_to_supplier 5781 5781 0 0.00 4 9477 118 0 0
sp_payment_common 12808 12808 0 0.00 1 6484 21 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 11718 265 0 0
sp_qd_handle_on_invoice_upd_sts 10268 10268 0 0.00 0 45289 386 0 0
sp_qd_handle_on_reserve_upd_sts 9983 9983 0 0.00 0 25285 49 0 0
sp_reserve_write_off 9983 9983 0 0.00 7 25388 182 0 0
sp_supplier_invoice 5478 5478 0 0.00 7 114779 3663 0 0
sp_supplier_order 5462 5462 0 0.00 6 61529 1437 0 0
srv_aggregate_perf_data 124 124 0 0.00 3087 331550 14129 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 986 118491 42101 0 0
srv_find_qd_qs_mism 1025875 1025875 0 0.00 0 25479 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 67 94580 3494 0 0
srv_make_money_saldo 728 728 0 0.00 1 28642 598 0 0
srv_recalc_idx_stat 4 4 0 0.00 57322 464346 275585 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 0 13578 158 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 5174 124 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 1742 8 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 944 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 939 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 853 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1865 29 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 16173 1394 0 0
t$perf-norm:sp_cancel_write_off 1073 1073 0 0.00 0 1401 6 0 0
t$perf-norm:sp_client_order 20623 20623 0 0.00 0 29387 20 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 5673 9 0 0
t$perf-norm:sp_pay_from_customer 7027 7027 0 0.00 0 3902 8 0 0
t$perf-norm:sp_pay_to_supplier 5781 5781 0 0.00 0 2944 7 0 0
t$perf-norm:sp_reserve_write_off 9983 9983 0 0.00 0 27773 15 0 0
t$perf-norm:sp_supplier_invoice 5478 5478 0 0.00 0 22989 21 0 0
t$perf-norm:sp_supplier_order 5462 5462 0 0.00 0 24209 21 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 165 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 742 9 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 675 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10224 9942 282 2.75 0 7255 22 0 282
v_all_customers 36974 36974 0 0.00 0 38 1 0 0
v_all_suppliers 30236 30236 0 0.00 0 119 1 0 0
v_all_wares 41244 41244 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 6313 13 0 0
v_cancel_client_order 3406 3406 0 0.00 0 5523 6 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 7100 24 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 3912 10 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 7615 21 0 0
v_cancel_supplier_invoice 810 790 20 2.46 0 6163 35 0 20
v_cancel_supplier_order 802 802 0 0.00 0 298 4 0 0
v_cancel_write_off 2146 2146 0 0.00 0 3837 17 0 0
v_max_id_clo_ord 5461 5461 0 0.00 0 1066 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 2024 14 0 0
v_max_id_ord_sup 5469 5469 0 0.00 0 8558 6 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 399 2 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 1840 3 0 0
v_min_id_clo_ord 5461 5461 0 0.00 0 2076 5 0 0
v_min_id_clo_res 991 991 0 0.00 0 5196 15 0 0
v_min_id_ord_sup 5469 5469 0 0.00 0 6095 9 0 0
v_min_non_paid_invoice 5781 5781 0 0.00 0 5349 13 0 0
v_min_non_paid_realizn 7027 7027 0 0.00 0 6467 16 0 0
v_our_firm 10484 10484 0 0.00 0 38 1 0 0
v_reserve_write_off 19966 19966 0 0.00 0 5233 8 0 0
x_lock_depdocs_on_canc_invoice 10732 10732 0 0.00 0 15792 118 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 7886 324 0 0
x_make_qstorno_1000_1200 5462 5462 0 0.00 2 61521 1359 0 0
x_make_qstorno_1200_2000 5478 5478 0 0.00 3 109999 3507 0 0
x_make_qstorno_2100_3300 464054 464054 0 0.00 0 62454 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2020 04:27:35 to 28-12-2020 04:27:39.

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:57 9 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:57:58 27374 8192000000 9703027152 9997369344 784415808 8.738 100 80 160 0 812393296 7939488 20038000
01:59:30 33937 8192000000 9756579008 10061651968 827868592 9.178 100 86 172 0 861711744 13200704 20642448
02:01:07 15062 8192000000 9794519840 10112430080 858278400 9.483 100 83 166 2 912071168 34428304 19364464
02:02:41 8728 8192000000 9888355360 10224680960 927050784 10.166 100 86 170 1 998717264 52824400 18842080
02:03:52 5560 8192000000 9929182064 10278068224 975506288 10.640 100 78 154 1 1044720592 53052448 16161856
02:04:59 4787 8192000000 10001935536 10359234560 1004352064 10.921 100 91 180 2 1116724688 94328048 18044576
02:06:05 2434 8192000000 10015516176 10376953856 1039698464 11.262 100 84 166 1 1123266848 65917520 17650864
02:07:09 1764 8192000000 10018025712 10390814720 1058139040 11.439 100 81 162 0 1131262576 56712544 16410992
02:08:12 7488 8192000000 9993341376 10389811200 1066256240 11.516 100 33 66 1 1129528672 56959200 6313232
02:09:20 42620 8192000000 9984011264 10379423744 1074485712 11.595 100 44 86 1 1124096464 39860608 9750144
02:11:22 4525 8192000000 10026398176 10418188288 1089110016 11.734 100 50 97 4 1135000048 35016608 10873424
02:12:27 4786 8192000000 10002360160 10403627008 1090129232 11.744 100 39 77 1 1119284784 20591472 8564080
02:13:35 5634 8192000000 10009519312 10412560384 1095438608 11.794 100 41 81 4 1137498960 33028688 9031664
02:14:40 33725 8192000000 10013425392 10431225856 1096294304 11.802 100 28 55 1 1128893232 26456272 6142656
02:16:18 6404 8192000000 10001502528 10407567360 1092185424 11.763 100 22 42 1 1119780864 23004000 4591440
02:17:26 4594 8192000000 9995503072 10382151680 1068369216 11.537 99 37 73 3 1102051248 24513952 9168080
02:18:31 4012 8192000000 9945265648 10330193920 1028981232 11.159 99 46 88 2 1054962816 15554512 10427072
02:19:38 53455 8192000000 9935963728 10332282880 977636064 10.661 94 17 34 2 991864640 10123536 4105040
02:21:31 4611 8192000000 9923404608 10291286016 997745920 10.857 99 37 71 4 1018979920 13150656 8083344
02:22:36 5734 8192000000 9949138208 10335801344 1041823520 11.282 100 48 95 1 1063664752 9998000 11843232
02:23:49 5443 8192000000 9979381792 10366124032 1058179072 11.439 100 40 80 2 1092943472 25776208 8988192
02:24:55 30536 8192000000 9980938976 10381869056 1066423520 11.518 100 27 54 1 1096882176 24229376 6229280
02:26:29 3900 8192000000 10009154304 10396897280 1092491040 11.766 100 39 76 1 1115682032 15248960 7942032
02:27:39 13489 8192000000 10055389680 10453274624 1110835120 11.940 100 59 117 0 1151997776 28198976 12963680
02:28:56 17567 8192000000 10083555232 10490228736 1122861872 12.054 100 46 92 0 1191865600 59348288 9655440
02:30:23 71405 8192000000 10111911584 10530467840 1130594896 12.127 100 42 84 4 1223984848 83520128 9869824
02:32:47 23845 8192000000 10128167072 10537099264 1151233024 12.321 100 57 111 1 1236432816 73322064 11877728
02:34:16 11918 8192000000 10155922480 10572873728 1153291568 12.340 100 57 114 1 1265241648 99521776 12428304
02:35:31 4649 8192000000 10166879040 10585829376 1162746960 12.429 100 50 100 2 1276669216 102724720 11197536
02:36:44 3351 8192000000 10165608576 10578464768 1160980560 12.412 99 71 133 3 1265555456 90137312 14437584
02:37:51 6177 8192000000 10212217504 10626781184 1174299616 12.537 100 63 126 2 1317901952 129503392 14098944
02:39:12 6522 8192000000 10222215248 10635886592 1153210592 12.340 99 57 114 1 1310597888 144855360 12531936
02:40:24 2123 8192000000 10243357680 10652200960 1168315520 12.481 100 75 145 3 1347456720 162466752 16674448
02:41:29 3375 8192000000 10228480192 10637152256 1146184432 12.274 100 86 175 0 1340165056 174331792 19648832
02:42:38 1611 8192000000 10238421232 10636324864 1148079680 12.291 100 82 164 0 1336440000 170826320 17534000
02:43:42 1437 8192000000 10223106832 10613964800 1121967008 12.046 98 80 160 0 1315416800 174294560 19155232
02:44:46 12730 8192000000 10220149360 10636943360 1120185264 12.029 100 49 98 0 1323585680 192037968 11362448
02:46:05 3179 8192000000 10286563648 10694160384 1124536288 12.070 100 71 143 1 1386280912 245657488 16087136
02:47:12 3248 8192000000 10302063424 10702004224 1134647280 12.165 100 64 126 1 1401060112 251029120 15383712
02:48:22 983 8192000000 10351246560 10769498112 1143111824 12.245 100 95 188 1 1448671664 283061008 22498832
02:49:28 11775 8192000000 10317011792 10754469888 1150043712 12.310 100 40 79 2 1449129984 289240944 9845328
02:50:45 9563 8192000000 10344009264 10799996928 1142895312 12.243 100 42 83 1 1439023504 285756992 10371200
02:52:00 11637 8192000000 10327932272 10785079296 1152195360 12.330 100 34 68 0 1431937312 270798208 8943744
02:53:18 13472 8192000000 10325811696 10773860352 1133716464 12.156 99 38 76 1 1439564816 296667840 9180512
02:54:36 24711 8192000000 10317654384 10750181376 1126528560 12.089 99 26 52 0 1431435392 298248096 6658736
02:56:04 27444 8192000000 10318288768 10761744384 1121723504 12.043 100 14 28 0 1411748624 285738640 4286480
02:57:32 52465 8192000000 10079534976 10491695104 1112122288 11.953 98 29 56 1 1170552752 51551840 6878624
02:59:26 72513 8192000000 10068793120 10536632320 1111601120 11.948 98 6 12 1 1160994768 48553456 840192
03:01:39 51346 8192000000 10137312416 10559946752 1141024240 12.225 99 28 55 0 1212188800 65761312 5403248
03:03:37 111542 8192000000 10146464000 10587394048 1143731712 12.251 100 45 89 1 1252335280 98214640 10388928
03:06:31 54175 8192000000 10129587792 10565341184 1142199184 12.236 99 32 63 4 1220611264 71776288 6635792
03:08:32 38711 8192000000 10112574704 10563145728 1155768832 12.364 100 17 32 2 1229400528 70301568 3330128
03:10:15 61054 8192000000 10133155328 10579238912 1147224800 12.283 99 12 24 0 1229963104 80050512 2687792
03:13:07 43864 8192000000 10170634864 10641498112 1152571008 12.334 100 14 28 2 1281140880 125063760 3506112
03:15:11 54090 8192000000 10111867360 10622054400 1156600336 12.371 100 8 16 0 1247688912 89436512 1652064
03:17:44 59403 8192000000 10098066976 10569334784 1156707504 12.372 100 17 34 4 1179992912 19938224 3347184
03:19:43 84987 8192000000 10114295088 10615816192 1164526688 12.446 100 10 20 1 1212893264 46264912 2101664
03:22:16 59013 8192000000 10147624448 10599825408 1150025696 12.310 98 28 56 1 1230101872 74356864 5719312
03:24:16 49390 8192000000 10097488768 10548273152 1141511248 12.230 98 36 72 4 1178769120 28828592 8429280
03:26:05 70443 8192000000 10079931392 10522165248 1110440608 11.937 96 18 36 0 1164030128 50214080 3375440
03:28:16 16343 8192000000 10077335200 10498195456 1122101920 12.047 99 35 69 1 1166818880 38006736 6710224
03:29:33 41943 8192000000 10086508432 10525114368 1103133088 11.867 97 27 54 1 1157735088 49227680 5374320
03:31:15 62375 8192000000 10039174640 10511355904 1068768432 11.540 95 9 18 0 1107604400 37085776 1750192
03:33:22 70516 8192000000 10047521088 10506547200 1114905744 11.979 100 15 30 0 1156918304 39261872 2750688
03:35:38 51359 8192000000 10045063552 10493210624 1109828720 11.931 99 9 18 0 1140249120 28702928 1717472
03:37:42 61844 8192000000 10018606416 10479525888 1083179024 11.678 97 11 22 0 1125220336 39510432 2530880
03:40:07 45559 8192000000 10033451104 10462126080 1081965520 11.666 97 19 38 2 1115732144 29966304 3800320
03:41:55 64754 8192000000 10057155664 10485669888 1068727776 11.540 96 27 53 0 1112979232 38886192 5365264
03:44:47 74584 8192000000 10069949408 10497236992 1091132992 11.753 99 15 30 0 1161994736 67763008 3098736
03:47:01 57417 8192000000 10066081232 10480988160 1108052560 11.914 99 40 80 0 1134077968 17706704 8318704
03:49:12 54480 8192000000 10074484768 10519031808 1096741344 11.807 97 29 58 3 1140955488 38089216 6124928
03:51:09 73496 8192000000 10043167856 10513752064 1122806176 12.053 100 10 20 1 1184548352 59621840 2120336
03:54:43 45915 8192000000 10093692416 10552295424 1134289840 12.162 100 11 22 1 1209053888 72466560 2297488
03:56:29 52721 8192000000 10114026688 10553950208 1142426800 12.238 100 13 26 1 1206444864 61444576 2573488
03:58:26 48520 8192000000 10125678080 10582990848 1142281472 12.237 99 18 35 1 1224821680 78458432 4081776
04:00:19 77120 8192000000 10115330864 10578178048 1140768496 12.223 99 24 48 1 1238769360 92500496 5500368
04:02:49 72728 8192000000 10127260400 10591584256 1158245776 12.387 100 13 26 0 1239197712 78040432 2911504
04:05:03 74522 8192000000 10117412096 10556809216 1135710032 12.175 98 18 35 3 1194033280 54654608 3668640
04:07:18 43960 8192000000 10110198464 10537140224 1123702000 12.062 97 40 80 1 1168368896 36258352 8408544
04:09:03 46752 8192000000 10140650528 10619183104 1141928144 12.234 99 12 24 0 1213674368 69361680 2384544
04:10:58 118490 8192000000 10110007264 10598637568 1138746960 12.204 99 18 36 1 1179092128 36562816 3782352
04:14:35 56419 8192000000 10099316992 10570801152 1136982976 12.187 99 3 6 0 1191920720 54514832 422912
04:16:32 65995 8192000000 10093545776 10553262080 1105509616 11.890 97 15 28 0 1155925264 47651104 2764544
04:18:48 74641 8192000000 10081749440 10537746432 1131557072 12.136 99 19 38 0 1185365040 49558816 4249152
04:21:09 24752 8192000000 10060668384 10505609216 1101180624 11.849 97 15 28 0 1132900288 29018368 2701296
04:22:34 22067 8192000000 10071845824 10500087808 1063634112 11.491 95 34 68 1 1109475200 39315872 6525216
04:24:03 52151 8192000000 10113977968 10539188224 1125473056 12.079 100 32 64 1 1182328832 50582320 6273456
04:26:23 35862 8192000000 8906792928 9098567680 70958096 0.858 7 3 6 0 75091568 3766704 366768

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-12-2020 04:27:41 to 28-12-2020 04:27:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2020 04:27:57 to 28-12-2020 04:27:59.

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          239144
MON$OLDEST_ACTIVE               239145
MON$OLDEST_SNAPSHOT             239145
MON$NEXT_TRANSACTION            239145
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                       2108416
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             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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.12.20 04:28:00. 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-28 04:28:02 No severe PSQL-related problems occured
28.12.20 04:28:04. End of report.