Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2020 07:28
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 16918176 kB
MemAvailable 31625756 kB
Buffers 0 kB
Cached 14764360 kB
SwapCached 5552 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-12 07:26:51
DTS_END                         2020-10-12 07:26:51.6600
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/40922
TRN_ID                          239927
ATT_ID                          686
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: 9032.74 26332 1083929
customer order: creation 165.83 373 19900
customer order: refuse 15.16 326 1819
order to supplier: creation 45.93 1387 5512
order to supplier: removal 3.25 120833 390
invoice (draft): creation 44.92 3509 5390
invoice (draft): removal 49.44 1015 5933
invoice accept: apply 42.25 17930 5070
invoice accept: cancel 44.79 950 5375
customer reserve: creation 3917.02 176 470042
customer reserve: removal 4417.09 70 530051
realization accept: apply 82.88 170 9946
realization accept: cancel 76.32 123 9158
payment from customer: creation 58.29 114 6995
payment from customer: removal 5.58 82 670
payment to supplier: creation 48.73 134 5848
payment to supplier: removal 3.81 87 457
service: total inventory turnovers 4.01 3272 481
service: total monetary turnovers 6.48 480 778
service: aggregate perf. data 0.92 15301 110
service: refresh index statistics 0.03 360299 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2020 07:28:39 to 12-10-2020 07:28:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6062
TEST_TIME 2 4459
TEST_TIME 3 4748
TEST_TIME 4 4264
TEST_TIME 5 4640
TEST_TIME 6 5461
TEST_TIME 7 5442
TEST_TIME 8 5630
TEST_TIME 9 6188
TEST_TIME 10 6340
TEST_TIME 11 6333
TEST_TIME 12 6345
TEST_TIME 13 6516
TEST_TIME 14 6917
TEST_TIME 15 7323
TEST_TIME 16 7341
TEST_TIME 17 7841
TEST_TIME 18 8122
TEST_TIME 19 8504
TEST_TIME 20 8542
TEST_TIME 21 8611
TEST_TIME 22 8485
TEST_TIME 23 8895
TEST_TIME 24 9266
TEST_TIME 25 9441
TEST_TIME 26 9595
TEST_TIME 27 10073
TEST_TIME 28 10963
TEST_TIME 29 11532
TEST_TIME 30 11437
TEST_TIME 31 11159
TEST_TIME 32 10891
TEST_TIME 33 10735
TEST_TIME 34 10557
TEST_TIME 35 10616
TEST_TIME 36 10540
TEST_TIME 37 10397
TEST_TIME 38 10314
TEST_TIME 39 10274
TEST_TIME 40 10329
TEST_TIME 41 10172
TEST_TIME 42 10032
TEST_TIME 43 10074
TEST_TIME 44 10112
TEST_TIME 45 10129
TEST_TIME 46 10064
TEST_TIME 47 10104
TEST_TIME 48 10090
TEST_TIME 49 10087
TEST_TIME 50 10077
TEST_TIME 51 10078
TEST_TIME 52 10072
TEST_TIME 53 10043
TEST_TIME 54 10029
TEST_TIME 55 10189
TEST_TIME 56 10062
TEST_TIME 57 9909
TEST_TIME 58 9861
TEST_TIME 59 9747
TEST_TIME 60 9688
TEST_TIME 61 9599
TEST_TIME 62 9531
TEST_TIME 63 9446
TEST_TIME 64 9432
TEST_TIME 65 9401
TEST_TIME 66 9389
TEST_TIME 67 9402
TEST_TIME 68 9397
TEST_TIME 69 9376
TEST_TIME 70 9501
TEST_TIME 71 9477
TEST_TIME 72 9534
TEST_TIME 73 9607
TEST_TIME 74 9586
TEST_TIME 75 9540
TEST_TIME 76 9521
TEST_TIME 77 9639
TEST_TIME 78 9581
TEST_TIME 79 9488
TEST_TIME 80 9458
TEST_TIME 81 9430
TEST_TIME 82 9354
TEST_TIME 83 9299
TEST_TIME 84 9345
TEST_TIME 85 9308
TEST_TIME 86 9322
TEST_TIME 87 9295
TEST_TIME 88 9308
TEST_TIME 89 9271
TEST_TIME 90 9244
TEST_TIME 91 9301
TEST_TIME 92 9293
TEST_TIME 93 9264
TEST_TIME 94 9356
TEST_TIME 95 9332
TEST_TIME 96 9311
TEST_TIME 97 9267
TEST_TIME 98 9207
TEST_TIME 99 9155
TEST_TIME 100 9111
TEST_TIME 101 9097
TEST_TIME 102 9070
TEST_TIME 103 9059
TEST_TIME 104 9033
TEST_TIME 105 9039
TEST_TIME 106 9017
TEST_TIME 107 9052
TEST_TIME 108 9080
TEST_TIME 109 9053
TEST_TIME 110 9116
TEST_TIME 111 9082
TEST_TIME 112 9102
TEST_TIME 113 9118
TEST_TIME 114 9106
TEST_TIME 115 9170
TEST_TIME 116 9167
TEST_TIME 117 9099
TEST_TIME 118 9041
TEST_TIME 119 9071
TEST_TIME 120 9035

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2020 07:28:43 to 12-10-2020 07:28:46.

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 1517155 1517155 0 0.00 0 16741 7 0 0
doc_list_biud 1517154 1517154 0 0.00 0 30482 31 0 0
sp_add_invoice_to_stock 5205 5070 135 2.59 38 165568 17931 0 135
sp_cancel_adding_invoice 5375 5375 0 0.00 5 77690 951 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 8562 326 0 0
sp_cancel_customer_reserve 530051 530051 0 0.00 1 30485 71 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 8776 82 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 3 8385 88 0 0
sp_cancel_supplier_invoice 5949 5933 16 0.26 3 22827 1016 0 16
sp_cancel_supplier_order 390 390 0 0.00 8 1185852 120833 0 0
sp_cancel_write_off 9158 9158 0 0.00 1 13745 123 0 0
sp_client_order 19900 19900 0 0.00 4 18870 374 0 0
sp_customer_reserve 470042 470042 0 0.00 0 42976 176 0 0
sp_fill_shopping_cart 30796 30796 0 0.00 1 20459 42 0 0
sp_fill_shopping_cart_clo_res 470036 470036 0 0.00 0 22261 16 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 32292 343 0 0
sp_kill_cost_storno 15660 15660 0 0.00 0 8381 63 0 0
sp_kill_qstorno_ret_qs2qd 1075583 1075583 0 0.00 0 30476 38 0 0
sp_kill_qty_storno 567736 567736 0 0.00 0 30482 82 0 0
sp_lock_selected_doc 20856 20856 0 0.00 0 8030 5 0 0
sp_make_cost_storno 27853 27853 0 0.00 0 16280 61 0 0
sp_multiply_rows_for_pdistr 27853 27853 0 0.00 0 10089 31 0 0
sp_multiply_rows_for_qdistr 19900 19900 0 0.00 0 18674 214 0 0
sp_pay_from_customer 6995 6995 0 0.00 4 17665 114 0 0
sp_pay_to_supplier 5848 5848 0 0.00 5 21489 135 0 0
sp_payment_common 12843 12843 0 0.00 1 13067 28 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 8544 294 0 0
sp_qd_handle_on_invoice_upd_sts 10439 10439 0 0.00 0 14277 370 0 0
sp_qd_handle_on_reserve_upd_sts 9946 9946 0 0.00 0 14014 40 0 0
sp_reserve_write_off 9946 9946 0 0.00 7 16727 171 0 0
sp_supplier_invoice 5390 5390 0 0.00 7 120244 3510 0 0
sp_supplier_order 5512 5512 0 0.00 5 43956 1388 0 0
srv_aggregate_perf_data 110 110 0 0.00 2568 220325 15301 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1745 125007 45400 0 0
srv_find_qd_qs_mism 1035464 1035464 0 0.00 0 7457 6 0 0
srv_make_invnt_saldo 481 481 0 0.00 20 109803 3273 0 0
srv_make_money_saldo 778 778 0 0.00 1 12465 481 0 0
srv_recalc_idx_stat 4 4 0 0.00 266627 452619 360299 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5070 5070 0 0.00 0 13480 179 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 7271 110 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 6143 11 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 1289 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 5226 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 960 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 8726 56 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 18258 1544 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 3925 13 0 0
t$perf-norm:sp_client_order 19900 19900 0 0.00 0 14345 17 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 959 6 0 0
t$perf-norm:sp_pay_from_customer 6995 6995 0 0.00 0 7842 9 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 1436 6 0 0
t$perf-norm:sp_reserve_write_off 9946 9946 0 0.00 0 13839 16 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 8077 21 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 9220 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 178 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1145 25 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 577 11 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1486 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 44 13 0 0
v_add_invoice_to_stock 10394 10124 270 2.59 0 7068 17 0 270
v_all_customers 36592 36592 0 0.00 0 44 1 0 0
v_all_suppliers 29904 29904 0 0.00 0 14 1 0 0
v_all_wares 39800 39800 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3513 9 0 0
v_cancel_client_order 3638 3638 0 0.00 0 1422 5 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2289 10 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 1449 4 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 970 5 0 0
v_cancel_supplier_invoice 834 802 32 3.83 0 1651 16 0 32
v_cancel_supplier_order 780 780 0 0.00 0 3675 12 0 0
v_cancel_write_off 1990 1990 0 0.00 0 3414 15 0 0
v_max_id_clo_ord 5512 5512 0 0.00 0 989 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 5499 16 0 0
v_max_id_ord_sup 5384 5384 0 0.00 0 8816 4 0 0
v_max_non_paid_invoice 1792 1792 0 0.00 0 223 2 0 0
v_max_non_paid_realizn 3683 3683 0 0.00 0 2988 3 0 0
v_min_id_clo_ord 5512 5512 0 0.00 0 5640 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 568 3 0 0
v_min_id_ord_sup 5384 5384 0 0.00 0 3156 5 0 0
v_min_non_paid_invoice 5848 5848 0 0.00 0 7592 17 0 0
v_min_non_paid_realizn 6995 6995 0 0.00 0 9525 20 0 0
v_our_firm 9832 9832 0 0.00 0 14 1 0 0
v_reserve_write_off 19892 19892 0 0.00 0 4003 7 0 0
x_lock_depdocs_on_canc_invoice 10907 10907 0 0.00 0 24233 117 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 10069 343 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 2 43943 1310 0 0
x_make_qstorno_1200_2000 5390 5390 0 0.00 3 120236 3351 0 0
x_make_qstorno_2100_3300 470042 470042 0 0.00 0 39145 157 0 0

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

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
04:56:55 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
04:57:56 32314 8192000000 9729202432 10029969408 804481040 8.942 100 88 177 0 833711744 7516192 21714512
04:59:31 22788 8192000000 9769375760 10077065216 844590000 9.346 100 88 176 0 877258992 11761904 20907088
05:01:03 18518 8192000000 9801518912 10122891264 874767648 9.648 100 81 162 3 909793984 16738512 18287824
05:02:24 12465 8192000000 9870645232 10202583040 920250272 10.099 100 81 162 0 977953488 40140320 17562896
05:03:43 7510 8192000000 9944443264 10291871744 966239904 10.550 100 87 171 1 1058124992 74164368 17720720
05:04:53 3016 8192000000 9969029216 10324299776 1000988016 10.888 100 76 152 1 1085725696 69212192 15525488
05:05:59 2918 8192000000 10009029888 10368131072 1031910432 11.187 100 94 188 1 1120118064 68745296 19462336
05:07:03 2356 8192000000 10029289280 10399813632 1064031456 11.495 100 92 184 2 1147849984 65478576 18339952
05:08:07 34162 8192000000 10015074208 10398998528 1066604288 11.520 100 49 97 0 1130031632 52741824 10685520
05:09:42 6612 8192000000 9983561712 10378121216 1071274784 11.564 100 35 70 1 1097246272 18820832 7150656
05:10:49 9932 8192000000 9993146640 10400632832 1081531904 11.662 100 40 79 3 1123693568 33184512 8977152
05:12:00 5865 8192000000 10010196400 10408280064 1088058544 11.724 100 36 73 3 1117252000 21395520 7797936
05:13:06 37870 8192000000 9999265008 10417602560 1090158672 11.744 100 34 68 2 1113970752 16128928 7683152
05:14:47 4927 8192000000 10028235184 10438000640 1101335056 11.850 100 48 97 2 1147670128 35896144 10438928
05:15:54 9483 8192000000 10024217648 10441609216 1090909280 11.751 99 37 74 1 1129981120 30395952 8675888
05:17:06 6310 8192000000 10005298704 10412711936 1085597312 11.701 99 31 60 3 1116336304 24582992 6156000
05:18:13 36400 8192000000 9943749984 10337361920 937111632 10.265 87 25 50 0 956424800 14306448 5006720
05:19:52 6701 8192000000 9933124272 10317131776 986252432 10.745 96 38 76 1 1011942784 16901712 8788640
05:20:59 8416 8192000000 9946201088 10318458880 1015009088 11.024 99 46 93 5 1041749856 15961696 10779072
05:22:08 8658 8192000000 9959133664 10352754688 1026478832 11.135 99 26 52 1 1053031088 20709136 5843120
05:23:17 33934 8192000000 9940395152 10342699008 1025938128 11.129 99 24 47 2 1060011616 29531920 4541568
05:24:55 7843 8192000000 9967743264 10367504384 1060924784 11.465 100 20 40 1 1088754400 23753088 4076528
05:26:03 6810 8192000000 9981121520 10381725696 1069068160 11.543 100 45 92 0 1098716976 19234768 10414048
05:27:12 10178 8192000000 10005384720 10391572480 1081986304 11.666 100 54 107 4 1111652576 18102592 11563680
05:28:26 79224 8192000000 10040707216 10451865600 1093004432 11.771 100 37 73 2 1163733168 63071328 7657408
05:30:50 12140 8192000000 10080724464 10484940800 1108366752 11.917 100 37 74 1 1186352528 69597056 8388720
05:32:23 32951 8192000000 10110294864 10524553216 1125092416 12.075 100 51 102 0 1220140448 83097056 11950976
05:34:02 8170 8192000000 10171970320 10572357632 1132899648 12.149 100 61 121 0 1278360528 131905584 13555296
05:35:14 8897 8192000000 10201763520 10605375488 1143427760 12.248 100 56 111 1 1297612960 141063040 13122160
05:36:26 10915 8192000000 10210353200 10622779392 1154644560 12.353 100 54 107 2 1306242560 139832272 11765728
05:37:46 8803 8192000000 10247992736 10652811264 1173914336 12.533 100 54 107 1 1337659488 151499392 12245760
05:39:02 2291 8192000000 10275952016 10674937856 1156609504 12.371 100 76 149 1 1378538528 205481760 16447264
05:40:10 6270 8192000000 10292263872 10713341952 1153006224 12.338 100 68 134 0 1399390112 231596416 14787472
05:41:19 1743 8192000000 10244448512 10656477184 1142005104 12.234 100 76 153 0 1346563696 188027296 16531296
05:42:27 3575 8192000000 10297952400 10689413120 1138940032 12.206 99 75 148 0 1383729504 227783920 17005552
05:43:36 3924 8192000000 10324081376 10717495296 1141105392 12.226 100 80 160 2 1413872560 254419824 18347344
05:44:42 1745 8192000000 10304415680 10698944512 1156844512 12.374 100 73 147 0 1401312976 227476448 16992016
05:45:47 2935 8192000000 10357162112 10774032384 1152867040 12.336 100 56 112 2 1459047360 292824608 13355712
05:47:03 7040 8192000000 10396335568 10805809152 1151009888 12.319 100 54 108 1 1493013536 328867872 13135776
05:48:13 7908 8192000000 10430750640 10843021312 1138190576 12.199 100 48 96 0 1525719440 374926912 12601952
05:49:24 2157 8192000000 10493033136 10898145280 1146522048 12.277 100 62 124 1 1584155264 421095344 16537872
05:50:28 10638 8192000000 10360446288 10789769216 1126092240 12.085 100 39 78 3 1475085472 338727248 10265984
05:51:57 19542 8192000000 10415508688 10881142784 1143315184 12.247 100 33 66 1 1507211168 355330608 8565376
05:53:20 26851 8192000000 10388588400 10891382784 1125779392 12.082 100 31 63 0 1516430736 381997856 8653488
05:54:49 51864 8192000000 10084269136 10602536960 1099188384 11.830 98 9 18 1 1209938912 108796608 1953920
05:56:45 102913 8192000000 10042818544 10481373184 1108680000 11.920 99 25 49 1 1144371376 30894864 4796512
05:59:38 26631 8192000000 10112069392 10563125248 1116889728 11.998 99 51 101 0 1178054960 50673040 10492192
06:01:06 30983 8192000000 10104949136 10582667264 1127963664 12.102 100 37 73 4 1230224272 93670368 8590240
06:02:43 56699 8192000000 10104892736 10550448128 1115667520 11.986 98 17 34 1 1173717792 53999104 4051168
06:04:41 42271 8192000000 10074001424 10538332160 1099104592 11.829 97 15 30 0 1164973312 62537872 3330848
06:06:25 34004 8192000000 10064955584 10524520448 1135127408 12.170 100 8 16 1 1171500736 34897776 1475552
06:08:00 46460 8192000000 10109393888 10573725696 1140774400 12.223 100 10 20 1 1207648528 64992544 1881584
06:09:46 112668 8192000000 10097588048 10581463040 1125248288 12.077 98 9 18 1 1173625920 46869072 1508560
06:12:39 25082 8192000000 10104384704 10526924800 1140283152 12.218 99 38 75 1 1171258240 22998048 7977040
06:14:08 60008 8192000000 10085703392 10547224576 1127515488 12.098 98 14 28 0 1179463600 49034352 2913760
06:16:16 47143 8192000000 10096175312 10531954688 1103450576 11.870 97 32 63 1 1172707968 63205296 6052096
06:18:08 46933 8192000000 10113777920 10567659520 1121555200 12.042 99 28 56 1 1193504512 66215008 5734304
06:19:56 98921 8192000000 10068397088 10577932288 1078282944 11.631 94 13 27 0 1157659872 76468416 2908512
06:22:37 119136 8192000000 10041712160 10500227072 1095989168 11.800 97 22 44 2 1131452768 31209296 4254304
06:25:36 63409 8192000000 10053285152 10499641344 1113583520 11.966 99 10 20 0 1141021952 25252192 2186240
06:27:40 59580 8192000000 10062564048 10513481728 1113871536 11.969 99 9 18 0 1155253296 39625664 1756096
06:29:45 46611 8192000000 10054376544 10511577088 1085372064 11.699 97 17 33 0 1147069328 58324096 3373168
06:31:37 39911 8192000000 10075584800 10513231872 1075658912 11.606 96 22 44 0 1145544112 65209200 4676000
06:33:31 49366 8192000000 10070777056 10517516288 1080996400 11.657 97 18 36 1 1174680816 89620192 4064224
06:35:20 71065 8192000000 10040551504 10557362176 1078404912 11.632 97 23 46 1 1154132576 70785088 4942576
06:37:40 58816 8192000000 10071922544 10553372672 1086836704 11.713 97 21 42 2 1178214848 87371152 4006992
06:39:48 43927 8192000000 10058430256 10484801536 1085331536 11.698 97 18 36 0 1122124976 33523392 3270048
06:41:47 47351 8192000000 10142833408 10577240064 1110610672 11.938 99 38 76 1 1201202224 83030560 7560992
06:43:37 82205 8192000000 10066683328 10526330880 1110871376 11.941 99 18 36 0 1153295600 38506992 3917232
06:46:39 59044 8192000000 10041144288 10519019520 1111522464 11.947 99 10 20 0 1145629392 31982016 2124912
06:48:38 69202 8192000000 10056759280 10517868544 1078417952 11.632 96 9 18 0 1122408640 41682176 2308512
06:50:47 53870 8192000000 10079593872 10601840640 1096176704 11.801 98 9 18 0 1163418992 65412656 1829632
06:52:45 46986 8192000000 10069798896 10507440128 1124588656 12.070 100 33 67 1 1172585104 40119776 7876672
06:54:35 37499 8192000000 10062485056 10491748352 1104248160 11.878 98 19 38 0 1140917408 33083216 3586032
06:56:13 56107 8192000000 10122560640 10573086720 1124759456 12.072 99 38 76 1 1209857232 76815712 8282064
06:58:18 41293 8192000000 10105354000 10546638848 1109186720 11.925 98 31 62 0 1163842816 48187040 6469056
07:00:00 83394 8192000000 10079476480 10548563968 1118214880 12.010 99 14 28 1 1181166816 59884144 3067792
07:02:27 89900 8192000000 10085495280 10544504832 1135777360 12.176 100 36 72 0 1195660608 52899264 6983984
07:05:31 125006 8192000000 10080845120 10567442432 1120200720 12.029 98 11 22 0 1160249344 37805360 2243264
07:08:37 49060 8192000000 10052730768 10489966592 1110030784 11.933 97 13 27 0 1129128448 16674448 2423216
07:10:39 46216 8192000000 10126834240 10571804672 1154909776 12.356 100 20 40 0 1215729968 56900128 3920064
07:12:43 63550 8192000000 10116697584 10550280192 1117886576 12.007 97 22 44 1 1160422672 37860608 4675488
07:14:52 76390 8192000000 10120623312 10574209024 1116608080 11.995 97 35 70 1 1211547696 88137520 6802096
07:17:13 48961 8192000000 10083862384 10495016960 1081049024 11.657 95 44 88 0 1110010528 20295968 8665536
07:19:05 66511 8192000000 10082325664 10530762752 1113682960 11.967 98 17 34 1 1161947792 44665952 3598880
07:21:16 75822 8192000000 10051950448 10505678848 1054540688 11.404 94 10 20 2 1108894032 51994864 2358480
07:23:41 81340 8192000000 10100184800 10561155072 1101136192 11.848 99 7 14 0 1185202640 82526112 1540336
07:26:04 48928 8192000000 9979161376 10469699584 1037598800 11.242 94 10 20 0 1081130288 41671120 1860368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-10-2020 07:28:53 to 12-10-2020 07:29:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 135 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2020 07:29:09 to 12-10-2020 07:29:11.

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          238971
MON$OLDEST_ACTIVE               240044
MON$OLDEST_SNAPSHOT             240044
MON$NEXT_TRANSACTION            240044
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                       2106112
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.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:04 2020
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:12 2020
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:12 2020
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:12 2020
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:12 2020
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Mon Oct 12 07:27:12 2020
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:85: 
--- end of diff output ---

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

12.10.20 07:29:12. 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-12 07:29:14 No severe PSQL-related problems occured
12.10.20 07:29:16. End of report.