Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16985340 kB
MemAvailable 31589200 kB
Buffers 0 kB
Cached 14715260 kB
SwapCached 5152 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-21 10:26:48
DTS_END                         2020-10-21 10:26:48.2000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/46962
TRN_ID                          228905
ATT_ID                          654
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: 8655.33 24990 1038639
customer order: creation 158.79 414 19055
customer order: refuse 13.50 331 1620
order to supplier: creation 41.91 1422 5029
order to supplier: removal 3.21 113004 385
invoice (draft): creation 42.61 3590 5113
invoice (draft): removal 49.11 978 5893
invoice accept: apply 39.59 19940 4751
invoice accept: cancel 44.28 845 5314
customer reserve: creation 3650.63 197 438075
customer reserve: removal 4340.33 65 520839
realization accept: apply 77.29 171 9275
realization accept: cancel 75.57 115 9068
payment from customer: creation 55.41 116 6649
payment from customer: removal 5.08 76 610
payment to supplier: creation 44.96 124 5395
payment to supplier: removal 3.26 73 391
service: total inventory turnovers 3.59 3696 431
service: total monetary turnovers 5.43 596 651
service: aggregate perf. data 0.76 19473 91
service: refresh index statistics 0.03 334574 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2020 10:28:16 to 21-10-2020 10:28:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4688
TEST_TIME 2 3431
TEST_TIME 3 5707
TEST_TIME 4 5308
TEST_TIME 5 4986
TEST_TIME 6 5328
TEST_TIME 7 5506
TEST_TIME 8 6158
TEST_TIME 9 6136
TEST_TIME 10 6095
TEST_TIME 11 6263
TEST_TIME 12 6197
TEST_TIME 13 6393
TEST_TIME 14 6779
TEST_TIME 15 7046
TEST_TIME 16 7241
TEST_TIME 17 7473
TEST_TIME 18 7872
TEST_TIME 19 7849
TEST_TIME 20 8068
TEST_TIME 21 8237
TEST_TIME 22 8810
TEST_TIME 23 8660
TEST_TIME 24 8918
TEST_TIME 25 9513
TEST_TIME 26 9727
TEST_TIME 27 9741
TEST_TIME 28 10270
TEST_TIME 29 10717
TEST_TIME 30 11069
TEST_TIME 31 10784
TEST_TIME 32 10543
TEST_TIME 33 10440
TEST_TIME 34 10223
TEST_TIME 35 10331
TEST_TIME 36 10218
TEST_TIME 37 10068
TEST_TIME 38 9928
TEST_TIME 39 9884
TEST_TIME 40 9800
TEST_TIME 41 9663
TEST_TIME 42 9584
TEST_TIME 43 9437
TEST_TIME 44 9460
TEST_TIME 45 9384
TEST_TIME 46 9397
TEST_TIME 47 9276
TEST_TIME 48 9337
TEST_TIME 49 9313
TEST_TIME 50 9195
TEST_TIME 51 9296
TEST_TIME 52 9246
TEST_TIME 53 9218
TEST_TIME 54 9402
TEST_TIME 55 9370
TEST_TIME 56 9404
TEST_TIME 57 9449
TEST_TIME 58 9401
TEST_TIME 59 9453
TEST_TIME 60 9329
TEST_TIME 61 9297
TEST_TIME 62 9273
TEST_TIME 63 9215
TEST_TIME 64 9144
TEST_TIME 65 9160
TEST_TIME 66 9154
TEST_TIME 67 9145
TEST_TIME 68 9134
TEST_TIME 69 9165
TEST_TIME 70 9104
TEST_TIME 71 9081
TEST_TIME 72 9098
TEST_TIME 73 9112
TEST_TIME 74 9101
TEST_TIME 75 9227
TEST_TIME 76 9188
TEST_TIME 77 9160
TEST_TIME 78 9138
TEST_TIME 79 9102
TEST_TIME 80 9039
TEST_TIME 81 8996
TEST_TIME 82 8943
TEST_TIME 83 8904
TEST_TIME 84 8836
TEST_TIME 85 8902
TEST_TIME 86 8912
TEST_TIME 87 8866
TEST_TIME 88 8865
TEST_TIME 89 8858
TEST_TIME 90 8872
TEST_TIME 91 8918
TEST_TIME 92 8968
TEST_TIME 93 9029
TEST_TIME 94 9019
TEST_TIME 95 9000
TEST_TIME 96 9055
TEST_TIME 97 8976
TEST_TIME 98 8924
TEST_TIME 99 8870
TEST_TIME 100 8856
TEST_TIME 101 8824
TEST_TIME 102 8771
TEST_TIME 103 8764
TEST_TIME 104 8811
TEST_TIME 105 8851
TEST_TIME 106 8834
TEST_TIME 107 8802
TEST_TIME 108 8809
TEST_TIME 109 8797
TEST_TIME 110 8751
TEST_TIME 111 8850
TEST_TIME 112 8861
TEST_TIME 113 8876
TEST_TIME 114 8885
TEST_TIME 115 8817
TEST_TIME 116 8784
TEST_TIME 117 8762
TEST_TIME 118 8732
TEST_TIME 119 8691
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2020 10:28:20 to 21-10-2020 10:28:23.

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 1443937 1443937 0 0.00 0 19676 7 0 0
doc_list_biud 1443932 1443932 0 0.00 0 66227 30 0 0
sp_add_invoice_to_stock 4830 4751 79 1.63 33 212835 19940 0 79
sp_cancel_adding_invoice 5314 5314 0 0.00 4 70209 845 0 0
sp_cancel_client_order 1620 1620 0 0.00 4 9092 332 0 0
sp_cancel_customer_reserve 520839 520839 0 0.00 1 28735 65 0 0
sp_cancel_pay_from_customer 610 610 0 0.00 3 3700 77 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 1238 73 0 0
sp_cancel_supplier_invoice 5900 5893 7 0.11 2 48844 978 0 7
sp_cancel_supplier_order 385 385 0 0.00 9 1125133 113004 0 0
sp_cancel_write_off 9068 9068 0 0.00 1 14140 116 0 0
sp_client_order 19055 19055 0 0.00 4 31716 414 0 0
sp_customer_reserve 438075 438075 0 0.00 0 76281 197 0 0
sp_fill_shopping_cart 29184 29184 0 0.00 1 17985 50 0 0
sp_fill_shopping_cart_clo_res 438069 438069 0 0.00 0 26412 17 0 0
sp_get_clo_for_invoice 4745 4745 0 0.00 0 20118 379 0 0
sp_kill_cost_storno 15383 15383 0 0.00 0 6089 54 0 0
sp_kill_qstorno_ret_qs2qd 1057024 1057024 0 0.00 0 66225 36 0 0
sp_kill_qty_storno 557137 557137 0 0.00 0 66226 77 0 0
sp_lock_selected_doc 19327 19327 0 0.00 0 8197 7 0 0
sp_make_cost_storno 26064 26064 0 0.00 0 14610 52 0 0
sp_multiply_rows_for_pdistr 26060 26060 0 0.00 0 13710 30 0 0
sp_multiply_rows_for_qdistr 19054 19054 0 0.00 0 16828 205 0 0
sp_pay_from_customer 6649 6649 0 0.00 4 29110 116 0 0
sp_pay_to_supplier 5395 5395 0 0.00 5 29198 124 0 0
sp_payment_common 12042 12042 0 0.00 1 13442 20 0 0
sp_qd_handle_on_cancel_clo 1620 1620 0 0.00 0 9087 306 0 0
sp_qd_handle_on_invoice_upd_sts 10058 10058 0 0.00 0 16814 360 0 0
sp_qd_handle_on_reserve_upd_sts 9274 9274 0 0.00 0 8781 53 0 0
sp_reserve_write_off 9275 9275 0 0.00 7 14623 172 0 0
sp_supplier_invoice 5113 5113 0 0.00 6 94705 3590 0 0
sp_supplier_order 5029 5029 0 0.00 5 75643 1423 0 0
srv_aggregate_perf_data 91 91 0 0.00 2808 304012 19474 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1542 152728 49934 0 0
srv_find_qd_qs_mism 994022 994022 0 0.00 0 19629 6 0 0
srv_make_invnt_saldo 431 431 0 0.00 52 91142 3697 0 0
srv_make_money_saldo 651 651 0 0.00 1 14936 596 0 0
srv_recalc_idx_stat 4 4 0 0.00 89603 558524 334574 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4751 4751 0 0.00 0 15159 163 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 4011 104 0 0
t$perf-norm:sp_cancel_client_order 1620 1620 0 0.00 0 5259 15 0 0
t$perf-norm:sp_cancel_customer_reserve 630 630 0 0.00 0 3022 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 610 610 0 0.00 0 2127 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 315 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 7216 29 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 24473 1468 0 0
t$perf-norm:sp_cancel_write_off 899 899 0 0.00 0 2078 7 0 0
t$perf-norm:sp_client_order 19055 19055 0 0.00 0 7529 17 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 497 4 0 0
t$perf-norm:sp_pay_from_customer 6649 6649 0 0.00 0 6955 10 0 0
t$perf-norm:sp_pay_to_supplier 5395 5395 0 0.00 0 23670 18 0 0
t$perf-norm:sp_reserve_write_off 9275 9275 0 0.00 0 6730 10 0 0
t$perf-norm:sp_supplier_invoice 5113 5113 0 0.00 0 7925 20 0 0
t$perf-norm:sp_supplier_order 5029 5029 0 0.00 0 8894 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 186 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 537 9 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 628 9 0 0
t$perf-norm:srv_make_money_saldo 651 651 0 0.00 0 2383 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 107 29 0 0
v_add_invoice_to_stock 9644 9486 158 1.63 0 3874 16 0 158
v_all_customers 34844 34844 0 0.00 0 133 1 0 0
v_all_suppliers 27864 27864 0 0.00 0 18 1 0 0
v_all_wares 38108 38108 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1396 11 0 0
v_cancel_client_order 3240 3240 0 0.00 0 2275 5 0 0
v_cancel_customer_prepayment 1220 1220 0 0.00 0 3666 11 0 0
v_cancel_customer_reserve 1260 1260 0 0.00 0 2779 6 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 866 5 0 0
v_cancel_supplier_invoice 784 770 14 1.78 0 1129 14 0 14
v_cancel_supplier_order 770 770 0 0.00 0 1485 7 0 0
v_cancel_write_off 1798 1798 0 0.00 0 3230 15 0 0
v_max_id_clo_ord 5028 5028 0 0.00 0 4452 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 2966 11 0 0
v_max_id_ord_sup 5101 5101 0 0.00 0 2851 3 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 226 2 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 505 3 0 0
v_min_id_clo_ord 5028 5028 0 0.00 0 3544 6 0 0
v_min_id_clo_res 942 942 0 0.00 0 1011 7 0 0
v_min_id_ord_sup 5101 5101 0 0.00 0 7196 12 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 13437 16 0 0
v_min_non_paid_realizn 6649 6649 0 0.00 0 9889 13 0 0
v_our_firm 9484 9484 0 0.00 0 67 1 0 0
v_reserve_write_off 18548 18548 0 0.00 0 3598 5 0 0
x_lock_depdocs_on_canc_invoice 10822 10822 0 0.00 0 23772 134 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 6974 362 0 0
x_make_qstorno_1000_1200 5029 5029 0 0.00 2 75617 1337 0 0
x_make_qstorno_1200_2000 5113 5113 0 0.00 3 87605 3395 0 0
x_make_qstorno_2100_3300 438075 438075 0 0.00 0 76279 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2020 10:28:24 to 21-10-2020 10:28:28.

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:52 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:52 24226 8192000000 9711290720 10008162304 796882544 8.865 100 87 173 1 825605968 7232048 21491376
07:59:21 26561 8192000000 9769129744 10078969856 842670784 9.327 100 87 174 0 874990144 11701792 20617568
08:00:51 22377 8192000000 9818518784 10135642112 877446640 9.674 100 89 178 0 922410544 24736544 20227360
08:02:16 13896 8192000000 9875231120 10208075776 917486256 10.071 100 75 150 1 977449136 44198736 15764144
08:03:32 5236 8192000000 9938702048 10285543424 965443952 10.542 100 83 166 2 1055274704 72118496 17712256
08:04:39 2991 8192000000 9961095328 10322128896 1003796256 10.915 100 77 152 0 1086453248 67103920 15553072
08:05:44 8057 8192000000 9995035840 10360430592 1032707136 11.195 100 80 160 2 1105042144 56230656 16104352
08:06:53 1718 8192000000 10031398528 10403319808 1059091888 11.448 100 94 188 0 1146499232 67305360 20101984
08:07:56 988 8192000000 10035988688 10413109248 1078377248 11.632 100 92 185 1 1152168256 54890432 18900576
08:08:57 3443 8192000000 10041664400 10433712128 1080143168 11.649 100 56 112 0 1146543360 55176160 11224032
08:10:04 9697 8192000000 10010530176 10406285312 1086358432 11.708 100 44 87 1 1116260208 20525632 9376144
08:11:14 8097 8192000000 10004409712 10418933760 1088819024 11.731 100 26 52 0 1116163648 21950784 5393840
08:12:23 72987 8192000000 10026521536 10446766080 1096153616 11.801 100 40 81 1 1119294368 14037760 9102992
08:14:37 10708 8192000000 10019407664 10438696960 1090798544 11.750 99 38 77 5 1123673680 24156400 8718736
08:15:47 6410 8192000000 10015067648 10414563328 1087351600 11.717 99 40 80 2 1118258096 22148912 8757584
08:16:59 10704 8192000000 9963420656 10347569152 1008651248 10.962 94 30 58 5 1032422336 17025600 6745488
08:18:10 46829 8192000000 9943952208 10331242496 977056144 10.656 92 33 66 3 1000498384 16185664 7256576
08:20:00 4613 8192000000 9956822656 10335551488 1013394864 11.008 99 59 116 4 1049546448 23427216 12724368
08:21:07 9461 8192000000 9915941248 10307563520 1005876128 10.935 99 21 42 0 1030056592 19366960 4813504
08:22:17 9812 8192000000 9964985520 10360336384 1038602800 11.251 100 31 61 3 1060712064 15204160 6905104
08:23:30 46475 8192000000 9964730160 10372907008 1050655792 11.367 100 27 54 2 1084425456 27868304 5901360
08:25:22 6619 8192000000 9982860752 10375041024 1062838352 11.484 100 51 103 0 1083233856 9175600 11219904
08:26:29 14529 8192000000 10004340400 10410500096 1084978864 11.695 100 27 53 2 1115196880 24424736 5793280
08:27:47 23948 8192000000 10063291104 10454749184 1102050768 11.857 100 59 117 2 1161715488 48002304 11662416
08:29:15 24969 8192000000 10057395104 10460766208 1113818256 11.969 100 52 102 0 1194395664 69765824 10811584
08:30:48 11587 8192000000 10084529168 10489970688 1122109216 12.047 100 52 103 0 1201541376 68681216 10750944
08:32:06 8420 8192000000 10146829232 10548289536 1134298416 12.162 100 61 122 1 1250816592 104308224 12209952
08:33:20 4547 8192000000 10148378736 10554634240 1144587280 12.259 100 47 94 1 1251202672 97270704 9344688
08:34:27 2963 8192000000 10153584160 10558808064 1146242432 12.274 100 49 98 1 1268138400 111615504 10280464
08:35:34 3110 8192000000 10179709024 10582048768 1154946528 12.356 100 67 135 2 1280948096 112129872 13871696
08:36:41 17310 8192000000 10251541344 10662014976 1166845936 12.467 100 65 130 0 1348418592 167949648 13623008
08:38:06 6861 8192000000 10263715104 10661134336 1152442896 12.332 99 79 158 0 1362752848 193051952 17258000
08:39:19 5278 8192000000 10307933232 10708926464 1158512128 12.389 99 72 144 0 1405574224 230895152 16166944
08:40:31 5334 8192000000 10304793408 10714816512 1174102368 12.535 100 66 132 3 1411160224 221419488 15638368
08:41:44 3034 8192000000 10295606112 10697977856 1153534752 12.343 100 75 151 1 1399296928 227374336 18387840
08:42:53 6125 8192000000 10338941120 10734211072 1154072224 12.348 100 81 160 1 1437059408 264828832 18158352
08:44:06 1541 8192000000 10317851152 10704211968 1141144336 12.226 99 80 159 3 1412569616 253538672 17886608
08:45:12 6608 8192000000 10344320864 10745782272 1121806176 12.044 98 57 114 1 1426988864 292026080 13156608
08:46:29 3008 8192000000 10398041216 10794119168 1153174000 12.339 100 86 167 3 1498217232 325718880 19324352
08:47:35 9536 8192000000 10368101920 10785132544 1146316016 12.275 100 41 82 1 1466478576 309601024 10561536
08:48:47 11184 8192000000 10325657360 10746658816 1127050272 12.094 99 27 54 0 1425174992 291018544 7106176
08:50:03 7609 8192000000 10367623600 10806599680 1135747040 12.176 100 40 80 2 1481301312 334448976 11105296
08:51:14 15448 8192000000 10296496368 10758930432 1123521712 12.060 100 34 67 1 1406775024 274537248 8716064
08:52:46 46293 8192000000 10262744560 10713276416 1088657024 11.730 97 14 28 1 1358705776 266169936 3878816
08:54:33 30184 8192000000 10222377360 10676854784 1104964336 11.885 99 14 28 0 1320548960 211902224 3682400
08:56:16 19946 8192000000 10086079904 10546356224 1120092192 12.028 100 12 24 1 1206607440 84171984 2343264
08:57:40 101447 8192000000 10126302256 10578681856 1135443424 12.173 100 26 52 1 1214866496 74111872 5311200
09:00:36 42796 8192000000 10127223808 10571767808 1142220032 12.236 100 22 44 0 1229401504 83006208 4175264
09:02:23 98818 8192000000 10081176784 10501550080 1100103984 11.839 97 26 52 0 1137328992 31348816 5876192
09:05:10 59276 8192000000 10096871536 10562527232 1134303520 12.162 100 38 74 2 1194989536 52775008 7911008
09:07:09 87019 8192000000 10111045792 10552451072 1143136784 12.245 100 17 34 1 1228420064 81335536 3947744
09:09:40 44366 8192000000 10141471936 10597875712 1147294272 12.284 100 35 70 1 1242060384 87299392 7466720
09:11:30 44299 8192000000 10086492304 10547085312 1133550720 12.155 99 14 28 0 1190274368 53706944 3016704
09:13:37 55631 8192000000 10088317584 10554404864 1149669904 12.306 100 20 40 2 1189446576 35239840 4536832
09:16:01 37807 8192000000 10109392864 10536820736 1139968544 12.215 99 33 66 1 1187667248 40293936 7404768
09:17:40 43668 8192000000 10099788832 10569781248 1135219120 12.171 98 21 42 0 1197563216 58118128 4225968
09:19:30 72202 8192000000 10094315872 10586779648 1137003904 12.187 99 17 34 2 1219507808 78845744 3658160
09:21:43 41996 8192000000 10097978576 10556907520 1133330464 12.153 99 24 47 1 1193474736 55144672 4999600
09:23:28 69530 8192000000 10098045712 10556256256 1127566816 12.098 99 21 42 0 1176044064 44419744 4057504
09:25:45 136893 8192000000 10118805696 10620387328 1142491648 12.239 100 14 28 1 1244959664 98902000 3566016
09:29:06 44689 8192000000 10072188448 10565804032 1121301168 12.039 98 8 14 1 1178145392 55265984 1578240
09:32:37 35622 8192000000 10048172608 10476797952 1074294240 11.593 95 27 53 1 1092072160 12401904 5376016
09:34:13 52029 8192000000 10088610304 10553024512 1107841616 11.912 98 20 40 0 1147560992 36111616 3607760
09:36:05 37506 8192000000 10052905424 10474295296 1068434112 11.537 96 29 56 3 1105930000 31190656 6305232
09:37:53 83089 8192000000 10022827056 10488754176 1052086640 11.381 96 19 38 1 1111647600 55361232 4199728
09:40:21 76177 8192000000 10112120672 10580144128 1104492464 11.880 100 18 36 0 1216361600 107430272 4438864
09:42:58 56582 8192000000 10063886512 10471911424 1073720592 11.588 97 26 52 1 1103752832 25189680 4842560
09:44:55 26971 8192000000 10086804640 10510114816 1088488384 11.728 99 45 90 1 1171060560 73094016 9478160
09:46:24 50637 8192000000 10009194992 10441555968 1073266400 11.583 98 31 62 0 1126257456 46760432 6230624
09:48:23 60013 8192000000 10029084608 10484105216 1089326384 11.736 99 11 22 0 1139971472 48311584 2333504
09:50:23 66922 8192000000 10058930224 10533277696 1071947648 11.571 97 10 20 1 1144831856 70663504 2220704
09:52:32 78224 8192000000 10039395376 10467852288 1058602000 11.443 96 25 49 3 1105966720 42330704 5034016
09:54:51 54368 8192000000 10080529776 10515132416 1098397216 11.822 99 34 67 2 1156138736 49946560 7794960
09:56:53 95605 8192000000 10108117904 10541805568 1101106592 11.848 98 20 40 1 1190075552 84514768 4454192
09:59:29 108206 8192000000 10075247968 10497810432 1084902080 11.694 96 12 24 0 1117127328 30028464 2196784
10:02:19 105161 8192000000 10069082832 10563899392 1107612720 11.910 98 10 20 0 1160620944 51039840 1968384
10:05:16 87221 8192000000 10108521168 10578509824 1131044112 12.131 100 12 24 0 1207893184 74278736 2570336
10:09:45 83719 8192000000 10100340160 10572181504 1125310528 12.077 99 15 30 0 1206848544 78285808 3252208
10:12:09 85310 8192000000 10093917360 10527490048 1103514016 11.871 97 24 48 0 1149416672 40975328 4927328
10:14:46 74469 8192000000 10129939152 10580787200 1135382208 12.172 100 28 56 0 1196406800 55611056 5413536
10:17:04 132470 8192000000 10085333376 10525741056 1094981248 11.790 96 31 62 1 1161389920 59765168 6643504
10:20:22 137522 8192000000 10134925520 10636025856 1146079440 12.273 100 18 36 1 1243020208 93404480 3536288
10:23:47 152727 8192000000 10081639952 10573586432 1106486816 11.899 97 7 14 0 1152125360 44727184 911360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-10-2020 10:28:30 to 21-10-2020 10:28:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 79 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

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

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          227887
MON$OLDEST_ACTIVE               229075
MON$OLDEST_SNAPSHOT             229075
MON$NEXT_TRANSACTION            229075
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                       2099072
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             763
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2229 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2229 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2229 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.2229 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Wed Oct 21 10:26:52 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

21.10.20 10:28:48. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-21 10:28:50 No severe PSQL-related problems occured
21.10.20 10:28:52. End of report.