Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.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 16769516 kB
MemAvailable 31435236 kB
Buffers 0 kB
Cached 14856608 kB
SwapCached 2872 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-11-04 10:26:50
DTS_END                         2020-11-04 10:26:50.4950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(451:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34506
TRN_ID                          232097
ATT_ID                          691
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: 8781.88 22074 1053826
customer order: creation 163.81 389 19657
customer order: refuse 14.07 320 1688
order to supplier: creation 43.61 1362 5233
order to supplier: removal 3.08 131998 370
invoice (draft): creation 43.88 4001 5266
invoice (draft): removal 51.03 942 6124
invoice accept: apply 39.58 18262 4750
invoice accept: cancel 46.19 848 5543
customer reserve: creation 3605.04 180 432605
customer reserve: removal 4490.93 72 538911
realization accept: apply 79.51 180 9541
realization accept: cancel 79.12 120 9494
payment from customer: creation 56.08 115 6729
payment from customer: removal 5.70 82 684
payment to supplier: creation 46.60 147 5592
payment to supplier: removal 3.46 75 415
service: total inventory turnovers 3.48 3771 418
service: total monetary turnovers 5.91 664 709
service: aggregate perf. data 0.77 16909 92
service: refresh index statistics 0.04 261039 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-11-2020 10:27:32 to 04-11-2020 10:27:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1390
TEST_TIME 2 3029
TEST_TIME 3 2973
TEST_TIME 4 3776
TEST_TIME 5 4665
TEST_TIME 6 4811
TEST_TIME 7 5445
TEST_TIME 8 5607
TEST_TIME 9 6067
TEST_TIME 10 6090
TEST_TIME 11 6045
TEST_TIME 12 6530
TEST_TIME 13 6758
TEST_TIME 14 6852
TEST_TIME 15 7304
TEST_TIME 16 7244
TEST_TIME 17 7762
TEST_TIME 18 7848
TEST_TIME 19 7951
TEST_TIME 20 8241
TEST_TIME 21 8759
TEST_TIME 22 9014
TEST_TIME 23 9084
TEST_TIME 24 8981
TEST_TIME 25 8913
TEST_TIME 26 9108
TEST_TIME 27 9218
TEST_TIME 28 10356
TEST_TIME 29 11613
TEST_TIME 30 11246
TEST_TIME 31 11003
TEST_TIME 32 11184
TEST_TIME 33 11155
TEST_TIME 34 10864
TEST_TIME 35 10762
TEST_TIME 36 10654
TEST_TIME 37 10485
TEST_TIME 38 10375
TEST_TIME 39 10358
TEST_TIME 40 10221
TEST_TIME 41 10103
TEST_TIME 42 10019
TEST_TIME 43 9917
TEST_TIME 44 9801
TEST_TIME 45 9739
TEST_TIME 46 9657
TEST_TIME 47 9578
TEST_TIME 48 9498
TEST_TIME 49 9472
TEST_TIME 50 9379
TEST_TIME 51 9319
TEST_TIME 52 9253
TEST_TIME 53 9150
TEST_TIME 54 9241
TEST_TIME 55 9259
TEST_TIME 56 9188
TEST_TIME 57 9214
TEST_TIME 58 9258
TEST_TIME 59 9248
TEST_TIME 60 9208
TEST_TIME 61 9300
TEST_TIME 62 9332
TEST_TIME 63 9427
TEST_TIME 64 9386
TEST_TIME 65 9338
TEST_TIME 66 9408
TEST_TIME 67 9379
TEST_TIME 68 9282
TEST_TIME 69 9250
TEST_TIME 70 9176
TEST_TIME 71 9113
TEST_TIME 72 9025
TEST_TIME 73 9015
TEST_TIME 74 9000
TEST_TIME 75 8944
TEST_TIME 76 8962
TEST_TIME 77 8924
TEST_TIME 78 8935
TEST_TIME 79 8897
TEST_TIME 80 9003
TEST_TIME 81 8949
TEST_TIME 82 8951
TEST_TIME 83 9075
TEST_TIME 84 9018
TEST_TIME 85 9010
TEST_TIME 86 8916
TEST_TIME 87 8912
TEST_TIME 88 8881
TEST_TIME 89 8839
TEST_TIME 90 8819
TEST_TIME 91 8776
TEST_TIME 92 8776
TEST_TIME 93 8753
TEST_TIME 94 8761
TEST_TIME 95 8848
TEST_TIME 96 8900
TEST_TIME 97 8872
TEST_TIME 98 8890
TEST_TIME 99 8909
TEST_TIME 100 8920
TEST_TIME 101 8935
TEST_TIME 102 8958
TEST_TIME 103 8974
TEST_TIME 104 9020
TEST_TIME 105 8981
TEST_TIME 106 8901
TEST_TIME 107 8860
TEST_TIME 108 8839
TEST_TIME 109 8785
TEST_TIME 110 8741
TEST_TIME 111 8750
TEST_TIME 112 8778
TEST_TIME 113 8729
TEST_TIME 114 8731
TEST_TIME 115 8754
TEST_TIME 116 8724
TEST_TIME 117 8797
TEST_TIME 118 8789
TEST_TIME 119 8791
TEST_TIME 120 8783

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-11-2020 10:27:36 to 04-11-2020 10:27:39.

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 1455218 1455218 0 0.00 0 19961 7 0 0
doc_list_biud 1455218 1455218 0 0.00 0 74704 33 0 0
sp_add_invoice_to_stock 4929 4750 179 3.63 34 192862 18263 0 179
sp_cancel_adding_invoice 5543 5543 0 0.00 3 65467 849 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 10102 321 0 0
sp_cancel_customer_reserve 538911 538911 0 0.00 1 74705 72 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 3640 83 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 3103 76 0 0
sp_cancel_supplier_invoice 6139 6124 15 0.24 1 18831 942 0 15
sp_cancel_supplier_order 370 370 0 0.00 8 1149310 131998 0 0
sp_cancel_write_off 9494 9494 0 0.00 1 8789 121 0 0
sp_client_order 19657 19657 0 0.00 4 21391 390 0 0
sp_customer_reserve 432605 432605 0 0.00 0 59802 181 0 0
sp_fill_shopping_cart 30150 30150 0 0.00 1 25498 55 0 0
sp_fill_shopping_cart_clo_res 432604 432604 0 0.00 0 16914 19 0 0
sp_get_clo_for_invoice 4749 4749 0 0.00 0 25316 442 0 0
sp_kill_cost_storno 16136 16136 0 0.00 0 7277 62 0 0
sp_kill_qstorno_ret_qs2qd 1093810 1093810 0 0.00 0 74700 39 0 0
sp_kill_qty_storno 576420 576420 0 0.00 0 74703 83 0 0
sp_lock_selected_doc 19812 19812 0 0.00 0 10265 5 0 0
sp_make_cost_storno 26611 26611 0 0.00 0 9675 58 0 0
sp_multiply_rows_for_pdistr 26611 26611 0 0.00 0 7228 33 0 0
sp_multiply_rows_for_qdistr 19657 19657 0 0.00 0 21360 198 0 0
sp_pay_from_customer 6729 6729 0 0.00 4 7471 115 0 0
sp_pay_to_supplier 5592 5592 0 0.00 5 15476 148 0 0
sp_payment_common 12321 12321 0 0.00 1 6337 22 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10096 295 0 0
sp_qd_handle_on_invoice_upd_sts 10292 10292 0 0.00 0 16408 390 0 0
sp_qd_handle_on_reserve_upd_sts 9541 9541 0 0.00 0 8405 53 0 0
sp_reserve_write_off 9541 9541 0 0.00 7 15021 181 0 0
sp_supplier_invoice 5266 5266 0 0.00 6 103388 4001 0 0
sp_supplier_order 5233 5233 0 0.00 5 31540 1362 0 0
srv_aggregate_perf_data 92 92 0 0.00 2258 167467 16909 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1186 154906 45050 0 0
srv_find_qd_qs_mism 1009112 1009112 0 0.00 0 19961 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 50 143164 3772 0 0
srv_make_money_saldo 709 709 0 0.00 1 44506 664 0 0
srv_recalc_idx_stat 5 5 0 0.00 136497 543269 261039 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4751 4751 0 0.00 0 15816 179 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 0 5720 90 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 2044 11 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 6191 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 970 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 303 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1253 18 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 23346 1902 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 2137 5 0 0
t$perf-norm:sp_client_order 19657 19657 0 0.00 0 9333 19 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 2115 8 0 0
t$perf-norm:sp_pay_from_customer 6729 6729 0 0.00 0 3958 9 0 0
t$perf-norm:sp_pay_to_supplier 5592 5592 0 0.00 0 5567 9 0 0
t$perf-norm:sp_reserve_write_off 9541 9541 0 0.00 0 5866 11 0 0
t$perf-norm:sp_supplier_invoice 5266 5266 0 0.00 0 5009 16 0 0
t$perf-norm:sp_supplier_order 5233 5233 0 0.00 0 7784 17 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 79 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 226 4 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1288 11 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 840 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9856 9498 358 3.63 0 3362 18 0 358
v_all_customers 35470 35470 0 0.00 0 15 1 0 0
v_all_suppliers 28792 28792 0 0.00 0 154 1 0 0
v_all_wares 39314 39314 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 3120 17 0 0
v_cancel_client_order 3376 3376 0 0.00 0 352 3 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2848 7 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 375 3 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1009 6 0 0
v_cancel_supplier_invoice 774 744 30 3.87 0 2032 17 0 30
v_cancel_supplier_order 740 740 0 0.00 0 3301 8 0 0
v_cancel_write_off 1942 1942 0 0.00 0 4586 23 0 0
v_max_id_clo_ord 5232 5232 0 0.00 0 1375 2 0 0
v_max_id_clo_res 972 972 0 0.00 0 3870 15 0 0
v_max_id_ord_sup 5261 5261 0 0.00 0 7558 7 0 0
v_max_non_paid_invoice 1689 1689 0 0.00 0 1793 3 0 0
v_max_non_paid_realizn 3657 3657 0 0.00 0 3891 4 0 0
v_min_id_clo_ord 5232 5232 0 0.00 0 8022 9 0 0
v_min_id_clo_res 972 972 0 0.00 0 7078 19 0 0
v_min_id_ord_sup 5261 5261 0 0.00 0 9226 19 0 0
v_min_non_paid_invoice 5592 5592 0 0.00 0 4993 16 0 0
v_min_non_paid_realizn 6729 6729 0 0.00 0 6103 14 0 0
v_our_firm 9988 9988 0 0.00 0 21 1 0 0
v_reserve_write_off 19082 19082 0 0.00 0 4566 6 0 0
x_lock_depdocs_on_canc_invoice 11295 11295 0 0.00 0 18083 117 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 7526 375 0 0
x_make_qstorno_1000_1200 5233 5233 0 0.00 2 31524 1279 0 0
x_make_qstorno_1200_2000 5266 5266 0 0.00 2 103016 3779 0 0
x_make_qstorno_2100_3300 432605 432605 0 0.00 0 59734 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2020 10:27:40 to 04-11-2020 10:27:44.

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:54 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:55 12519 8192000000 9697288016 9987493888 779273680 8.686 100 90 180 0 808924736 7049632 22601424
07:59:19 18886 8192000000 9742606000 10041024512 814160720 9.040 100 86 172 1 846174704 11100720 20913264
08:00:44 19235 8192000000 9768636480 10084777984 844220624 9.342 100 76 151 0 881247264 19293152 17733488
08:02:06 12895 8192000000 9837779552 10168885248 907886432 9.976 100 74 148 1 947479696 23376000 16217264
08:03:23 10427 8192000000 9925507680 10264031232 957726640 10.467 100 86 171 1 1028579024 53181280 17671104
08:04:36 6495 8192000000 9959550944 10313408512 985890432 10.742 100 80 160 1 1063976576 61409840 16676304
08:05:47 3118 8192000000 10015114256 10374803456 1015549632 11.029 100 93 184 0 1123326032 89337680 18438720
08:06:52 2417 8192000000 10028611568 10395557888 1051220464 11.372 100 90 178 3 1134101776 65140144 17741168
08:07:55 1768 8192000000 10033853472 10407260160 1065707872 11.511 100 90 178 1 1141479328 57448096 18323360
08:08:58 4083 8192000000 10035727312 10428850176 1068710208 11.540 100 49 98 1 1146086320 67295440 10080672
08:10:03 6195 8192000000 10005772816 10394673152 1080204496 11.649 100 47 94 2 1115573632 24636560 10732576
08:11:10 5235 8192000000 9998286576 10401112064 1084947920 11.695 100 42 84 1 1119508560 25188592 9372048
08:12:19 24619 8192000000 10003818016 10397253632 1089278720 11.736 100 55 110 1 1109647664 8310576 12058368
08:13:44 5575 8192000000 10023925296 10428628992 1097833696 11.817 100 37 72 3 1139700928 33490960 8376272
08:14:54 6329 8192000000 10020445520 10418073600 1100583232 11.843 100 33 61 0 1132548688 25416880 6548576
08:16:01 9020 8192000000 10012685200 10416889856 1094397552 11.784 100 31 62 0 1116766752 15817680 6551520
08:17:13 33989 8192000000 9961226816 10369417216 1045450208 11.317 97 17 34 2 1063202096 14209408 3542480
08:18:50 9259 8192000000 9976406976 10368708608 1038702560 11.252 99 39 78 0 1080832160 33262704 8866896
08:20:01 3650 8192000000 9968330896 10349469696 1034319056 11.210 100 49 96 1 1080869424 35929792 10620576
08:21:05 6662 8192000000 9911081488 10291744768 968209328 10.569 95 22 39 1 994100464 21969392 3921744
08:22:13 67087 8192000000 9950580160 10351464448 1032482224 11.192 99 27 54 2 1054732112 17402288 4847600
08:24:20 7556 8192000000 9982822384 10377695232 1059420384 11.451 100 46 88 1 1092081600 22356560 10304656
08:25:34 10024 8192000000 9994693872 10391801856 1069912432 11.551 100 49 100 1 1101520448 20374928 11233088
08:26:47 17321 8192000000 10006706400 10400968704 1095340176 11.793 100 27 51 2 1123812464 23119920 5352368
08:28:12 55482 8192000000 10053486976 10458329088 1110540864 11.938 100 43 85 2 1164535808 44573712 9421232
08:30:30 7804 8192000000 10110595040 10514567168 1130466048 12.126 100 53 106 1 1218014064 76390256 11157760
08:31:42 4112 8192000000 10127036032 10525601792 1128985568 12.112 99 57 111 3 1222129168 81876240 11267360
08:32:50 6170 8192000000 10164923360 10568609792 1150975520 12.319 100 64 125 3 1273631456 109789984 12865952
08:34:00 13063 8192000000 10187937264 10604302336 1161679936 12.419 100 55 109 3 1301633296 128136976 11816384
08:35:16 3386 8192000000 10221215776 10628665344 1168599456 12.484 100 64 125 2 1323416432 140532448 14284528
08:36:23 8915 8192000000 10261119248 10672934912 1180146288 12.592 100 63 126 2 1367749568 174327504 13275776
08:37:36 5480 8192000000 10252426160 10653257728 1151193280 12.321 97 65 130 0 1346378464 180671904 14513280
08:38:49 1255 8192000000 10285116752 10688512000 1158353056 12.388 99 75 148 2 1382327168 207627120 16346992
08:39:54 4394 8192000000 10306666304 10714550272 1171455536 12.510 100 72 143 2 1404831904 217104144 16272224
08:41:04 2712 8192000000 10348840816 10747359232 1161589632 12.418 99 91 179 1 1449380672 266999056 20791984
08:42:10 1632 8192000000 10346658160 10744397824 1154869344 12.355 100 83 157 2 1446232336 273724864 17638128
08:43:17 1182 8192000000 10406713600 10814713856 1175557456 12.549 100 93 186 1 1511194352 314271424 21365472
08:44:23 15769 8192000000 10359272800 10772135936 1139995184 12.215 99 42 84 0 1450541088 299045376 11500528
08:45:44 1781 8192000000 10437531920 10831581184 1157921808 12.384 100 90 181 1 1537170176 357079392 22168976
08:46:52 4524 8192000000 10448777360 10850156544 1168190080 12.480 100 80 161 0 1549270144 361639296 19440768
08:48:16 7585 8192000000 10438511888 10856828928 1167544176 12.474 100 51 101 0 1540483248 360403168 12535904
08:49:28 14306 8192000000 10420904784 10878377984 1163002912 12.431 100 33 66 1 1525225696 353404928 8817856
08:50:43 29890 8192000000 10475374432 10902040576 1146423136 12.276 99 33 66 2 1556746432 401804032 8519264
08:52:16 15579 8192000000 10537570832 10999406592 1130710816 12.128 100 27 54 1 1650650704 512492240 7447648
08:53:37 11060 8192000000 10526968576 10965692416 1106525552 11.900 99 34 68 0 1619267824 503475968 9266304
08:54:53 43584 8192000000 10055338240 10483302400 1072932352 11.580 97 22 44 3 1269061440 190528208 5600880
08:56:40 39504 8192000000 10070237712 10482040832 1068345904 11.536 96 52 102 1 1133259776 53035696 11878176
08:58:20 20096 8192000000 10136294176 10603778048 1103712960 11.873 100 12 24 0 1242583056 136030096 2840000
08:59:42 68348 8192000000 10064713904 10499436544 1104661536 11.882 99 5 10 0 1166043504 60507136 874832
09:02:36 24194 8192000000 10079418608 10499006464 1118093392 12.009 100 35 70 2 1172873920 47735984 7044544
09:04:01 149553 8192000000 10064410960 10549067776 1107354208 11.907 98 29 56 4 1177249904 63833360 6062336
09:07:36 154906 8192000000 10059677344 10498953216 1140305568 12.218 100 26 51 0 1195550864 50058112 5187184
09:11:17 57118 8192000000 10102494320 10537172992 1140598512 12.221 100 32 64 1 1193509088 46335136 6575440
09:13:22 37974 8192000000 10098388320 10556342272 1137592672 12.193 99 11 22 1 1210036496 70116640 2327184
09:15:01 59881 8192000000 10060526224 10545627136 1142198752 12.236 100 15 30 0 1209436320 63810048 3427520
09:17:02 50785 8192000000 10124211440 10589515776 1153297680 12.340 100 23 46 1 1213521520 54861696 5362144
09:19:19 75027 8192000000 10112812336 10632691712 1148030592 12.291 100 9 18 1 1256532128 106119904 2381632
09:21:34 59980 8192000000 10089492400 10536611840 1135139136 12.170 99 8 16 1 1201922416 64871520 1911760
09:23:38 34748 8192000000 10084570272 10526756864 1105544832 11.890 96 32 64 1 1141618576 29158064 6915680
09:25:13 65099 8192000000 10113413744 10544025600 1127867664 12.101 98 19 38 1 1216678080 84722992 4087424
09:27:20 52190 8192000000 10095332688 10591866880 1085592528 11.701 95 20 40 0 1203285040 113352768 4339744
09:29:13 67064 8192000000 10047731920 10490327040 1065591472 11.510 94 19 38 1 1109958208 40319152 4047584
09:31:26 56897 8192000000 10066052272 10496376832 1100357760 11.841 97 32 65 1 1138839344 32309616 6171968
09:33:24 68390 8192000000 10087953552 10524090368 1104558992 11.881 99 49 96 3 1185330752 71649520 9122240
09:35:33 51377 8192000000 10043301712 10497761280 1111130176 11.943 99 4 8 0 1134673328 22845600 697552
09:37:39 33352 8192000000 10053801152 10518482944 1107897392 11.913 100 8 16 0 1162014528 52402768 1714368
09:39:13 73763 8192000000 10035288816 10520903680 1085732704 11.702 97 12 24 0 1163091616 74470464 2888448
09:41:31 65996 8192000000 10077385232 10513526784 1105772896 11.892 98 22 45 1 1148419152 37992000 4654256
09:43:43 37781 8192000000 10121031648 10553454592 1117068608 11.999 99 42 85 0 1214009872 87737360 9203904
09:45:29 50661 8192000000 10113025952 10547613696 1073294256 11.584 96 20 40 1 1158600464 81318992 3987216
09:47:22 107111 8192000000 10100809472 10561191936 1105536912 11.890 99 16 32 1 1180471888 71491616 3443360
09:50:13 33997 8192000000 10067919184 10477846528 1097890144 11.818 98 44 88 2 1134222416 26908912 9423360
09:52:00 96828 8192000000 10078882816 10521251840 1112128736 11.953 99 17 34 0 1191046464 75212160 3705568
09:54:50 56341 8192000000 10067380416 10573266944 1098705120 11.825 98 4 8 0 1138834528 39240128 889280
09:56:47 61169 8192000000 10068242928 10500292608 1097523952 11.814 97 31 61 1 1150303488 45796384 6983152
09:58:50 44428 8192000000 10126841200 10557128704 1134784560 12.166 99 29 58 3 1201573888 60342528 6446800
10:00:40 77599 8192000000 10113432816 10601680896 1126234224 12.086 99 11 20 1 1215168656 86395152 2539280
10:03:05 78627 8192000000 10112209424 10540085248 1129919152 12.121 99 22 44 0 1173679584 39512416 4248016
10:05:24 32526 8192000000 10134359040 10585583616 1150309152 12.312 100 47 92 2 1217752912 57726256 9717504
10:07:04 61700 8192000000 10101973984 10548797440 1115595072 11.985 98 14 28 2 1148716256 30536720 2584464
10:09:06 33194 8192000000 10118460736 10551103488 1131833856 12.139 99 34 68 1 1184978032 45745360 7398816
10:10:48 67526 8192000000 10083753168 10566512640 1123639184 12.061 98 13 26 0 1161827792 35363840 2824768
10:13:55 61448 8192000000 10068462416 10529042432 1122707232 12.053 98 14 28 0 1172166656 46158336 3301088
10:16:09 51041 8192000000 10080952032 10550657024 1138914944 12.205 100 16 32 0 1204878288 62038048 3925296
10:18:02 86748 8192000000 10072701968 10542067712 1117403536 12.002 98 15 30 2 1151465840 30029376 4032928
10:20:31 74999 8192000000 10112137680 10577870848 1108101488 11.914 97 10 20 0 1178957568 68853872 2002208
10:22:54 107608 8192000000 10082531024 10550075392 1122514000 12.051 98 13 26 0 1168256416 42815392 2927024
10:25:42 63605 8192000000 10063958912 10501042176 1061977712 11.475 94 27 54 0 1121133664 53724944 5431008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-11-2020 10:27:46 to 04-11-2020 10:28:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

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          231120
MON$OLDEST_ACTIVE               232660
MON$OLDEST_SNAPSHOT             232660
MON$NEXT_TRANSACTION            232660
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                       2107520
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             795
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2244 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.2244 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'

04.11.20 10:28:05. 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-11-04 10:28:08 No severe PSQL-related problems occured
04.11.20 10:28:09. End of report.