Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.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 17436904 kB
MemAvailable 31582704 kB
Buffers 0 kB
Cached 14216304 kB
SwapCached 5440 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-12 22:26:57
DTS_END                         2020-10-12 22:26:57.7920
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(56:17)
IP                              ::1/45930
TRN_ID                          249891
ATT_ID                          658
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: 9935.79 24224 1192295
customer order: creation 176.13 362 21136
customer order: refuse 14.49 307 1739
order to supplier: creation 46.07 1217 5528
order to supplier: removal 3.72 80034 446
invoice (draft): creation 46.92 3231 5630
invoice (draft): removal 60.63 615 7276
invoice accept: apply 41.61 14379 4993
invoice accept: cancel 55.08 672 6609
customer reserve: creation 3849.19 142 461903
customer reserve: removal 5326.81 44 639217
realization accept: apply 86.54 172 10385
realization accept: cancel 97.87 83 11744
payment from customer: creation 59.69 116 7163
payment from customer: removal 6.04 55 725
payment to supplier: creation 49.48 124 5937
payment to supplier: removal 3.12 36 374
service: total inventory turnovers 4.99 850 599
service: total monetary turnovers 6.42 17 770
service: aggregate perf. data 0.98 14990 117
service: refresh index statistics 0.03 367033 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-10-2020 22:28:06 to 12-10-2020 22:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1914
TEST_TIME 2 2797
TEST_TIME 3 3983
TEST_TIME 4 3740
TEST_TIME 5 3287
TEST_TIME 6 3877
TEST_TIME 7 4724
TEST_TIME 8 5510
TEST_TIME 9 5615
TEST_TIME 10 6255
TEST_TIME 11 6887
TEST_TIME 12 7168
TEST_TIME 13 7584
TEST_TIME 14 7775
TEST_TIME 15 8344
TEST_TIME 16 8830
TEST_TIME 17 9253
TEST_TIME 18 9685
TEST_TIME 19 10479
TEST_TIME 20 10640
TEST_TIME 21 10960
TEST_TIME 22 11558
TEST_TIME 23 11347
TEST_TIME 24 11068
TEST_TIME 25 11017
TEST_TIME 26 11005
TEST_TIME 27 11509
TEST_TIME 28 11678
TEST_TIME 29 11585
TEST_TIME 30 11432
TEST_TIME 31 11497
TEST_TIME 32 11330
TEST_TIME 33 11032
TEST_TIME 34 10809
TEST_TIME 35 10763
TEST_TIME 36 10678
TEST_TIME 37 10723
TEST_TIME 38 10694
TEST_TIME 39 10592
TEST_TIME 40 10494
TEST_TIME 41 10364
TEST_TIME 42 10194
TEST_TIME 43 10094
TEST_TIME 44 9995
TEST_TIME 45 10223
TEST_TIME 46 10131
TEST_TIME 47 10133
TEST_TIME 48 10147
TEST_TIME 49 10064
TEST_TIME 50 10173
TEST_TIME 51 10224
TEST_TIME 52 10339
TEST_TIME 53 10404
TEST_TIME 54 10326
TEST_TIME 55 10357
TEST_TIME 56 10503
TEST_TIME 57 10563
TEST_TIME 58 10450
TEST_TIME 59 10337
TEST_TIME 60 10227
TEST_TIME 61 10225
TEST_TIME 62 10133
TEST_TIME 63 10106
TEST_TIME 64 10007
TEST_TIME 65 10021
TEST_TIME 66 10063
TEST_TIME 67 9980
TEST_TIME 68 9998
TEST_TIME 69 10089
TEST_TIME 70 10125
TEST_TIME 71 10096
TEST_TIME 72 10152
TEST_TIME 73 10208
TEST_TIME 74 10144
TEST_TIME 75 10138
TEST_TIME 76 10236
TEST_TIME 77 10233
TEST_TIME 78 10279
TEST_TIME 79 10366
TEST_TIME 80 10267
TEST_TIME 81 10189
TEST_TIME 82 10122
TEST_TIME 83 10115
TEST_TIME 84 10114
TEST_TIME 85 10036
TEST_TIME 86 10037
TEST_TIME 87 10043
TEST_TIME 88 9954
TEST_TIME 89 9961
TEST_TIME 90 9955
TEST_TIME 91 9927
TEST_TIME 92 9858
TEST_TIME 93 9865
TEST_TIME 94 9912
TEST_TIME 95 9930
TEST_TIME 96 9946
TEST_TIME 97 9958
TEST_TIME 98 9990
TEST_TIME 99 9989
TEST_TIME 100 9968
TEST_TIME 101 10017
TEST_TIME 102 9998
TEST_TIME 103 10002
TEST_TIME 104 10087
TEST_TIME 105 10151
TEST_TIME 106 10160
TEST_TIME 107 10076
TEST_TIME 108 10071
TEST_TIME 109 10073
TEST_TIME 110 10019
TEST_TIME 111 9972
TEST_TIME 112 9949
TEST_TIME 113 9907
TEST_TIME 114 9932
TEST_TIME 115 9907
TEST_TIME 116 9919
TEST_TIME 117 9952
TEST_TIME 118 9932
TEST_TIME 119 9951
TEST_TIME 120 9939

Performance per minute, chart


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

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 1622356 1622356 0 0.00 0 37180 5 0 0
doc_list_biud 1622355 1622355 0 0.00 0 41842 22 0 0
sp_add_invoice_to_stock 5098 4993 105 2.05 39 136915 14380 0 105
sp_cancel_adding_invoice 6609 6609 0 0.00 4 74438 672 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 14524 307 0 0
sp_cancel_customer_reserve 639217 639217 0 0.00 1 22803 44 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 3 2337 56 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 1036 36 0 0
sp_cancel_supplier_invoice 7282 7276 6 0.08 2 41850 616 0 6
sp_cancel_supplier_order 446 446 0 0.00 5 776356 80035 0 0
sp_cancel_write_off 11744 11744 0 0.00 1 11078 84 0 0
sp_client_order 21136 21136 0 0.00 4 26145 363 0 0
sp_customer_reserve 461903 461903 0 0.00 0 80561 143 0 0
sp_fill_shopping_cart 32278 32278 0 0.00 1 15776 36 0 0
sp_fill_shopping_cart_clo_res 461898 461898 0 0.00 0 11903 11 0 0
sp_get_clo_for_invoice 4988 4988 0 0.00 0 28383 270 0 0
sp_kill_cost_storno 19452 19452 0 0.00 0 12819 43 0 0
sp_kill_qstorno_ret_qs2qd 1297900 1297900 0 0.00 0 41839 24 0 0
sp_kill_qty_storno 682404 682404 0 0.00 0 41840 52 0 0
sp_lock_selected_doc 21271 21271 0 0.00 0 9514 5 0 0
sp_make_cost_storno 28473 28473 0 0.00 0 37062 50 0 0
sp_multiply_rows_for_pdistr 28473 28473 0 0.00 0 17113 25 0 0
sp_multiply_rows_for_qdistr 21136 21136 0 0.00 0 12536 182 0 0
sp_pay_from_customer 7163 7163 0 0.00 4 20183 117 0 0
sp_pay_to_supplier 5937 5937 0 0.00 4 15324 124 0 0
sp_payment_common 13100 13100 0 0.00 1 14222 31 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 14502 274 0 0
sp_qd_handle_on_invoice_upd_sts 11597 11597 0 0.00 0 32677 270 0 0
sp_qd_handle_on_reserve_upd_sts 10385 10385 0 0.00 0 16648 57 0 0
sp_reserve_write_off 10385 10385 0 0.00 6 17358 172 0 0
sp_supplier_invoice 5630 5630 0 0.00 6 83971 3232 0 0
sp_supplier_order 5528 5528 0 0.00 5 31881 1218 0 0
srv_aggregate_perf_data 117 117 0 0.00 2405 151452 14990 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1815 163119 53146 0 0
srv_find_qd_qs_mism 1145291 1145291 0 0.00 0 13988 4 0 0
srv_make_invnt_saldo 599 599 0 0.00 44 24028 851 0 0
srv_make_money_saldo 770 770 0 0.00 0 2006 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 292477 537991 367034 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 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 4993 4993 0 0.00 0 17290 164 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 1790 55 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 5643 17 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1924 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 2538 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1627 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 5128 24 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 25773 1483 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 1543 5 0 0
t$perf-norm:sp_client_order 21136 21136 0 0.00 0 15136 18 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 9442 30 0 0
t$perf-norm:sp_pay_from_customer 7163 7163 0 0.00 0 11308 12 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 9169 10 0 0
t$perf-norm:sp_reserve_write_off 10385 10385 0 0.00 0 15764 16 0 0
t$perf-norm:sp_supplier_invoice 5630 5630 0 0.00 0 7463 26 0 0
t$perf-norm:sp_supplier_order 5528 5528 0 0.00 0 16643 17 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 4948 49 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 128 3 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 845 11 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 7251 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10186 9976 210 2.06 0 15762 19 0 210
v_all_customers 38588 38588 0 0.00 0 92 0 0 0
v_all_suppliers 31076 31076 0 0.00 0 15 1 0 0
v_all_wares 42272 42272 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1463 7 0 0
v_cancel_client_order 3478 3478 0 0.00 0 9078 8 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 2299 9 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 13826 16 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 102 2 0 0
v_cancel_supplier_invoice 834 822 12 1.43 0 1940 18 0 12
v_cancel_supplier_order 892 892 0 0.00 0 7028 11 0 0
v_cancel_write_off 2110 2110 0 0.00 0 3256 13 0 0
v_max_id_clo_ord 5527 5527 0 0.00 0 444 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 2428 17 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 6551 5 0 0
v_max_non_paid_invoice 1541 1541 0 0.00 0 3076 5 0 0
v_max_non_paid_realizn 3858 3858 0 0.00 0 1280 3 0 0
v_min_id_clo_ord 5527 5527 0 0.00 0 11087 11 0 0
v_min_id_clo_res 996 996 0 0.00 0 3648 14 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 6256 7 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 14218 21 0 0
v_min_non_paid_realizn 7163 7163 0 0.00 0 9920 18 0 0
v_our_firm 10294 10294 0 0.00 0 13 1 0 0
v_reserve_write_off 20770 20770 0 0.00 0 16358 11 0 0
x_lock_depdocs_on_canc_invoice 13474 13474 0 0.00 0 27111 90 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 4814 267 0 0
x_make_qstorno_1000_1200 5528 5528 0 0.00 1 31873 1151 0 0
x_make_qstorno_1200_2000 5630 5630 0 0.00 2 83963 3100 0 0
x_make_qstorno_2100_3300 461903 461903 0 0.00 0 80519 130 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2020 22:28:14 to 12-10-2020 22:28:18.

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:01 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:03 30353 8192000000 10376695472 10701045760 1424095600 14.809 100 85 170 0 1458151040 6042352 28013088
19:59:38 16500 8192000000 10435082992 10767798272 1491878736 15.405 100 89 178 1 1529106640 8649168 28578736
20:01:00 9005 8192000000 10506167120 10849280000 1562695088 16.019 100 86 172 1 1599406416 10322688 26388640
20:02:16 11397 8192000000 10569740896 10931781632 1632375872 16.615 100 75 150 0 1666106560 11145568 22585120
20:03:31 6475 8192000000 10643845856 11013521408 1701183488 17.195 100 78 156 1 1735448544 11901424 22363632
20:04:41 3382 8192000000 10719543712 11095793664 1777570480 17.829 100 90 180 1 1816183328 13897408 24715440
20:05:47 1579 8192000000 10773894240 11155787776 1827982736 18.243 100 91 182 2 1867059136 13806048 25270352
20:06:49 1112 8192000000 10809309328 11200192512 1865160448 18.545 100 88 178 0 1903647392 13454896 25032048
20:07:50 5228 8192000000 10789050128 11202633728 1869968880 18.584 100 40 80 2 1888746208 7986464 10790864
20:08:56 9001 8192000000 10793136256 11213611008 1876529392 18.637 100 21 42 0 1889390320 6267136 6593792
20:10:06 11868 8192000000 10788397040 11227230208 1885319040 18.708 100 21 42 1 1901999632 9863504 6817088
20:11:20 2672 8192000000 10817391696 11239317504 1895098464 18.787 100 64 127 1 1922421136 8380944 18941728
20:12:25 7035 8192000000 10811475440 11242835968 1896958608 18.802 100 33 66 3 1913057872 6269648 9829616
20:13:33 8392 8192000000 10823622416 11250147328 1901657408 18.840 100 25 50 2 1915016832 5790032 7569392
20:14:49 44327 8192000000 10815214080 11238965248 1875792912 18.631 99 23 46 1 1888275168 5384048 7098208
20:16:35 3667 8192000000 10779753360 11207417856 1868394656 18.571 100 26 52 2 1882374096 6911536 7067904
20:17:39 7872 8192000000 10724246944 11141529600 1762764640 17.707 97 25 49 0 1774410784 4721200 6924944
20:18:48 11601 8192000000 10708403824 11126984704 1771196464 17.777 98 20 40 2 1782225392 5528384 5500544
20:20:00 12436 8192000000 10703844560 11129458688 1753133968 17.628 97 18 36 2 1764056224 5966976 4955280
20:21:14 6639 8192000000 10757917856 11177529344 1824539600 18.215 99 26 51 1 1842429888 10226224 7664064
20:22:21 6944 8192000000 10766292320 11199184896 1857121120 18.480 100 10 20 0 1865856752 5392736 3342896
20:23:31 87114 8192000000 10780256032 11223334912 1880671248 18.671 100 26 52 1 1894306144 5448576 8186320
20:25:59 4850 8192000000 10812654272 11239788544 1891046880 18.754 100 27 54 0 1904747552 5394064 8306608
20:27:09 21566 8192000000 10827571472 11251568640 1899618096 18.823 99 42 83 1 1918594576 5635840 13340640
20:28:35 14854 8192000000 10892494544 11311923200 1949247088 19.220 100 45 90 1 1974612624 12672144 12693392
20:29:53 57873 8192000000 10895553200 11335811072 1968325328 19.372 100 24 47 2 1989515776 14049232 7141216
20:32:01 12784 8192000000 10922476624 11357851648 1966813664 19.360 99 43 83 4 1999035184 18998192 13223328
20:33:19 16643 8192000000 10927151904 11362177024 1966661744 19.359 99 52 102 1 2007574528 24799952 16112832
20:34:48 18927 8192000000 10910404016 11342110720 1935812368 19.113 98 41 80 0 1975083152 25947456 13323328
20:36:13 3622 8192000000 10934227344 11364872192 1970515248 19.390 99 66 128 1 2021837088 31877952 19443888
20:37:18 1812 8192000000 10957267808 11370708992 1955375248 19.269 98 83 164 4 2013019008 33612480 24031280
20:38:24 1927 8192000000 10933854128 11361726464 1977835392 19.448 100 65 129 4 2028725696 31070192 19820112
20:39:29 2904 8192000000 10941569712 11357569024 1951858480 19.241 98 76 150 0 2007602848 33609024 22135344
20:40:36 3119 8192000000 10947281520 11362770944 1956676672 19.280 99 77 153 3 2017510016 37838608 22994736
20:41:48 12014 8192000000 10886440352 11323863040 1908694528 18.896 99 48 97 0 1967570160 42786736 16088896
20:43:05 8479 8192000000 10882918384 11320733696 1925952496 19.035 100 40 81 2 1979915920 40826720 13136704
20:44:18 21210 8192000000 10873113312 11325272064 1925948608 19.034 100 22 44 1 1970124160 36821536 7354016
20:45:40 21810 8192000000 10869623968 11323817984 1912046992 18.923 99 23 46 3 1959188768 38874016 8267760
20:47:21 42017 8192000000 10838156464 11291795456 1849290832 18.416 97 16 31 2 1878134896 23140880 5703184
20:49:04 33571 8192000000 10828943936 11282595840 1875656336 18.630 98 41 84 3 1899814224 11403984 12753904
20:50:43 31861 8192000000 10878962480 11317948416 1905442400 18.870 98 57 115 3 1935317504 13345744 16529360
20:52:21 62006 8192000000 10900694384 11366879232 1929994208 19.067 100 11 22 2 1971848496 38295648 3558640
20:54:25 163119 8192000000 10872591680 11319386112 1804181680 18.048 93 7 14 0 1817617824 11557376 1878768
20:58:09 74321 8192000000 10871244816 11324887040 1911823040 18.921 98 11 21 0 1930063168 15585664 2654464
21:00:24 94288 8192000000 10884480688 11353083904 1964800688 19.344 100 23 46 0 1983110944 12093808 6216448
21:03:01 51468 8192000000 10892958640 11366637568 1945933056 19.194 98 30 59 0 1970209808 15168272 9108480
21:04:55 42511 8192000000 10903313216 11366260736 1963224416 19.332 99 12 23 1 1973897936 7081520 3592000
21:07:00 121117 8192000000 10922812128 11391524864 1977812960 19.447 99 11 22 0 1991574144 10529888 3231296
21:10:22 44531 8192000000 10927695648 11407851520 2002614496 19.643 100 40 80 1 2031805696 17753248 11437952
21:12:16 26295 8192000000 10912730832 11380441088 1954957376 19.266 98 11 22 0 1964350912 6265424 3128112
21:13:43 69468 8192000000 10924471936 11390775296 1944546064 19.183 97 22 44 3 1962618144 11801712 6270368
21:15:52 49255 8192000000 10926346640 11408551936 1981370112 19.476 99 18 36 1 2010181216 23332416 5478688
21:17:42 70586 8192000000 10895874928 11365801984 1914322832 18.941 96 22 44 1 1943081680 22619344 6139504
21:19:53 40843 8192000000 10911430224 11366100992 1923218800 19.013 97 29 59 2 1940723728 9066560 8438368
21:21:39 68414 8192000000 10885703872 11360284672 1882621456 18.686 96 21 42 1 1912041856 23424688 5995712
21:23:48 79460 8192000000 10870689856 11343912960 1880597648 18.670 96 20 40 1 1902651680 16324688 5729344
21:27:02 45878 8192000000 10858760576 11340066816 1923984096 19.019 98 9 18 1 1935465968 8869216 2612656
21:28:49 47479 8192000000 10862515712 11334479872 1904577376 18.863 97 10 20 1 1915442896 8405904 2459616
21:30:42 58303 8192000000 10845896688 11319443456 1899951968 18.826 98 8 16 0 1911599488 9390192 2257328
21:33:19 87906 8192000000 10863172224 11319619584 1856127856 18.472 96 21 41 2 1870874208 8695536 6050816
21:35:50 94933 8192000000 10847471136 11315798016 1832012784 18.276 95 16 32 1 1848990000 12873040 4104176
21:38:34 62427 8192000000 10866170384 11341983744 1919830048 18.985 99 19 37 1 1940941248 16117888 4993312
21:40:45 28963 8192000000 10895087232 11342159872 1916186960 18.956 98 52 106 2 1948511472 17185712 15138800
21:42:29 73279 8192000000 10879845824 11339223040 1930768480 19.073 99 22 44 1 1948281664 11829808 5683376
21:44:48 38341 8192000000 10899934800 11372781568 1953475536 19.254 99 30 60 1 1976414448 14043696 8895216
21:46:27 121845 8192000000 10880560832 11353976832 1926918896 19.042 98 26 52 1 1943418688 9051440 7448352
21:50:17 47874 8192000000 10886676976 11377033216 1968338144 19.372 100 13 26 1 1984388336 12499968 3550224
21:52:15 69550 8192000000 10873746704 11342602240 1902321072 18.845 96 14 28 0 1919140208 12634720 4184416
21:54:29 47385 8192000000 10890378096 11374317568 1973716096 19.415 100 12 24 2 1988460496 11076576 3667824
21:56:36 69142 8192000000 10894319408 11373170688 1963598000 19.335 99 20 39 1 1980252624 11863600 4791024
21:58:46 57930 8192000000 10922757520 11403382784 1994548192 19.580 100 14 28 1 2012224016 13916512 3759312
22:00:46 39488 8192000000 10917024624 11394072576 1998334752 19.610 100 17 34 1 2011096256 8247888 4513616
22:02:26 38771 8192000000 10925052240 11400400896 1983544320 19.493 99 29 58 1 2004234576 12878656 7811600
22:04:05 41307 8192000000 10877122912 11343900672 1888765392 18.736 96 18 36 1 1904958096 11254528 4938176
22:05:54 80267 8192000000 10890999200 11366367232 1932042688 19.083 98 29 58 1 1952208768 12128544 8037536
22:08:17 79003 8192000000 10899672704 11359977472 1918919792 18.978 97 30 60 0 1943992144 16710032 8362320
22:10:41 47257 8192000000 10854476400 11349938176 1886974656 18.721 97 6 12 0 1907121440 18371920 1774864
22:12:29 118564 8192000000 10867437984 11340939264 1887571616 18.726 97 23 46 0 1910928464 16723440 6633408
22:15:29 74136 8192000000 10859518592 11346153472 1922376080 19.006 99 20 39 1 1945330032 16672784 6281168
22:17:51 68504 8192000000 10839084352 11306803200 1836850704 18.315 94 17 35 1 1854789904 13005312 4933888
22:20:00 84570 8192000000 10851160496 11330727936 1878289904 18.651 97 16 32 1 1903385984 20451808 4644272
22:22:32 116245 8192000000 10861607360 11320791040 1860858928 18.510 96 11 23 2 1880257136 16288496 3109712
22:25:30 64708 8192000000 10862950000 11319386112 1898890400 18.817 98 20 40 1 1915963184 11205264 5867520

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 22:28:20 to 12-10-2020 22:28:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 105 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:01, from 12-10-2020 22:28:36 to 12-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          249175
MON$OLDEST_ACTIVE               250495
MON$OLDEST_SNAPSHOT             250495
MON$NEXT_TRANSACTION            250495
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                       2036064
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.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Mon Oct 12 22:26:58 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Oct 12 22:26:59 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:00 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:04 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:04 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:08 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:08 2020
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:08 2020
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Mon Oct 12 22:27:08 2020
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
--- end of diff output ---

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

12.10.20 22:28:39. 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-12 22:28:41 No severe PSQL-related problems occured
12.10.20 22:28:42. End of report.