Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2020 22:29
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 17747052 kB
MemAvailable 31600628 kB
Buffers 0 kB
Cached 13999692 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-24 22:27:01
DTS_END                         2020-10-24 22:27:01.5010
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/42088
TRN_ID                          246722
ATT_ID                          707
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.26 19959 1138591
customer order: creation 176.34 327 21161
customer order: refuse 14.61 255 1753
order to supplier: creation 47.27 1048 5672
order to supplier: removal 3.81 70137 457
invoice (draft): creation 46.48 2646 5578
invoice (draft): removal 55.41 650 6649
invoice accept: apply 41.85 14973 5022
invoice accept: cancel 49.89 566 5987
customer reserve: creation 3878.81 148 465457
customer reserve: removal 4874.66 42 584959
realization accept: apply 84.94 154 10193
realization accept: cancel 80.86 98 9703
payment from customer: creation 60.55 115 7266
payment from customer: removal 5.90 66 708
payment to supplier: creation 50.76 119 6091
payment to supplier: removal 3.74 41 449
service: total inventory turnovers 4.97 951 596
service: total monetary turnovers 6.51 21 781
service: aggregate perf. data 0.88 19932 105
service: refresh index statistics 0.03 286897 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 22:29:35 to 24-10-2020 22:29:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9598
TEST_TIME 2 8290
TEST_TIME 3 7415
TEST_TIME 4 6234
TEST_TIME 5 6161
TEST_TIME 6 5711
TEST_TIME 7 6311
TEST_TIME 8 7297
TEST_TIME 9 7288
TEST_TIME 10 7575
TEST_TIME 11 8958
TEST_TIME 12 9668
TEST_TIME 13 9665
TEST_TIME 14 10174
TEST_TIME 15 10191
TEST_TIME 16 10568
TEST_TIME 17 10642
TEST_TIME 18 10777
TEST_TIME 19 11662
TEST_TIME 20 11962
TEST_TIME 21 11690
TEST_TIME 22 11504
TEST_TIME 23 11102
TEST_TIME 24 10727
TEST_TIME 25 11064
TEST_TIME 26 10835
TEST_TIME 27 10645
TEST_TIME 28 10557
TEST_TIME 29 10362
TEST_TIME 30 10342
TEST_TIME 31 10113
TEST_TIME 32 10330
TEST_TIME 33 10125
TEST_TIME 34 10004
TEST_TIME 35 10155
TEST_TIME 36 10024
TEST_TIME 37 10051
TEST_TIME 38 10182
TEST_TIME 39 10193
TEST_TIME 40 10003
TEST_TIME 41 10037
TEST_TIME 42 10097
TEST_TIME 43 10077
TEST_TIME 44 10093
TEST_TIME 45 10202
TEST_TIME 46 10185
TEST_TIME 47 10192
TEST_TIME 48 10082
TEST_TIME 49 10010
TEST_TIME 50 10062
TEST_TIME 51 10010
TEST_TIME 52 9922
TEST_TIME 53 9883
TEST_TIME 54 9944
TEST_TIME 55 9884
TEST_TIME 56 9804
TEST_TIME 57 9815
TEST_TIME 58 9761
TEST_TIME 59 9690
TEST_TIME 60 9655
TEST_TIME 61 9600
TEST_TIME 62 9632
TEST_TIME 63 9743
TEST_TIME 64 9701
TEST_TIME 65 9791
TEST_TIME 66 9786
TEST_TIME 67 9777
TEST_TIME 68 9882
TEST_TIME 69 9804
TEST_TIME 70 9954
TEST_TIME 71 9933
TEST_TIME 72 9906
TEST_TIME 73 9899
TEST_TIME 74 9837
TEST_TIME 75 9743
TEST_TIME 76 9736
TEST_TIME 77 9709
TEST_TIME 78 9652
TEST_TIME 79 9568
TEST_TIME 80 9591
TEST_TIME 81 9634
TEST_TIME 82 9641
TEST_TIME 83 9598
TEST_TIME 84 9603
TEST_TIME 85 9633
TEST_TIME 86 9591
TEST_TIME 87 9600
TEST_TIME 88 9661
TEST_TIME 89 9703
TEST_TIME 90 9761
TEST_TIME 91 9732
TEST_TIME 92 9804
TEST_TIME 93 9784
TEST_TIME 94 9784
TEST_TIME 95 9837
TEST_TIME 96 9784
TEST_TIME 97 9695
TEST_TIME 98 9683
TEST_TIME 99 9684
TEST_TIME 100 9611
TEST_TIME 101 9621
TEST_TIME 102 9574
TEST_TIME 103 9526
TEST_TIME 104 9573
TEST_TIME 105 9550
TEST_TIME 106 9562
TEST_TIME 107 9610
TEST_TIME 108 9589
TEST_TIME 109 9561
TEST_TIME 110 9539
TEST_TIME 111 9572
TEST_TIME 112 9697
TEST_TIME 113 9670
TEST_TIME 114 9676
TEST_TIME 115 9725
TEST_TIME 116 9682
TEST_TIME 117 9626
TEST_TIME 118 9593
TEST_TIME 119 9573
TEST_TIME 120 9493

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2020 22:29:39 to 24-10-2020 22:29:42.

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 1569711 1569711 0 0.00 0 44299 5 0 0
doc_list_biud 1569711 1569711 0 0.00 0 45417 20 0 0
sp_add_invoice_to_stock 5118 5022 96 1.87 45 213593 14974 0 96
sp_cancel_adding_invoice 5987 5987 0 0.00 3 38967 566 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 8853 255 0 0
sp_cancel_customer_reserve 584959 584959 0 0.00 1 45419 42 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 6232 66 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 1562 41 0 0
sp_cancel_supplier_invoice 6660 6649 11 0.16 1 20378 651 0 11
sp_cancel_supplier_order 457 457 0 0.00 5 732009 70138 0 0
sp_cancel_write_off 9703 9703 0 0.00 1 16906 99 0 0
sp_client_order 21161 21161 0 0.00 3 22091 327 0 0
sp_customer_reserve 465457 465457 0 0.00 0 55734 149 0 0
sp_fill_shopping_cart 32396 32396 0 0.00 1 16442 34 0 0
sp_fill_shopping_cart_clo_res 465447 465447 0 0.00 0 32950 14 0 0
sp_get_clo_for_invoice 5012 5012 0 0.00 0 31228 301 0 0
sp_kill_cost_storno 16847 16847 0 0.00 0 16776 42 0 0
sp_kill_qstorno_ret_qs2qd 1186727 1186727 0 0.00 0 45391 23 0 0
sp_kill_qty_storno 624713 624713 0 0.00 0 45417 50 0 0
sp_lock_selected_doc 21121 21121 0 0.00 0 7901 6 0 0
sp_make_cost_storno 28562 28562 0 0.00 0 44291 43 0 0
sp_multiply_rows_for_pdistr 28562 28562 0 0.00 0 19190 23 0 0
sp_multiply_rows_for_qdistr 21161 21161 0 0.00 0 10896 151 0 0
sp_pay_from_customer 7266 7266 0 0.00 4 16013 115 0 0
sp_pay_to_supplier 6091 6091 0 0.00 5 44564 119 0 0
sp_payment_common 13357 13357 0 0.00 1 20067 32 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 7871 220 0 0
sp_qd_handle_on_invoice_upd_sts 10999 10999 0 0.00 0 21398 230 0 0
sp_qd_handle_on_reserve_upd_sts 10193 10193 0 0.00 0 14014 50 0 0
sp_reserve_write_off 10193 10193 0 0.00 6 22270 154 0 0
sp_supplier_invoice 5578 5578 0 0.00 7 72604 2647 0 0
sp_supplier_order 5672 5672 0 0.00 5 20073 1048 0 0
srv_aggregate_perf_data 105 105 0 0.00 2902 304917 19933 0 0
srv_fill_mon_cache_memory 55 55 0 0.00 1924 247453 60210 0 0
srv_find_qd_qs_mism 1089963 1089963 0 0.00 0 14123 4 0 0
srv_make_invnt_saldo 596 596 0 0.00 36 115223 952 0 0
srv_make_money_saldo 781 781 0 0.00 1 6051 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 151773 419520 286897 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5022 5022 0 0.00 0 16761 155 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 9486 92 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 3496 8 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 5944 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 1749 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 4205 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 924 11 0 0
t$perf-norm:sp_cancel_supplier_order 457 457 0 0.00 0 18190 1188 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 6454 22 0 0
t$perf-norm:sp_client_order 21161 21161 0 0.00 0 7875 15 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 4482 14 0 0
t$perf-norm:sp_pay_from_customer 7266 7266 0 0.00 0 8532 17 0 0
t$perf-norm:sp_pay_to_supplier 6091 6091 0 0.00 0 8517 21 0 0
t$perf-norm:sp_reserve_write_off 10193 10193 0 0.00 0 9013 13 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 7849 22 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 6680 12 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 339 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 55 55 0 0.00 0 4062 95 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2733 15 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 735 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10214 10022 192 1.87 0 9341 19 0 192
v_all_customers 38504 38504 0 0.00 0 13 0 0 0
v_all_suppliers 31350 31350 0 0.00 0 39 0 0 0
v_all_wares 42322 42322 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 4023 12 0 0
v_cancel_client_order 3506 3506 0 0.00 0 5912 8 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 3336 8 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 6147 8 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 1551 5 0 0
v_cancel_supplier_invoice 872 850 22 2.52 0 4974 20 0 22
v_cancel_supplier_order 914 914 0 0.00 0 942 4 0 0
v_cancel_write_off 2014 2014 0 0.00 0 1657 10 0 0
v_max_id_clo_ord 5668 5668 0 0.00 0 676 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 4571 19 0 0
v_max_id_ord_sup 5567 5567 0 0.00 0 5648 3 0 0
v_max_non_paid_invoice 1651 1651 0 0.00 0 532 2 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 1955 3 0 0
v_min_id_clo_ord 5668 5668 0 0.00 0 9921 8 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 9409 21 0 0
v_min_id_ord_sup 5567 5567 0 0.00 0 3266 4 0 0
v_min_non_paid_invoice 6091 6091 0 0.00 0 17592 23 0 0
v_min_non_paid_realizn 7266 7266 0 0.00 0 15796 19 0 0
v_our_firm 10694 10694 0 0.00 0 32 0 0 0
v_reserve_write_off 20386 20386 0 0.00 0 9723 10 0 0
x_lock_depdocs_on_canc_invoice 12211 12211 0 0.00 0 17415 96 0 0
x_lock_depdocs_on_canc_sup_ord 457 457 0 0.00 0 5358 238 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 1 20023 981 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 3 71618 2528 0 0
x_make_qstorno_2100_3300 465457 465457 0 0.00 0 55731 133 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 22:29:43 to 24-10-2020 22:29:47.

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:05 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:05 30172 8192000000 10345874704 10664869888 1392055968 14.524 100 82 164 0 1425570304 5562752 27951584
19:59:40 18007 8192000000 10419904128 10749255680 1475386176 15.261 100 85 171 1 1510834704 7911744 27536784
20:01:03 23613 8192000000 10485217840 10828402688 1543683248 15.855 100 89 178 0 1581094224 9182896 28228080
20:02:30 7555 8192000000 10564034432 10921357312 1619972736 16.510 100 72 144 0 1650672272 9516896 21182640
20:03:40 7843 8192000000 10637056304 11008786432 1698850144 17.175 100 72 143 0 1730630704 11237504 20543056
20:04:50 5128 8192000000 10712994336 11092602880 1770956032 17.775 100 81 162 3 1806401696 13192800 22252864
20:05:57 2291 8192000000 10768616112 11155300352 1824278352 18.213 100 86 172 1 1862448464 13702608 24467504
20:07:01 1392 8192000000 10810369248 11204640768 1865046848 18.544 100 90 178 0 1903215984 13838592 24330544
20:08:05 3429 8192000000 10805710672 11209850880 1868788592 18.574 100 54 104 3 1892931136 9320656 14821888
20:09:09 5568 8192000000 10795410816 11217616896 1882981888 18.689 100 26 52 0 1896228816 6068000 7178928
20:10:15 58040 8192000000 10796582208 11232956416 1892422496 18.765 100 34 66 0 1907617200 5483888 9710816
20:12:16 7440 8192000000 10813541872 11247218688 1898620224 18.815 100 25 50 2 1912548848 6853536 7075088
20:13:24 11180 8192000000 10801332288 11244036096 1887499088 18.726 99 16 32 1 1897492368 5335120 4658160
20:14:36 10050 8192000000 10813028416 11250872320 1892203376 18.764 99 17 34 1 1902719920 5974912 4541632
20:15:46 36412 8192000000 10812019040 11248152576 1857434640 18.482 97 29 58 2 1871069792 5940672 7694480
20:17:27 6578 8192000000 10794815328 11218542592 1860331328 18.506 99 29 59 3 1877844560 9598976 7914256
20:18:35 9373 8192000000 10731170192 11142569984 1772829792 17.790 98 25 50 1 1790127408 10387920 6909696
20:19:47 61795 8192000000 10694045696 11107069952 1686963936 17.076 93 35 70 4 1702130112 4687760 10478416
20:21:49 9249 8192000000 10727836752 11152289792 1800796128 18.020 99 18 36 0 1811954672 5243024 5915520
20:23:01 14780 8192000000 10752227824 11178729472 1826240320 18.229 99 22 43 2 1838161328 5286816 6634192
20:24:19 8077 8192000000 10784775584 11209756672 1861246784 18.513 100 19 38 3 1875685536 9457056 4981696
20:25:27 31020 8192000000 10779972416 11209502720 1867881728 18.567 100 26 51 1 1880269536 5426560 6961248
20:27:01 11272 8192000000 10816098944 11238506496 1892129472 18.763 100 32 64 1 1907054912 5184816 9740624
20:28:19 13673 8192000000 10862971408 11287134208 1930425664 19.070 100 52 104 2 1954865040 10062208 14377168
20:29:37 40340 8192000000 10873797472 11318210560 1936452672 19.118 99 30 60 2 1957687136 12665440 8569024
20:31:20 82263 8192000000 10908019040 11353591808 1975226896 19.427 100 35 70 0 2001642016 16039504 10375616
20:33:50 36445 8192000000 10933371184 11374354432 1986650400 19.517 100 41 81 2 2022850224 22835056 13364768
20:35:35 9007 8192000000 10955124784 11387666432 2001387760 19.634 100 65 129 1 2043580464 23369216 18823488
20:36:48 16154 8192000000 10969941104 11401039872 2009456656 19.697 100 67 134 1 2061177264 31085440 20635168
20:38:11 1922 8192000000 10986091280 11404021760 1988014784 19.528 99 84 168 2 2056181824 41824592 26342448
20:39:17 7497 8192000000 10929466736 11366248448 1944273808 19.181 98 42 84 2 1988128144 30104304 13750032
20:40:28 18545 8192000000 10906144544 11353001984 1915397536 18.950 97 34 69 1 1953928544 27865360 10665648
20:41:52 15620 8192000000 10887202064 11342450688 1919487296 18.983 98 26 52 1 1956884800 29370160 8027344
20:43:13 21184 8192000000 10869525504 11321438208 1904692912 18.864 99 29 55 2 1944977312 31131216 9153184
20:44:48 18229 8192000000 10849860192 11289935872 1847296512 18.400 96 31 62 1 1889051360 31303072 10451776
20:46:08 16068 8192000000 10838795872 11299033088 1881175568 18.675 99 19 38 1 1909540112 22388496 5976048
20:47:24 24343 8192000000 10828023520 11278000128 1881488704 18.677 99 13 26 2 1896151920 10620432 4042784
20:49:40 90897 8192000000 10830430048 11277754368 1864692752 18.541 98 18 36 0 1884952400 15227760 5031888
20:52:53 97044 8192000000 10828666320 11281719296 1822103888 18.195 95 21 41 0 1840712976 11637024 6972064
20:55:35 70028 8192000000 10855241920 11300937728 1898866928 18.817 99 15 29 0 1916185072 12679984 4638160
20:57:46 47593 8192000000 10877853664 11310137344 1882206768 18.683 97 24 47 3 1904911824 16087856 6617200
20:59:34 36577 8192000000 10878176288 11334524928 1944936896 19.186 100 19 38 0 1969536272 18686144 5913232
21:01:12 65987 8192000000 10892138672 11356667904 1951314832 19.237 100 11 22 0 1972822832 18616464 2891536
21:03:33 32599 8192000000 10925592240 11373703168 1976253376 19.435 100 24 48 0 1993459664 9331328 7874960
21:05:06 109504 8192000000 10907908832 11389628416 1976345632 19.436 100 4 8 1 1997121424 19548160 1227632
21:08:19 76795 8192000000 10928301712 11400974336 1990540960 19.548 100 16 32 0 2010276912 15138800 4597152
21:10:37 247425 8192000000 10916836800 11387658240 1890254176 18.748 95 12 24 0 1914603792 21114128 3235488
21:15:45 108409 8192000000 10938911824 11387973632 1977870336 19.448 99 27 54 0 2000142656 14487856 7784464
21:18:48 59050 8192000000 10899581072 11356700672 1925616976 19.032 97 52 104 2 1954599312 13822592 15159744
21:20:47 71779 8192000000 10881697072 11362787328 1950017616 19.227 99 11 22 1 1966807424 13295296 3494512
21:23:03 82055 8192000000 10868817648 11343908864 1900495680 18.830 97 15 30 0 1917551520 12385936 4669904
21:25:30 68672 8192000000 10884821552 11357511680 1960659616 19.311 100 19 37 0 1976781184 11381984 4739584
21:27:39 45425 8192000000 10880178304 11348234240 1893262016 18.772 96 14 28 2 1902890592 5983536 3645040
21:30:24 65772 8192000000 10828604816 11280015360 1825420624 18.222 95 18 34 2 1838124576 8165776 4538176
21:32:40 53782 8192000000 10858669872 11314843648 1847164256 18.399 95 15 30 0 1859780864 8252800 4363808
21:34:42 35913 8192000000 10848976864 11313467392 1885842400 18.712 98 26 51 1 1908687504 15515520 7329584
21:36:27 43983 8192000000 10870694144 11311398912 1889674720 18.743 98 43 84 1 1916919568 15000064 12244784
21:38:11 82224 8192000000 10876436416 11306815488 1817138032 18.154 94 23 44 4 1837392192 14268112 5986048
21:40:35 61464 8192000000 10888804336 11328507904 1898925712 18.818 98 47 94 0 1930433264 18061600 13445952
21:43:35 39157 8192000000 10873583952 11334160384 1925449856 19.030 99 28 56 0 1946086240 12876048 7760336
21:45:35 71374 8192000000 10870199856 11338924032 1939526144 19.143 100 10 20 1 1956096544 13490320 3080080
21:47:47 64794 8192000000 10870615072 11358052352 1954065264 19.259 100 9 18 0 1972312048 15387408 2859376
21:49:58 124356 8192000000 10892203344 11356200960 1927064736 19.043 98 19 38 1 1941125200 8818160 5242304
21:53:02 64053 8192000000 10930035888 11380228096 1958241696 19.292 99 14 28 1 1970508736 8558288 3708752
21:55:09 73212 8192000000 10916921600 11398479872 1953141328 19.251 98 9 18 0 1972250336 15736864 3372144
21:58:48 43498 8192000000 10897219488 11361927168 1941402704 19.158 98 20 40 2 1959904304 13192992 5308608
22:01:29 124084 8192000000 10921978368 11380903936 1927586384 19.048 97 9 18 1 1940388768 10153264 2649120
22:04:34 91976 8192000000 10904141200 11376939008 1950877136 19.233 98 30 60 0 1968400640 8785376 8738128
22:07:11 57852 8192000000 10875137296 11358806016 1937734480 19.129 99 11 22 0 1955444000 14376304 3333216
22:09:14 31537 8192000000 10891643696 11356049408 1965344320 19.348 100 15 30 0 1978526896 9290192 3892384
22:10:49 62564 8192000000 10877326496 11362537472 1935165616 19.108 98 15 30 0 1948897408 9422896 4308896
22:12:58 83931 8192000000 10877928192 11346214912 1896362224 18.797 97 12 24 1 1922655072 22580320 3712528
22:15:28 60151 8192000000 10841514432 11299676160 1823702224 18.208 93 28 56 4 1838579296 7563504 7313568
22:17:28 81953 8192000000 10866585968 11331862528 1906485424 18.878 98 17 34 0 1929782672 18546352 4750896
22:19:57 132806 8192000000 10887367808 11346337792 1891415808 18.757 97 28 56 2 1911266256 12142544 7707904
22:23:15 49124 8192000000 10870035600 11316330496 1846315424 18.392 95 25 50 2 1863032880 9557520 7159936
22:25:10 69459 8192000000 10802892992 11269779456 1860073392 18.504 97 27 54 1 1878019584 9780656 8165536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-10-2020 22:29:49 to 24-10-2020 22:30:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 96 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2020 22:30:04 to 24-10-2020 22:30:06.

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          245382
MON$OLDEST_ACTIVE               246893
MON$OLDEST_SNAPSHOT             246893
MON$NEXT_TRANSACTION            246893
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                       2004864
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Oct 24 22:27:32 2020
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Oct 24 22:27:32 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

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

24.10.20 22:30:07. 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-24 22:30:09 No severe PSQL-related problems occured
24.10.20 22:30:10. End of report.