Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.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 17127460 kB
MemAvailable 31489076 kB
Buffers 0 kB
Cached 14535260 kB
SwapCached 3064 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-31 10:26:53
DTS_END                         2020-10-31 10:26:53.0840
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/57948
TRN_ID                          224304
ATT_ID                          556
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: 8595.33 23507 1031439
customer order: creation 161.81 440 19417
customer order: refuse 13.39 372 1607
order to supplier: creation 42.73 1494 5127
order to supplier: removal 3.23 126342 388
invoice (draft): creation 42.48 3830 5097
invoice (draft): removal 48.19 999 5783
invoice accept: apply 38.92 18311 4670
invoice accept: cancel 43.43 897 5211
customer reserve: creation 3598.95 177 431874
customer reserve: removal 4325.07 75 519008
realization accept: apply 77.97 167 9356
realization accept: cancel 79.10 123 9492
payment from customer: creation 55.86 97 6703
payment from customer: removal 5.33 81 639
payment to supplier: creation 45.54 113 5465
payment to supplier: removal 3.07 76 368
service: total inventory turnovers 3.54 3600 425
service: total monetary turnovers 5.86 490 703
service: aggregate perf. data 0.85 14225 102
service: refresh index statistics 0.03 298222 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-10-2020 10:28:11 to 31-10-2020 10:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 189
TEST_TIME 2 1675
TEST_TIME 3 2628
TEST_TIME 4 3406
TEST_TIME 5 4109
TEST_TIME 6 4351
TEST_TIME 7 4208
TEST_TIME 8 4251
TEST_TIME 9 4268
TEST_TIME 10 4143
TEST_TIME 11 4364
TEST_TIME 12 4507
TEST_TIME 13 4961
TEST_TIME 14 5048
TEST_TIME 15 5538
TEST_TIME 16 5903
TEST_TIME 17 6264
TEST_TIME 18 6406
TEST_TIME 19 7227
TEST_TIME 20 7452
TEST_TIME 21 7419
TEST_TIME 22 8007
TEST_TIME 23 7906
TEST_TIME 24 8054
TEST_TIME 25 8382
TEST_TIME 26 8731
TEST_TIME 27 9398
TEST_TIME 28 9787
TEST_TIME 29 10184
TEST_TIME 30 9858
TEST_TIME 31 9942
TEST_TIME 32 9965
TEST_TIME 33 10009
TEST_TIME 34 9810
TEST_TIME 35 9727
TEST_TIME 36 9562
TEST_TIME 37 9422
TEST_TIME 38 9294
TEST_TIME 39 9084
TEST_TIME 40 8991
TEST_TIME 41 8984
TEST_TIME 42 8897
TEST_TIME 43 8857
TEST_TIME 44 8743
TEST_TIME 45 8838
TEST_TIME 46 8842
TEST_TIME 47 8847
TEST_TIME 48 8869
TEST_TIME 49 8824
TEST_TIME 50 8811
TEST_TIME 51 8822
TEST_TIME 52 8930
TEST_TIME 53 9051
TEST_TIME 54 9025
TEST_TIME 55 9051
TEST_TIME 56 9167
TEST_TIME 57 9109
TEST_TIME 58 8982
TEST_TIME 59 8873
TEST_TIME 60 8843
TEST_TIME 61 8735
TEST_TIME 62 8745
TEST_TIME 63 8678
TEST_TIME 64 8755
TEST_TIME 65 8734
TEST_TIME 66 8714
TEST_TIME 67 8734
TEST_TIME 68 8752
TEST_TIME 69 8772
TEST_TIME 70 8802
TEST_TIME 71 8833
TEST_TIME 72 8835
TEST_TIME 73 8947
TEST_TIME 74 9033
TEST_TIME 75 9038
TEST_TIME 76 9058
TEST_TIME 77 9107
TEST_TIME 78 8993
TEST_TIME 79 8915
TEST_TIME 80 8879
TEST_TIME 81 8824
TEST_TIME 82 8750
TEST_TIME 83 8699
TEST_TIME 84 8665
TEST_TIME 85 8628
TEST_TIME 86 8609
TEST_TIME 87 8637
TEST_TIME 88 8582
TEST_TIME 89 8566
TEST_TIME 90 8546
TEST_TIME 91 8719
TEST_TIME 92 8699
TEST_TIME 93 8893
TEST_TIME 94 8914
TEST_TIME 95 8893
TEST_TIME 96 8885
TEST_TIME 97 8874
TEST_TIME 98 8824
TEST_TIME 99 8808
TEST_TIME 100 8750
TEST_TIME 101 8744
TEST_TIME 102 8730
TEST_TIME 103 8713
TEST_TIME 104 8689
TEST_TIME 105 8698
TEST_TIME 106 8706
TEST_TIME 107 8713
TEST_TIME 108 8746
TEST_TIME 109 8743
TEST_TIME 110 8746
TEST_TIME 111 8796
TEST_TIME 112 8784
TEST_TIME 113 8797
TEST_TIME 114 8733
TEST_TIME 115 8690
TEST_TIME 116 8665
TEST_TIME 117 8632
TEST_TIME 118 8608
TEST_TIME 119 8617
TEST_TIME 120 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2020 10:28:15 to 31-10-2020 10:28:18.

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 1431613 1431613 0 0.00 0 23771 6 0 0
doc_list_biud 1431613 1431613 0 0.00 0 35360 34 0 0
sp_add_invoice_to_stock 4779 4670 109 2.28 33 198313 18312 0 109
sp_cancel_adding_invoice 5211 5211 0 0.00 6 85780 897 0 0
sp_cancel_client_order 1607 1607 0 0.00 4 11373 372 0 0
sp_cancel_customer_reserve 519008 519008 0 0.00 1 33489 75 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 4819 81 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 2566 77 0 0
sp_cancel_supplier_invoice 5789 5783 6 0.10 2 20490 999 0 6
sp_cancel_supplier_order 388 388 0 0.00 8 1278299 126343 0 0
sp_cancel_write_off 9492 9492 0 0.00 1 9212 124 0 0
sp_client_order 19417 19417 0 0.00 4 34176 440 0 0
sp_customer_reserve 431874 431874 0 0.00 0 109800 177 0 0
sp_fill_shopping_cart 29637 29637 0 0.00 1 25303 54 0 0
sp_fill_shopping_cart_clo_res 431871 431871 0 0.00 0 34251 23 0 0
sp_get_clo_for_invoice 4664 4664 0 0.00 0 42186 517 0 0
sp_kill_cost_storno 15710 15710 0 0.00 0 6347 60 0 0
sp_kill_qstorno_ret_qs2qd 1053679 1053679 0 0.00 0 32143 41 0 0
sp_kill_qty_storno 555505 555505 0 0.00 0 35360 87 0 0
sp_lock_selected_doc 19262 19262 0 0.00 0 9240 6 0 0
sp_make_cost_storno 26184 26184 0 0.00 0 11778 45 0 0
sp_multiply_rows_for_pdistr 26184 26184 0 0.00 0 9325 29 0 0
sp_multiply_rows_for_qdistr 19417 19417 0 0.00 0 34107 276 0 0
sp_pay_from_customer 6703 6703 0 0.00 4 9463 97 0 0
sp_pay_to_supplier 5465 5465 0 0.00 4 9342 114 0 0
sp_payment_common 12168 12168 0 0.00 1 7078 15 0 0
sp_qd_handle_on_cancel_clo 1607 1607 0 0.00 0 11229 344 0 0
sp_qd_handle_on_invoice_upd_sts 9871 9871 0 0.00 0 35359 423 0 0
sp_qd_handle_on_reserve_upd_sts 9356 9356 0 0.00 0 13713 58 0 0
sp_reserve_write_off 9356 9356 0 0.00 7 16267 167 0 0
sp_supplier_invoice 5097 5097 0 0.00 6 101708 3831 0 0
sp_supplier_order 5127 5127 0 0.00 5 34929 1494 0 0
srv_aggregate_perf_data 102 102 0 0.00 3001 201956 14226 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1623 156404 47866 0 0
srv_find_qd_qs_mism 986975 986975 0 0.00 0 23771 5 0 0
srv_make_invnt_saldo 425 425 0 0.00 34 120183 3601 0 0
srv_make_money_saldo 703 703 0 0.00 1 11211 491 0 0
srv_recalc_idx_stat 4 4 0 0.00 156248 471459 298223 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4670 4670 0 0.00 0 13333 183 0 0
t$perf-norm:sp_cancel_adding_invoice 348 348 0 0.00 0 6855 120 0 0
t$perf-norm:sp_cancel_client_order 1607 1607 0 0.00 0 2125 9 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 934 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 1152 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 2651 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 341 341 0 0.00 0 2984 23 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 24828 1519 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 3594 10 0 0
t$perf-norm:sp_client_order 19417 19417 0 0.00 0 7710 19 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 5539 24 0 0
t$perf-norm:sp_pay_from_customer 6703 6703 0 0.00 0 5200 9 0 0
t$perf-norm:sp_pay_to_supplier 5465 5465 0 0.00 0 4560 9 0 0
t$perf-norm:sp_reserve_write_off 9356 9356 0 0.00 0 3298 12 0 0
t$perf-norm:sp_supplier_invoice 5097 5097 0 0.00 0 5488 19 0 0
t$perf-norm:sp_supplier_order 5127 5127 0 0.00 0 3276 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 351 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 101 3 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 932 6 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 1662 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9538 9320 218 2.28 0 5990 18 0 218
v_all_customers 35996 35996 0 0.00 0 18 1 0 0
v_all_suppliers 28438 28438 0 0.00 0 20 1 0 0
v_all_wares 38832 38832 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 696 696 0 0.00 0 1052 11 0 0
v_cancel_client_order 3214 3214 0 0.00 0 3808 7 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 2000 10 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 2748 8 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 373 3 0 0
v_cancel_supplier_invoice 694 682 12 1.72 0 796 14 0 12
v_cancel_supplier_order 776 776 0 0.00 0 2004 10 0 0
v_cancel_write_off 1848 1848 0 0.00 0 1510 14 0 0
v_max_id_clo_ord 5127 5127 0 0.00 0 846 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 3789 17 0 0
v_max_id_ord_sup 5094 5094 0 0.00 0 3050 4 0 0
v_max_non_paid_invoice 1467 1467 0 0.00 0 715 3 0 0
v_max_non_paid_realizn 3405 3405 0 0.00 0 997 3 0 0
v_min_id_clo_ord 5127 5127 0 0.00 0 7831 12 0 0
v_min_id_clo_res 965 965 0 0.00 0 7108 28 0 0
v_min_id_ord_sup 5094 5094 0 0.00 0 12578 16 0 0
v_min_non_paid_invoice 5465 5465 0 0.00 0 7073 10 0 0
v_min_non_paid_realizn 6703 6703 0 0.00 0 6567 9 0 0
v_our_firm 9432 9432 0 0.00 0 44 1 0 0
v_reserve_write_off 18712 18712 0 0.00 0 3916 5 0 0
x_lock_depdocs_on_canc_invoice 10653 10653 0 0.00 0 9337 109 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 11884 320 0 0
x_make_qstorno_1000_1200 5127 5127 0 0.00 2 33924 1412 0 0
x_make_qstorno_1200_2000 5097 5097 0 0.00 3 101699 3603 0 0
x_make_qstorno_2100_3300 431874 431874 0 0.00 0 109773 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 10:28:19 to 31-10-2020 10:28:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:56 2 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:58 19818 8192000000 9701450480 9997094912 785829392 8.752 100 79 158 0 812524480 7086112 19608976
07:59:34 15581 8192000000 9758557312 10067087360 835915600 9.259 100 88 176 1 868524896 11744304 20864992
08:00:55 21665 8192000000 9802854000 10121900032 865665008 9.557 100 79 156 1 908188960 24780000 17743952
08:02:22 11533 8192000000 9854566592 10186240000 912417136 10.021 100 77 154 1 966991216 37562544 17011536
08:03:45 13379 8192000000 9910929520 10251649024 955993328 10.450 100 81 162 1 1013938608 41406768 16538512
08:05:00 5091 8192000000 9969802480 10319052800 988777184 10.770 100 83 166 4 1081426608 76232592 16416832
08:06:09 3796 8192000000 9982993024 10341257216 1011035968 10.985 100 84 168 0 1094645008 66722128 16886912
08:07:21 2982 8192000000 9992687728 10357833728 1041586320 11.280 100 85 170 0 1109999808 51423920 16989568
08:08:26 1966 8192000000 10017148272 10387390464 1055534048 11.414 100 89 178 2 1125961824 52261584 18166192
08:09:31 2049 8192000000 10009412576 10392387584 1063397104 11.489 100 61 121 2 1132709776 57331904 11980768
08:10:35 4979 8192000000 9997693920 10391773184 1074866272 11.599 100 38 77 0 1134482400 52198480 7417648
08:11:41 21714 8192000000 9989917808 10392817664 1082427392 11.671 100 33 66 2 1116742848 27046080 7269376
08:13:04 23828 8192000000 9992027216 10402185216 1084156320 11.687 100 22 42 3 1120929072 31737648 5035104
08:14:29 8259 8192000000 10005717984 10410201088 1094051584 11.781 100 39 76 2 1117406640 14915760 8439296
08:15:40 5912 8192000000 9997725968 10396377088 1095969776 11.799 100 34 68 1 1119021360 13363472 9688112
08:16:47 12308 8192000000 10005142064 10413428736 1085181808 11.697 99 33 66 1 1116210112 24096864 6931440
08:18:03 9359 8192000000 10003753952 10399752192 1092185264 11.763 100 48 98 2 1114977952 12404656 10388032
08:19:12 4310 8192000000 9983753008 10370588672 1040988176 11.274 97 59 115 5 1073456000 18483664 13984160
08:20:18 6703 8192000000 9925932688 10301829120 972267280 10.609 94 32 64 2 987984000 8948880 6767840
08:21:29 12657 8192000000 9928963808 10314416128 983651072 10.720 96 42 83 4 1016373120 23743760 8978288
08:22:42 51513 8192000000 9937376144 10326720512 983419920 10.717 95 20 40 2 1012834784 25119680 4295184
08:24:35 6072 8192000000 9957347696 10342105088 1046087744 11.323 100 45 91 1 1069725296 13802960 9834592
08:25:43 5254 8192000000 9960978912 10347184128 1058082416 11.438 100 26 53 1 1073322192 9151344 6088432
08:26:49 112328 8192000000 10037428928 10432090112 1079776944 11.645 100 32 64 0 1103507472 17538192 6192336
08:29:49 10059 8192000000 10080297776 10478821376 1105397152 11.889 100 50 100 0 1175308624 59718608 10192864
08:31:05 10027 8192000000 10129089056 10537373696 1118746224 12.015 100 60 121 1 1249150656 117795104 12609328
08:32:21 11267 8192000000 10124645776 10527932416 1136210672 12.180 100 51 99 4 1230252352 82526656 11515024
08:33:44 18858 8192000000 10188313376 10590814208 1146132272 12.273 100 53 106 1 1283546496 125487872 11926352
08:35:16 13312 8192000000 10215567696 10638635008 1162757472 12.429 100 41 80 3 1324491984 151520112 10214400
08:36:35 7716 8192000000 10265185920 10668077056 1172348624 12.519 100 59 118 0 1368687472 182652976 13685872
08:37:53 6129 8192000000 10329584576 10735726592 1179037120 12.581 100 70 140 0 1438135936 243278352 15820464
08:39:03 2083 8192000000 10357559968 10781093888 1186882688 12.654 100 70 138 1 1456780512 253671776 16226048
08:40:09 5245 8192000000 10383371952 10795102208 1176730000 12.560 99 64 126 0 1485524672 294237680 14556992
08:41:23 4894 8192000000 10391586576 10822180864 1185767712 12.644 100 64 126 2 1491325072 290956800 14600560
08:42:39 11141 8192000000 10388083456 10816684032 1177643360 12.568 99 68 134 0 1496425008 302652096 16129552
08:43:56 1797 8192000000 10416821840 10818777088 1166927472 12.468 100 86 172 0 1507613840 320839472 19846896
08:45:05 1620 8192000000 10419839216 10839461888 1161959264 12.422 100 85 166 1 1518451376 336800880 19691232
08:46:12 11368 8192000000 10339781408 10792361984 1149899376 12.309 99 49 98 1 1461752176 300423008 11429792
08:47:27 20055 8192000000 10345598304 10774417408 1124560480 12.070 99 37 72 2 1448977984 315148480 9269024
08:48:52 24972 8192000000 10310466144 10752860160 1117857616 12.007 99 21 42 2 1393831104 270528144 5445344
08:50:18 23152 8192000000 10315478880 10750894080 1085366592 11.699 98 23 46 4 1404828912 313407552 6054768
08:51:50 12639 8192000000 10285088224 10745274368 1112772048 11.959 100 30 60 2 1377809440 257550720 7486672
08:53:04 15306 8192000000 10185017392 10643365888 1116730112 11.996 100 27 52 0 1337678752 214548768 6399872
08:54:23 42573 8192000000 10146998656 10666033152 1098386512 11.822 99 13 26 1 1284517232 182921712 3209008
08:56:30 149444 8192000000 10066690816 10527223808 1067341552 11.527 95 28 57 2 1142554560 69033840 6179168
09:00:01 47683 8192000000 10041031520 10452017152 1106993648 11.904 99 48 100 3 1141515200 22251488 12270064
09:01:53 72678 8192000000 10079162864 10522202112 1114713312 11.977 99 17 35 0 1151452400 33310688 3428400
09:04:09 131353 8192000000 10073260688 10505764864 1118696816 12.015 99 21 41 0 1171266944 48690480 3879648
09:07:24 38695 8192000000 10115532224 10559496192 1126768576 12.091 99 44 86 3 1195192736 58211216 10212944
09:09:10 62721 8192000000 10112851456 10574864384 1138636224 12.203 100 28 55 4 1225394176 79695280 7062672
09:11:32 41105 8192000000 10056880800 10516819968 1125646768 12.080 99 26 52 1 1153728176 22535264 5546144
09:13:22 20768 8192000000 10068288912 10496024576 1140277296 12.218 100 28 55 1 1167535904 22151568 5107040
09:14:45 34464 8192000000 10064541424 10499158016 1116317392 11.992 98 21 42 1 1158817120 38429616 4070112
09:16:22 72641 8192000000 10119140048 10587123712 1103187808 11.868 96 25 49 0 1201756976 93265568 5303600
09:18:36 79281 8192000000 10085750448 10540859392 1133347648 12.153 100 17 34 1 1214165424 76879456 3938320
09:21:20 122410 8192000000 10067570176 10523332608 1127604000 12.099 98 11 20 2 1191265264 61604208 2057056
09:24:23 136218 8192000000 10068735120 10530594816 1120477200 12.031 98 32 64 1 1183130128 56002096 6650832
09:27:41 45859 8192000000 10109688832 10576924672 1142168096 12.236 100 10 20 0 1202407600 58121344 2118160
09:29:42 42668 8192000000 10087920016 10534449152 1110882528 11.941 98 20 40 0 1162597216 47436704 4277984
09:31:29 51539 8192000000 10089030672 10529353728 1133834736 12.157 99 32 64 0 1187264752 47040784 6389232
09:33:22 70875 8192000000 10121849664 10573774848 1131273248 12.133 98 20 40 1 1192312112 56221248 4817616
09:35:38 36352 8192000000 10119096592 10578702336 1099543728 11.833 96 13 26 0 1148499376 46476752 2478896
09:37:21 95635 8192000000 10143452512 10595467264 1082379920 11.670 95 17 34 0 1176056848 89981936 3694992
09:39:58 68496 8192000000 10073328128 10559991808 1056014736 11.418 93 15 30 2 1136632672 77782944 2834992
09:42:11 21910 8192000000 10111714720 10528432128 1119783408 12.025 100 49 98 0 1180293328 50606160 9903760
09:43:37 59170 8192000000 10054924784 10577895424 1108637920 11.920 100 4 8 0 1161438208 52127248 673040
09:45:37 67602 8192000000 10049695760 10498048000 1108704880 11.920 99 15 30 2 1145970640 34041600 3224160
09:47:48 39660 8192000000 10003033360 10459418624 1072979120 11.581 97 1 2 0 1091462848 18448240 35488
09:49:28 53764 8192000000 10046292224 10480050176 1073692736 11.587 97 21 42 2 1112509424 34368544 4448144
09:51:22 45257 8192000000 10111401680 10543706112 1122932160 12.055 100 17 34 1 1205895888 79222688 3741040
09:53:07 82765 8192000000 10154143296 10602721280 1089771616 11.740 96 23 45 2 1212322256 117038128 5512512
09:55:36 31481 8192000000 10208341664 10641850368 1112412336 11.955 99 25 50 1 1280033248 161983840 5637072
09:57:08 84213 8192000000 10180269344 10610769920 1093052032 11.772 97 15 30 0 1290102912 193486800 3564080
09:59:44 38127 8192000000 10106599344 10565959680 1109696128 11.930 99 19 38 0 1162277280 49136720 3444432
10:01:22 40630 8192000000 10098539168 10545070080 1127875856 12.101 100 30 61 0 1176064128 42013008 6175264
10:03:09 40937 8192000000 10112734240 10568921088 1138595296 12.202 100 49 98 1 1204392416 55847856 9949264
10:06:01 64174 8192000000 10089777280 10573348864 1125726224 12.081 99 6 12 0 1163700336 36837840 1136272
10:08:06 87690 8192000000 10073527360 10507624448 1107261888 11.906 97 16 32 1 1145751600 34955504 3534208
10:10:34 25270 8192000000 10104214144 10539573248 1136860576 12.186 99 22 44 0 1184304384 42682432 4761376
10:12:03 38268 8192000000 10099843536 10552446976 1132928928 12.149 99 15 30 3 1191779584 55757648 3093008
10:13:46 33959 8192000000 10096975664 10534621184 1132238144 12.142 99 42 80 2 1190401088 49889248 8273696
10:15:27 156404 8192000000 10087262640 10574036992 1090615728 11.749 95 8 16 0 1137176336 44866736 1693872
10:19:04 77388 8192000000 10086539376 10525609984 1090363840 11.746 96 25 50 1 1149267760 53885888 5018032
10:22:22 45737 8192000000 10084140128 10543468544 1136453648 12.182 100 6 12 0 1174222912 36344080 1425184
10:25:18 45789 8192000000 10080761344 10523398144 1137696048 12.194 100 19 37 0 1179540928 37278096 4566784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2020 10:28:25 to 31-10-2020 10:28:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 109 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

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

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          222977
MON$OLDEST_ACTIVE               225167
MON$OLDEST_SNAPSHOT             225167
MON$NEXT_TRANSACTION            225167
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                       2072288
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             758
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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 ---
:59: 
:60: mscs-test-161.budzdorov.ru    Sat Oct 31 10:26:53 2020
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Oct 31 10:26:59 2020
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Oct 31 10:26:59 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
--- end of diff output ---

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

31.10.20 10:28:44. 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-31 10:28:47 No severe PSQL-related problems occured
31.10.20 10:28:48. End of report.