Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2020 01: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 17369444 kB
MemAvailable 31594180 kB
Buffers 0 kB
Cached 14293964 kB
SwapCached 5572 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 01:26:55
DTS_END                         2020-10-12 01:26:55.7610
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/60216
TRN_ID                          247788
ATT_ID                          677
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: 9887.03 19969 1186443
customer order: creation 175.90 339 21108
customer order: refuse 15.01 278 1801
order to supplier: creation 47.03 1117 5644
order to supplier: removal 3.58 91131 430
invoice (draft): creation 46.90 2874 5628
invoice (draft): removal 57.14 732 6857
invoice accept: apply 42.88 15152 5146
invoice accept: cancel 52.23 608 6267
customer reserve: creation 4007.97 148 480956
customer reserve: removal 5124.41 49 614929
realization accept: apply 86.18 180 10342
realization accept: cancel 94.88 87 11385
payment from customer: creation 61.58 127 7389
payment from customer: removal 6.16 59 739
payment to supplier: creation 49.63 140 5955
payment to supplier: removal 3.64 58 437
service: total inventory turnovers 5.04 1066 605
service: total monetary turnovers 6.09 20 731
service: aggregate perf. data 0.75 24300 90
service: refresh index statistics 0.03 260905 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2813
TEST_TIME 2 6847
TEST_TIME 3 4885
TEST_TIME 4 4145
TEST_TIME 5 4759
TEST_TIME 6 4850
TEST_TIME 7 5664
TEST_TIME 8 5641
TEST_TIME 9 5580
TEST_TIME 10 5783
TEST_TIME 11 5967
TEST_TIME 12 6455
TEST_TIME 13 6532
TEST_TIME 14 7146
TEST_TIME 15 7412
TEST_TIME 16 7795
TEST_TIME 17 8322
TEST_TIME 18 8710
TEST_TIME 19 8768
TEST_TIME 20 9043
TEST_TIME 21 8971
TEST_TIME 22 9171
TEST_TIME 23 9691
TEST_TIME 24 11962
TEST_TIME 25 12059
TEST_TIME 26 11999
TEST_TIME 27 11846
TEST_TIME 28 11855
TEST_TIME 29 11557
TEST_TIME 30 11591
TEST_TIME 31 11444
TEST_TIME 32 11147
TEST_TIME 33 11180
TEST_TIME 34 11246
TEST_TIME 35 11187
TEST_TIME 36 11009
TEST_TIME 37 10900
TEST_TIME 38 10899
TEST_TIME 39 10961
TEST_TIME 40 10901
TEST_TIME 41 10811
TEST_TIME 42 10666
TEST_TIME 43 10496
TEST_TIME 44 10464
TEST_TIME 45 10394
TEST_TIME 46 10309
TEST_TIME 47 10274
TEST_TIME 48 10298
TEST_TIME 49 10243
TEST_TIME 50 10235
TEST_TIME 51 10190
TEST_TIME 52 10257
TEST_TIME 53 10274
TEST_TIME 54 10307
TEST_TIME 55 10357
TEST_TIME 56 10492
TEST_TIME 57 10497
TEST_TIME 58 10482
TEST_TIME 59 10579
TEST_TIME 60 10534
TEST_TIME 61 10423
TEST_TIME 62 10367
TEST_TIME 63 10297
TEST_TIME 64 10295
TEST_TIME 65 10214
TEST_TIME 66 10132
TEST_TIME 67 10014
TEST_TIME 68 9975
TEST_TIME 69 10022
TEST_TIME 70 9974
TEST_TIME 71 10014
TEST_TIME 72 9996
TEST_TIME 73 10089
TEST_TIME 74 10084
TEST_TIME 75 10084
TEST_TIME 76 10117
TEST_TIME 77 10079
TEST_TIME 78 10236
TEST_TIME 79 10200
TEST_TIME 80 10297
TEST_TIME 81 10330
TEST_TIME 82 10299
TEST_TIME 83 10206
TEST_TIME 84 10178
TEST_TIME 85 10101
TEST_TIME 86 10136
TEST_TIME 87 10094
TEST_TIME 88 10070
TEST_TIME 89 10059
TEST_TIME 90 10096
TEST_TIME 91 10063
TEST_TIME 92 10010
TEST_TIME 93 9989
TEST_TIME 94 10026
TEST_TIME 95 10034
TEST_TIME 96 10027
TEST_TIME 97 10017
TEST_TIME 98 10022
TEST_TIME 99 10066
TEST_TIME 100 10099
TEST_TIME 101 10107
TEST_TIME 102 10087
TEST_TIME 103 10126
TEST_TIME 104 10127
TEST_TIME 105 10181
TEST_TIME 106 10158
TEST_TIME 107 10150
TEST_TIME 108 10091
TEST_TIME 109 10049
TEST_TIME 110 10056
TEST_TIME 111 10008
TEST_TIME 112 9966
TEST_TIME 113 9952
TEST_TIME 114 9900
TEST_TIME 115 9924
TEST_TIME 116 9862
TEST_TIME 117 9861
TEST_TIME 118 9858
TEST_TIME 119 9874
TEST_TIME 120 9888

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-10-2020 01:28:08 to 12-10-2020 01: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 1632664 1632664 0 0.00 0 24981 6 0 0
doc_list_biud 1632663 1632663 0 0.00 0 57513 24 0 0
sp_add_invoice_to_stock 5251 5146 105 1.99 25 216827 15153 0 105
sp_cancel_adding_invoice 6267 6267 0 0.00 5 69796 609 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 18003 278 0 0
sp_cancel_customer_reserve 614929 614929 0 0.00 1 57516 50 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 4561 60 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 3019 59 0 0
sp_cancel_supplier_invoice 6863 6857 6 0.08 1 22025 732 0 6
sp_cancel_supplier_order 430 430 0 0.00 7 893666 91131 0 0
sp_cancel_write_off 11385 11385 0 0.00 1 14134 87 0 0
sp_client_order 21108 21108 0 0.00 4 23875 339 0 0
sp_customer_reserve 480956 480956 0 0.00 0 82818 149 0 0
sp_fill_shopping_cart 32371 32371 0 0.00 1 10867 35 0 0
sp_fill_shopping_cart_clo_res 480952 480952 0 0.00 0 30767 12 0 0
sp_get_clo_for_invoice 5142 5142 0 0.00 0 28152 315 0 0
sp_kill_cost_storno 18828 18828 0 0.00 0 14117 46 0 0
sp_kill_qstorno_ret_qs2qd 1248530 1248530 0 0.00 0 57482 27 0 0
sp_kill_qty_storno 657153 657153 0 0.00 0 57512 58 0 0
sp_lock_selected_doc 21375 21375 0 0.00 0 6102 4 0 0
sp_make_cost_storno 28828 28828 0 0.00 0 24881 58 0 0
sp_multiply_rows_for_pdistr 28828 28828 0 0.00 0 14994 29 0 0
sp_multiply_rows_for_qdistr 21108 21108 0 0.00 0 10242 151 0 0
sp_pay_from_customer 7389 7389 0 0.00 4 24992 128 0 0
sp_pay_to_supplier 5955 5955 0 0.00 4 24985 141 0 0
sp_payment_common 13344 13344 0 0.00 1 18890 43 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 17997 242 0 0
sp_qd_handle_on_invoice_upd_sts 11409 11409 0 0.00 0 18833 256 0 0
sp_qd_handle_on_reserve_upd_sts 10342 10342 0 0.00 0 13133 50 0 0
sp_reserve_write_off 10342 10342 0 0.00 6 24993 180 0 0
sp_supplier_invoice 5628 5628 0 0.00 6 93756 2875 0 0
sp_supplier_order 5644 5644 0 0.00 5 23682 1117 0 0
srv_aggregate_perf_data 90 90 0 0.00 2773 484489 24300 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1656 124435 51266 0 0
srv_find_qd_qs_mism 1137654 1137654 0 0.00 0 11357 4 0 0
srv_make_invnt_saldo 605 605 0 0.00 32 75091 1066 0 0
srv_make_money_saldo 731 731 0 0.00 0 1467 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 185393 368113 260905 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 5146 5146 0 0.00 0 16141 143 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 7543 114 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 5024 11 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 889 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 2437 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2992 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 947 15 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 26555 1363 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 1767 6 0 0
t$perf-norm:sp_client_order 21108 21108 0 0.00 0 9211 16 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 1882 8 0 0
t$perf-norm:sp_pay_from_customer 7389 7389 0 0.00 0 13022 13 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 11232 14 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 5225 9 0 0
t$perf-norm:sp_supplier_invoice 5628 5628 0 0.00 0 8357 16 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 5567 13 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 375 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3360 103 0 0
t$perf-norm:srv_make_invnt_saldo 605 605 0 0.00 0 1029 13 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 1443 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 5 0 0
v_add_invoice_to_stock 10494 10284 210 2.00 0 7807 17 0 210
v_all_customers 39186 39186 0 0.00 0 147 0 0 0
v_all_suppliers 31402 31402 0 0.00 0 18 1 0 0
v_all_wares 42216 42216 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 537 5 0 0
v_cancel_client_order 3602 3602 0 0.00 0 3255 6 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 4540 8 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 3263 9 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2834 10 0 0
v_cancel_supplier_invoice 784 772 12 1.53 0 4648 26 0 12
v_cancel_supplier_order 860 860 0 0.00 0 2616 11 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2707 12 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 276 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 3971 27 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 7554 6 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 3962 5 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 1362 3 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 6078 6 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 7088 12 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 7092 7 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 11889 29 0 0
v_min_non_paid_realizn 7389 7389 0 0.00 0 12687 35 0 0
v_our_firm 10356 10356 0 0.00 0 120 1 0 0
v_reserve_write_off 20684 20684 0 0.00 0 4716 7 0 0
x_lock_depdocs_on_canc_invoice 12738 12738 0 0.00 0 19425 97 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 8240 310 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 2 23672 1050 0 0
x_make_qstorno_1200_2000 5628 5628 0 0.00 3 89033 2751 0 0
x_make_qstorno_2100_3300 480956 480956 0 0.00 0 82816 135 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2020 01:28:13 to 12-10-2020 01: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
22:56:59 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:00 11043 8192000000 10348255392 10670903296 1415797984 14.735 100 84 168 0 1449428544 6391408 27239152
22:59:20 6983 8192000000 10428865216 10757513216 1484854928 15.344 100 90 180 0 1522616144 8775984 28985232
23:00:35 14143 8192000000 10491757744 10830520320 1540573184 15.829 100 87 174 0 1577415600 9610672 27231744
23:02:01 11354 8192000000 10543123776 10898915328 1608573280 16.413 100 79 158 0 1641925872 9972128 23380464
23:03:15 9541 8192000000 10627348912 10993291264 1679839248 17.016 100 79 158 0 1719860784 17543184 22478352
23:04:27 5582 8192000000 10690001600 11068354560 1753891888 17.634 100 76 152 1 1788704400 13487488 21325024
23:05:34 1772 8192000000 10744278512 11129856000 1806335376 18.066 100 87 175 1 1843172096 13050208 23786512
23:06:37 2382 8192000000 10789709600 11178459136 1848466192 18.410 100 89 177 1 1887337728 13725200 25146336
23:07:41 6499 8192000000 10772642688 11189870592 1858347584 18.490 100 36 73 1 1876682752 8173968 10161200
23:08:48 39928 8192000000 10808248688 11216764928 1874686688 18.622 100 60 118 3 1897997056 5048000 18262368
23:10:28 4225 8192000000 10810504960 11231760384 1881070864 18.674 100 26 53 1 1895390144 6755344 7563936
23:11:36 7497 8192000000 10805200048 11239067648 1891872944 18.761 100 18 36 1 1902413792 5538720 5002128
23:12:45 53474 8192000000 10813196464 11247894528 1903731200 18.856 100 27 54 0 1916403232 5425456 7246576
23:14:40 6000 8192000000 10821392288 11257999360 1912015984 18.923 100 29 58 1 1926282448 5917632 8348832
23:15:48 8835 8192000000 10793346912 11227176960 1868837504 18.575 99 15 28 2 1878713648 4803632 5072512
23:16:59 8580 8192000000 10731423328 11152068608 1750047456 17.602 95 27 54 1 1763445712 5289760 8108496
23:18:15 27843 8192000000 10691237568 11118211072 1711113280 17.278 94 22 44 1 1722857008 5369200 6374528
23:19:45 7523 8192000000 10700175680 11113852928 1732745760 17.458 96 23 46 1 1745234304 5461568 7026976
23:20:54 8891 8192000000 10743229328 11155714048 1806238832 18.065 99 36 72 1 1822719696 5749680 10731184
23:22:04 15777 8192000000 10774943264 11194449920 1854205360 18.456 100 30 61 3 1869047136 5470832 9370944
23:23:24 13193 8192000000 10782709248 11205029888 1862781520 18.526 100 36 73 2 1877719872 4849936 10088416
23:24:37 7482 8192000000 10787513648 11218956288 1872858224 18.607 100 21 42 3 1885118944 5988736 6271984
23:25:46 7121 8192000000 10781314288 11224952832 1879796912 18.663 100 18 36 2 1890517296 5449936 5270448
23:26:55 13542 8192000000 10819055072 11238584320 1890138176 18.747 100 28 57 0 1903288928 4799072 8351680
23:28:16 38872 8192000000 10844800400 11275149312 1913589904 18.935 99 62 123 1 1941002640 9598528 17814208
23:30:07 53920 8192000000 10875850512 11316572160 1949127120 19.220 100 34 68 2 1967274592 8784368 9363104
23:32:06 10660 8192000000 10921771824 11347124224 1977264224 19.443 100 68 134 2 2011583280 14716192 19602864
23:33:22 11224 8192000000 10925177872 11360145408 1981982912 19.480 100 42 83 0 2012984464 18710880 12290672
23:34:38 12707 8192000000 10930837600 11362947072 1988623424 19.533 100 51 101 3 2019214784 15466400 15124960
23:35:56 4058 8192000000 10948708560 11378577408 2001137040 19.632 100 58 116 1 2040373504 22915680 16320784
23:37:06 3198 8192000000 10974852352 11395891200 2008034608 19.686 100 74 148 2 2060008880 29593744 22380528
23:38:12 6832 8192000000 10970482912 11395375104 2002615072 19.643 100 62 124 2 2061302096 39150048 19536976
23:39:22 3149 8192000000 10971855184 11396517888 1994765968 19.581 100 63 126 0 2058803664 44070528 19967168
23:40:31 2630 8192000000 10957290848 11379773440 1957939536 19.290 99 59 117 1 2028070864 51735792 18395536
23:41:35 1656 8192000000 10964581040 11386294272 1972304912 19.404 99 77 151 3 2049023152 52729568 23988672
23:42:40 6007 8192000000 10930539344 11360493568 1928546480 19.055 98 61 122 0 2002232960 53795072 19891408
23:43:54 4614 8192000000 10918683584 11347054592 1911062912 18.915 98 45 90 1 1979119968 53659776 14397280
23:45:07 11938 8192000000 10917347824 11360018432 1940313408 19.149 100 44 88 0 2008709056 54257840 14137808
23:46:28 26077 8192000000 10929184704 11378606080 1959563536 19.303 100 32 64 2 2026117936 56238736 10315664
23:47:57 24913 8192000000 10923027840 11374116864 1936005120 19.115 100 33 66 1 2017151520 68749776 12396624
23:49:47 34484 8192000000 10902576048 11365236736 1915568672 18.951 99 26 52 0 1997021616 71812736 9640208
23:51:27 45695 8192000000 10851773904 11314868224 1910003504 18.907 99 12 24 2 1926973440 13504240 3465696
23:53:28 62842 8192000000 10884340704 11340926976 1939281152 19.141 99 16 28 2 1954070176 10650272 4138752
23:55:40 73004 8192000000 10897305216 11351748608 1955993088 19.274 100 35 70 3 1978158384 11937920 10227376
23:58:00 97260 8192000000 10908413152 11363475456 1938966848 19.139 98 38 76 1 1957498272 7706736 10824688
00:01:15 113708 8192000000 10895237232 11358969856 1963455856 19.334 100 15 30 1 1986872000 19188528 4227616
00:04:16 40069 8192000000 10927171664 11380801536 1982520160 19.485 100 44 87 1 2008076304 12002752 13553392
00:06:05 70645 8192000000 10899756896 11381678080 1976654496 19.438 100 8 16 0 1989979040 11074064 2250480
00:09:16 77379 8192000000 10910766928 11392086016 1986811488 19.519 100 16 31 1 2000947248 9685184 4450576
00:11:43 52635 8192000000 10925740032 11405828096 1994704032 19.581 100 13 27 0 2013693072 15219280 3769760
00:13:51 73283 8192000000 10917303632 11395108864 1956655840 19.279 98 3 6 0 1970906000 13585296 664864
00:16:09 43526 8192000000 10901000496 11378356224 1937417488 19.126 97 16 32 1 1951062048 8585536 5059024
00:17:53 57715 8192000000 10936023232 11420241920 1984755520 19.502 99 20 39 2 2009357760 18440944 6161296
00:19:51 51737 8192000000 10928408672 11399049216 1975381712 19.428 99 17 36 0 2000293216 19784688 5126816
00:21:50 63265 8192000000 10916077280 11383185408 1930134896 19.068 97 25 50 0 1952465264 15655168 6675200
00:23:58 72435 8192000000 10872962848 11314749440 1823472416 18.206 93 17 34 1 1840337232 12342592 4522224
00:26:11 68468 8192000000 10835270928 11315208192 1857603584 18.484 97 4 8 0 1879667424 20920704 1143136
00:28:29 32425 8192000000 10843528080 11298246656 1870711104 18.590 97 30 60 3 1886833504 8145424 7976976
00:30:03 33928 8192000000 10802823056 11269996544 1820415840 18.181 95 30 60 2 1837860144 9156400 8287904
00:31:47 50765 8192000000 10804999664 11272781824 1854199696 18.456 98 10 20 0 1863337600 6452080 2685824
00:33:49 73872 8192000000 10834840784 11310104576 1890833056 18.752 99 11 23 0 1905855680 12072960 2949664
00:36:03 42970 8192000000 10844767568 11302330368 1866511920 18.556 97 19 37 0 1879428928 7412224 5504784
00:37:51 49195 8192000000 10827842464 11299672064 1840245744 18.343 96 25 50 1 1857131328 9642816 7242768
00:39:41 50778 8192000000 10848645296 11305910272 1890261808 18.748 99 23 46 1 1907445488 10471312 6712368
00:41:36 44618 8192000000 10848732384 11318833152 1919350112 18.982 100 23 46 1 1938318560 11762400 7206048
00:43:25 36737 8192000000 10869792736 11320020992 1891133392 18.755 98 36 72 0 1917510352 16032544 10344416
00:45:08 63598 8192000000 10889421376 11354456064 1940858432 19.154 100 16 32 1 1962658592 17257296 4542864
00:47:16 92991 8192000000 10885555648 11366301696 1938492848 19.135 99 10 20 0 1954941648 13835312 2613488
00:50:06 70989 8192000000 10905325664 11368677376 1970491360 19.389 100 38 77 1 1990971968 9591104 10889504
00:52:18 83874 8192000000 10905077072 11393376256 1975190864 19.427 100 17 34 2 1994549280 14669536 4688880
00:54:42 69703 8192000000 10910859952 11403849728 1982184560 19.482 100 17 34 3 2008837312 21619568 5033184
00:57:29 88745 8192000000 10930353696 11409231872 1999500880 19.619 100 17 34 0 2017935408 14274448 4160080
00:59:58 90123 8192000000 10931413136 11404058624 1979345344 19.460 99 12 24 0 1996968272 14242480 3380448
01:02:47 75652 8192000000 10899926048 11364769792 1845089584 18.382 92 23 46 1 1862164288 11047648 6027056
01:05:09 92123 8192000000 10868097040 11354570752 1883382832 18.692 96 20 40 2 1902394464 13279040 5732592
01:07:42 73291 8192000000 10913798608 11370876928 1868337328 18.571 94 28 56 1 1885443136 9811568 7294240
01:10:02 99014 8192000000 10895331248 11368542208 1884596496 18.702 96 11 22 0 1903487632 16281856 2609280
01:12:45 124435 8192000000 10871939456 11354927104 1894017648 18.778 97 7 14 0 1919601104 23763104 1820352
01:15:53 39921 8192000000 10867299408 11333152768 1901925312 18.842 98 32 63 1 1928401104 17133056 9342736
01:17:37 65644 8192000000 10853089456 11330351104 1883650000 18.695 97 19 38 1 1901765488 13216736 4898752
01:19:43 71589 8192000000 10847700752 11317424128 1870085264 18.585 97 14 28 1 1886545584 12734224 3726096
01:21:55 63418 8192000000 10822680128 11289112576 1843071488 18.366 96 17 33 3 1862024608 14415184 4537936
01:24:03 74643 8192000000 10832007744 11289640960 1842974768 18.365 96 12 24 1 1859182336 12991808 3215760
01:26:24 37962 8192000000 9388536112 9680711680 480405056 5.539 26 5 10 0 485704912 3594320 1705536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-10-2020 01:28:19 to 12-10-2020 01:28:33.

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:02, from 12-10-2020 01:28:34 to 12-10-2020 01:28:36.

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          248149
MON$OLDEST_ACTIVE               248150
MON$OLDEST_SNAPSHOT             248150
MON$NEXT_TRANSACTION            248150
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                       2042592
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.33372 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33372 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33372 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33372 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Mon Oct 12 01:27:04 2020
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Mon Oct 12 01:27:07 2020
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Mon Oct 12 01:27:08 2020
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Mon Oct 12 01:27:09 2020
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Mon Oct 12 01:27:09 2020
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
--- end of diff output ---

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

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