Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 22: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 17588348 kB
MemAvailable 31540908 kB
Buffers 0 kB
Cached 14096660 kB
SwapCached 3048 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 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-23 22:27:01
DTS_END                         2020-10-23 22:27:01.3030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/54254
TRN_ID                          255388
ATT_ID                          727
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: 9488.98 21400 1138678
customer order: creation 185.20 386 22224
customer order: refuse 16.02 290 1923
order to supplier: creation 49.56 1164 5947
order to supplier: removal 3.58 86284 430
invoice (draft): creation 49.70 3117 5964
invoice (draft): removal 52.41 722 6289
invoice accept: apply 44.11 17329 5293
invoice accept: cancel 47.33 711 5680
customer reserve: creation 4055.53 173 486664
customer reserve: removal 4670.40 53 560448
realization accept: apply 92.23 173 11067
realization accept: cancel 82.48 95 9898
payment from customer: creation 63.93 110 7672
payment from customer: removal 6.34 59 761
payment to supplier: creation 53.11 117 6373
payment to supplier: removal 3.79 69 455
service: total inventory turnovers 5.42 1022 650
service: total monetary turnovers 6.93 21 831
service: aggregate perf. data 0.88 17246 105
service: refresh index statistics 0.03 298852 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-10-2020 22:28:05 to 23-10-2020 22:28:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9792
TEST_TIME 2 8303
TEST_TIME 3 8016
TEST_TIME 4 8447
TEST_TIME 5 8576
TEST_TIME 6 7985
TEST_TIME 7 7796
TEST_TIME 8 7772
TEST_TIME 9 7840
TEST_TIME 10 8035
TEST_TIME 11 8794
TEST_TIME 12 8804
TEST_TIME 13 9411
TEST_TIME 14 10019
TEST_TIME 15 9830
TEST_TIME 16 9659
TEST_TIME 17 9832
TEST_TIME 18 9986
TEST_TIME 19 10208
TEST_TIME 20 10415
TEST_TIME 21 11157
TEST_TIME 22 12819
TEST_TIME 23 12597
TEST_TIME 24 12261
TEST_TIME 25 11967
TEST_TIME 26 11720
TEST_TIME 27 11449
TEST_TIME 28 11268
TEST_TIME 29 11274
TEST_TIME 30 11135
TEST_TIME 31 11137
TEST_TIME 32 11055
TEST_TIME 33 10919
TEST_TIME 34 10880
TEST_TIME 35 10727
TEST_TIME 36 10644
TEST_TIME 37 10566
TEST_TIME 38 10576
TEST_TIME 39 10410
TEST_TIME 40 10204
TEST_TIME 41 10369
TEST_TIME 42 10319
TEST_TIME 43 10270
TEST_TIME 44 10240
TEST_TIME 45 10173
TEST_TIME 46 10144
TEST_TIME 47 10028
TEST_TIME 48 9952
TEST_TIME 49 10208
TEST_TIME 50 10277
TEST_TIME 51 10385
TEST_TIME 52 10321
TEST_TIME 53 10376
TEST_TIME 54 10344
TEST_TIME 55 10402
TEST_TIME 56 10380
TEST_TIME 57 10392
TEST_TIME 58 10239
TEST_TIME 59 10204
TEST_TIME 60 10099
TEST_TIME 61 10088
TEST_TIME 62 10058
TEST_TIME 63 9981
TEST_TIME 64 9977
TEST_TIME 65 9946
TEST_TIME 66 9884
TEST_TIME 67 9927
TEST_TIME 68 9885
TEST_TIME 69 9955
TEST_TIME 70 9935
TEST_TIME 71 9979
TEST_TIME 72 10016
TEST_TIME 73 9995
TEST_TIME 74 9958
TEST_TIME 75 9959
TEST_TIME 76 10025
TEST_TIME 77 10025
TEST_TIME 78 9982
TEST_TIME 79 10049
TEST_TIME 80 9924
TEST_TIME 81 9948
TEST_TIME 82 9924
TEST_TIME 83 9914
TEST_TIME 84 9828
TEST_TIME 85 9772
TEST_TIME 86 9740
TEST_TIME 87 9756
TEST_TIME 88 9727
TEST_TIME 89 9751
TEST_TIME 90 9701
TEST_TIME 91 9699
TEST_TIME 92 9724
TEST_TIME 93 9732
TEST_TIME 94 9838
TEST_TIME 95 9848
TEST_TIME 96 9801
TEST_TIME 97 9875
TEST_TIME 98 9825
TEST_TIME 99 9876
TEST_TIME 100 9875
TEST_TIME 101 9882
TEST_TIME 102 9877
TEST_TIME 103 9808
TEST_TIME 104 9759
TEST_TIME 105 9759
TEST_TIME 106 9747
TEST_TIME 107 9695
TEST_TIME 108 9670
TEST_TIME 109 9646
TEST_TIME 110 9564
TEST_TIME 111 9539
TEST_TIME 112 9514
TEST_TIME 113 9540
TEST_TIME 114 9604
TEST_TIME 115 9595
TEST_TIME 116 9592
TEST_TIME 117 9624
TEST_TIME 118 9611
TEST_TIME 119 9572
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2020 22:28:09 to 23-10-2020 22:28:12.

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 1589748 1589748 0 0.00 0 19504 6 0 0
doc_list_biud 1589745 1589745 0 0.00 0 52492 24 0 0
sp_add_invoice_to_stock 5426 5293 133 2.45 31 193649 17329 0 133
sp_cancel_adding_invoice 5680 5680 0 0.00 4 55298 711 0 0
sp_cancel_client_order 1923 1923 0 0.00 4 12099 290 0 0
sp_cancel_customer_reserve 560448 560448 0 0.00 1 52495 53 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 3 2969 59 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 2797 69 0 0
sp_cancel_supplier_invoice 6294 6289 5 0.07 1 43357 722 0 5
sp_cancel_supplier_order 430 430 0 0.00 8 984537 86285 0 0
sp_cancel_write_off 9898 9898 0 0.00 1 21695 96 0 0
sp_client_order 22224 22224 0 0.00 3 88663 387 0 0
sp_customer_reserve 486664 486664 0 0.00 0 79424 174 0 0
sp_fill_shopping_cart 34133 34133 0 0.00 1 31783 40 0 0
sp_fill_shopping_cart_clo_res 486662 486662 0 0.00 0 12327 14 0 0
sp_get_clo_for_invoice 5290 5290 0 0.00 0 41222 375 0 0
sp_kill_cost_storno 16794 16794 0 0.00 0 10645 46 0 0
sp_kill_qstorno_ret_qs2qd 1137513 1137513 0 0.00 0 52481 29 0 0
sp_kill_qty_storno 601024 601024 0 0.00 0 52492 62 0 0
sp_lock_selected_doc 22628 22628 0 0.00 0 8956 7 0 0
sp_make_cost_storno 30402 30402 0 0.00 0 19494 44 0 0
sp_multiply_rows_for_pdistr 30401 30401 0 0.00 0 5407 23 0 0
sp_multiply_rows_for_qdistr 22224 22224 0 0.00 0 88653 175 0 0
sp_pay_from_customer 7672 7672 0 0.00 4 19097 110 0 0
sp_pay_to_supplier 6373 6373 0 0.00 5 19514 117 0 0
sp_payment_common 14045 14045 0 0.00 1 19060 24 0 0
sp_qd_handle_on_cancel_clo 1923 1923 0 0.00 0 12091 257 0 0
sp_qd_handle_on_invoice_upd_sts 10969 10969 0 0.00 0 33781 264 0 0
sp_qd_handle_on_reserve_upd_sts 11067 11067 0 0.00 0 31058 67 0 0
sp_reserve_write_off 11067 11067 0 0.00 6 31075 173 0 0
sp_supplier_invoice 5964 5964 0 0.00 6 76329 3117 0 0
sp_supplier_order 5947 5947 0 0.00 6 37254 1164 0 0
srv_aggregate_perf_data 105 105 0 0.00 2886 160637 17247 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1838 225304 51639 0 0
srv_find_qd_qs_mism 1087756 1087756 0 0.00 0 11731 6 0 0
srv_make_invnt_saldo 650 650 0 0.00 44 47257 1023 0 0
srv_make_money_saldo 831 831 0 0.00 1 845 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 99245 409601 298852 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5294 5294 0 0.00 0 11592 142 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 2424 77 0 0
t$perf-norm:sp_cancel_client_order 1923 1923 0 0.00 0 7002 14 0 0
t$perf-norm:sp_cancel_customer_reserve 751 751 0 0.00 0 1869 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 4212 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 5591 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2756 22 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 20260 1187 0 0
t$perf-norm:sp_cancel_write_off 1071 1071 0 0.00 0 4112 13 0 0
t$perf-norm:sp_client_order 22224 22224 0 0.00 0 8296 14 0 0
t$perf-norm:sp_customer_reserve 1137 1137 0 0.00 0 855 4 0 0
t$perf-norm:sp_pay_from_customer 7672 7672 0 0.00 0 5934 7 0 0
t$perf-norm:sp_pay_to_supplier 6373 6373 0 0.00 0 4581 7 0 0
t$perf-norm:sp_reserve_write_off 11067 11067 0 0.00 0 15552 11 0 0
t$perf-norm:sp_supplier_invoice 5964 5964 0 0.00 0 10501 15 0 0
t$perf-norm:sp_supplier_order 5947 5947 0 0.00 0 4684 12 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 343 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 987 19 0 0
t$perf-norm:srv_make_invnt_saldo 650 650 0 0.00 0 490 10 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 627 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10844 10578 266 2.45 0 8945 23 0 266
v_all_customers 39932 39932 0 0.00 0 20 1 0 0
v_all_suppliers 32946 32946 0 0.00 0 27 1 0 0
v_all_wares 44448 44448 0 0.00 0 114 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 6875 19 0 0
v_cancel_client_order 3846 3846 0 0.00 0 4365 7 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 2958 8 0 0
v_cancel_customer_reserve 1502 1502 0 0.00 0 5922 14 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 2789 10 0 0
v_cancel_supplier_invoice 876 866 10 1.14 0 3112 19 0 10
v_cancel_supplier_order 860 860 0 0.00 0 978 6 0 0
v_cancel_write_off 2142 2142 0 0.00 0 7643 26 0 0
v_max_id_clo_ord 5946 5946 0 0.00 0 205 1 0 0
v_max_id_clo_res 1137 1137 0 0.00 0 1909 9 0 0
v_max_id_ord_sup 5963 5963 0 0.00 0 5503 4 0 0
v_max_non_paid_invoice 1809 1809 0 0.00 0 1274 3 0 0
v_max_non_paid_realizn 4391 4391 0 0.00 0 721 2 0 0
v_min_id_clo_ord 5946 5946 0 0.00 0 2604 5 0 0
v_min_id_clo_res 1137 1137 0 0.00 0 742 4 0 0
v_min_id_ord_sup 5963 5963 0 0.00 0 4878 6 0 0
v_min_non_paid_invoice 6373 6373 0 0.00 0 18804 14 0 0
v_min_non_paid_realizn 7672 7672 0 0.00 0 6959 13 0 0
v_our_firm 11078 11078 0 0.00 0 16 1 0 0
v_reserve_write_off 22134 22134 0 0.00 0 8142 9 0 0
x_lock_depdocs_on_canc_invoice 11536 11536 0 0.00 0 33796 106 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 14328 342 0 0
x_make_qstorno_1000_1200 5947 5947 0 0.00 2 37245 1094 0 0
x_make_qstorno_1200_2000 5964 5964 0 0.00 3 76247 2968 0 0
x_make_qstorno_2100_3300 486664 486664 0 0.00 0 79423 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 22:28:13 to 23-10-2020 22:28:17.

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
19:57:04 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:05 2478 8192000000 10348457584 10664837120 1409355376 14.678 100 91 182 0 1445658400 5846592 30456432
19:59:13 23382 8192000000 10427301744 10754940928 1480511312 15.306 100 90 180 0 1518003008 8218544 29273152
20:00:40 16504 8192000000 10478755392 10819121152 1527831232 15.718 100 86 172 0 1565484128 10370992 27281904
20:02:03 11799 8192000000 10555818992 10911649792 1611673120 16.439 100 79 157 2 1647717456 11664832 24379504
20:03:18 9932 8192000000 10630525552 11001008128 1687179936 17.078 100 74 148 2 1721422672 12516928 21725808
20:04:33 5379 8192000000 10693958064 11075301376 1762400976 17.704 100 68 136 0 1792911840 11874976 18635888
20:05:40 2635 8192000000 10749051728 11144970240 1818107872 18.162 100 79 160 2 1853219456 13216608 21894976
20:06:44 1391 8192000000 10807826752 11193253888 1861473376 18.515 100 90 180 1 1899265008 12096704 25694928
20:07:46 9877 8192000000 10801438352 11204374528 1866017440 18.552 100 55 109 5 1889768432 7431840 16319152
20:08:59 6375 8192000000 10783282496 11216990208 1880198976 18.667 100 26 53 3 1893754656 6473808 7081872
20:10:08 6648 8192000000 10802218512 11233857536 1891497728 18.758 100 26 52 5 1905194784 6325008 7372048
20:11:15 61847 8192000000 10808024960 11251212288 1896451888 18.798 100 22 43 1 1912114928 8963456 6699584
20:13:19 4748 8192000000 10829629104 11258019840 1911992800 18.923 100 43 86 1 1930888608 6331328 12564480
20:14:24 8906 8192000000 10837440480 11269132288 1914889616 18.946 100 23 46 1 1927889424 5855136 7144672
20:15:36 13682 8192000000 10796472384 11225714688 1853287296 18.449 97 19 38 1 1864967968 6029344 5651328
20:16:52 17236 8192000000 10758382592 11188359168 1774060320 17.801 94 36 72 3 1789688304 5766880 9861104
20:18:12 7695 8192000000 10718017792 11136159744 1761588944 17.698 98 21 42 0 1777064576 9324720 6150912
20:19:25 9038 8192000000 10678905968 11088556032 1708368832 17.255 96 14 27 0 1718256512 5297216 4590464
20:20:37 20843 8192000000 10742641408 11154608128 1802040288 18.031 99 37 74 0 1818665840 4961120 11664432
20:21:58 12075 8192000000 10731234848 11162480640 1806388480 18.066 99 9 18 0 1814239440 5349408 2501552
20:23:10 9362 8192000000 10760428736 11188432896 1843093984 18.366 100 21 42 0 1855028352 5810720 6123648
20:24:20 10389 8192000000 10782820048 11206467584 1862587152 18.524 100 35 70 2 1879139920 5513776 11038992
20:25:35 70429 8192000000 10785079968 11219918848 1878472160 18.653 100 47 94 4 1898300912 5704448 14124304
20:27:47 22516 8192000000 10850923136 11274104832 1921870304 19.002 100 49 98 5 1943788064 8293376 13624384
20:29:12 13541 8192000000 10888916016 11310338048 1945067184 19.187 100 60 117 5 1975052192 13230768 16754240
20:30:30 18110 8192000000 10885524240 11323437056 1955944192 19.274 100 39 78 1 1981584848 13979312 11661344
20:32:04 15073 8192000000 10903148640 11334029312 1957943712 19.290 100 61 121 1 1996558752 20150656 18464384
20:33:27 11483 8192000000 10920992848 11351252992 1975477696 19.429 100 33 66 2 2005376064 19761136 10137232
20:34:41 9281 8192000000 10947158464 11381116928 1992255568 19.562 100 60 117 3 2039902464 29575872 18071024
20:36:05 7735 8192000000 10916096016 11337957376 1941629088 19.160 99 62 123 1 1989498240 28790816 19078336
20:37:17 3150 8192000000 10924503568 11333230592 1948477504 19.214 100 71 142 3 2004316640 34591712 21247424
20:38:25 2330 8192000000 10890031872 11304726528 1907607680 18.887 99 73 145 2 1965927088 36506112 21813296
20:39:31 1838 8192000000 10916947408 11317686272 1914546304 18.943 99 82 164 2 1979623280 40126336 24950640
20:40:39 2989 8192000000 10918300912 11334332416 1936763120 19.121 100 60 117 2 1995521472 40736368 18021984
20:41:47 4087 8192000000 10933039280 11344867328 1942609056 19.168 100 70 139 1 2013667888 48477200 22581632
20:42:54 3379 8192000000 10906362112 11320741888 1926204832 19.037 100 61 122 1 1985442992 40050544 19187616
20:44:01 6768 8192000000 10916768080 11339104256 1935396208 19.110 100 41 82 0 1997472448 48011968 14064272
20:45:16 14938 8192000000 10922530336 11365748736 1949563952 19.223 100 30 60 3 2006310096 46395584 10350560
20:46:40 33360 8192000000 10905811760 11355926528 1938759680 19.137 100 27 54 1 2000634096 52150192 9724224
20:48:26 23230 8192000000 10905664608 11371843584 1931477536 19.079 100 24 48 3 2000556032 59668464 9410032
20:49:54 69448 8192000000 10885476816 11335544832 1940048352 19.147 100 17 34 0 1962966880 17168384 5750144
20:52:05 225304 8192000000 10868557984 11329933312 1878020880 18.649 96 22 44 0 1894270320 10804544 5444896
20:56:51 92754 8192000000 10899333472 11363725312 1960878288 19.313 100 14 28 0 1983940608 18950288 4112032
20:59:26 58123 8192000000 10932128224 11368960000 1964895920 19.345 99 41 82 0 1982779536 6514432 11369184
21:01:26 148243 8192000000 10921656880 11383308288 1954274800 19.261 98 16 32 0 1966324144 7608864 4440480
21:05:03 71537 8192000000 10932079824 11411529728 2009856576 19.700 100 22 44 0 2032627840 16108704 6662560
21:07:14 57624 8192000000 10927842016 11405164544 1993295456 19.570 99 29 59 0 2015918032 14609680 8012896
21:09:19 3208 8192000000 10936829424 11399118848 2000356816 19.626 100 41 80 2 2023459776 10911536 12191424
21:10:31 58945 8192000000 10907887552 11391098880 1992260192 19.562 100 11 22 0 2006067472 10622704 3184576
21:12:31 29507 8192000000 10897566928 11368697856 1975619280 19.430 100 16 32 3 1987790208 7703648 4467280
21:14:12 32470 8192000000 10938985776 11398508544 1998972416 19.615 100 42 79 2 2024680064 13525856 12181792
21:15:49 68313 8192000000 10907973808 11395878912 1952407152 19.246 98 14 28 0 1978963968 22279936 4276880
21:18:00 100342 8192000000 10895050432 11366518784 1861440144 18.515 94 9 18 0 1883171232 19489280 2241808
21:20:44 36920 8192000000 10908772080 11362250752 1966347792 19.356 100 25 49 0 1988561936 14259184 7954960
21:22:36 78215 8192000000 10902427856 11364728832 1912935552 18.930 97 20 40 2 1933962176 15696688 5329936
21:24:54 101160 8192000000 10861262144 11324309504 1911590880 18.919 98 20 40 1 1925168016 7521104 6056032
21:27:43 45088 8192000000 10825428320 11296837632 1874749984 18.623 97 33 66 1 1893278272 9665584 8862704
21:29:29 60928 8192000000 10830925984 11304488960 1878834480 18.656 98 9 18 1 1895410112 13759056 2816576
21:32:35 52563 8192000000 10830316256 11285250048 1880076656 18.666 97 24 49 1 1897120960 9921920 7122384
21:34:32 35342 8192000000 10850595808 11302039552 1854658480 18.460 96 13 26 1 1869181376 10756768 3766128
21:36:10 35990 8192000000 10842607936 11304808448 1869905568 18.584 97 27 52 4 1890673088 13605568 7161952
21:37:46 33338 8192000000 10854541504 11307716608 1913004144 18.931 100 26 52 0 1931527536 11008176 7515216
21:39:25 82811 8192000000 10859352992 11323138048 1893059904 18.770 98 15 30 1 1908223360 10958544 4204912
21:41:48 15957 8192000000 10880109472 11316084736 1894035280 18.778 97 64 128 0 1921600288 9602816 17962192
21:43:07 30407 8192000000 10889924288 11337195520 1940197776 19.148 100 37 74 1 1964346064 14069392 10078896
21:44:38 97766 8192000000 10899872992 11364986880 1958057264 19.291 100 22 44 1 1978839904 14692912 6089728
21:47:16 129208 8192000000 10899266224 11362652160 1956347152 19.277 99 35 70 2 1979666304 13081168 10237984
21:50:28 44621 8192000000 10875650096 11362725888 1964447648 19.341 100 8 16 0 1977113648 10647792 2018208
21:52:13 81573 8192000000 10875393008 11353128960 1960307984 19.308 99 11 22 0 1974039200 9324736 4406480
21:54:35 119617 8192000000 10893676288 11367583744 1915062992 18.947 97 14 28 1 1929976608 10884368 4029248
21:58:31 74944 8192000000 10886757744 11352821760 1899139040 18.819 96 15 30 1 1918610224 14772512 4698672
22:00:46 66480 8192000000 10908713728 11363057664 1934854880 19.106 98 23 46 1 1962350352 20983424 6512048
22:02:54 52816 8192000000 10898094656 11350892544 1913127760 18.932 97 19 38 1 1930060640 11516704 5416176
22:04:49 56652 8192000000 10887980592 11323400192 1859753856 18.501 95 35 70 2 1881184576 11911696 9519024
22:06:47 103094 8192000000 10875966240 11352408064 1857475184 18.483 95 10 20 1 1872992112 13095872 2421056
22:11:16 29872 8192000000 10877305440 11325222912 1859790208 18.502 95 39 77 1 1878239072 8181040 10267824
22:12:48 25089 8192000000 10858315648 11338498048 1942805872 19.169 100 6 12 0 1952768960 8403680 1559408
22:14:15 55620 8192000000 10822728512 11300167680 1884003872 18.697 98 10 20 0 1893807904 7015232 2788800
22:16:21 115669 8192000000 10864671120 11332071424 1904551424 18.863 98 15 30 2 1921504608 12683344 4269840
22:19:37 74542 8192000000 10882022448 11356004352 1916596416 18.960 98 11 22 0 1936459232 16541808 3321008
22:22:01 70941 8192000000 10872301072 11324047360 1846083152 18.390 95 22 44 1 1864579104 11028608 7467344
22:24:13 22152 8192000000 10850776192 11306876928 1844163728 18.375 95 31 62 3 1865935648 12258608 9513312
22:25:38 59282 8192000000 10876588640 11321118720 1858529648 18.491 96 31 62 0 1884923232 17676608 8716976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2020 22:28:19 to 23-10-2020 22:28:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 133 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2020 22:28:35 to 23-10-2020 22:28:37.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          254456
MON$OLDEST_ACTIVE               256473
MON$OLDEST_SNAPSHOT             256473
MON$NEXT_TRANSACTION            256473
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-03 16:41:55.3470
MON$PAGES                       2012736
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Fri Oct 23 22:27:09 2020
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Fri Oct 23 22:27:12 2020
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Fri Oct 23 22:27:12 2020
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

23.10.20 22:28:38. There are 3 logs in /var/tmp/logs-oltp30 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-23 22:28:40 No severe PSQL-related problems occured
23.10.20 22:28:41. End of report.