Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.10.2020 16:31
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 17363636 kB
MemAvailable 31508404 kB
Buffers 0 kB
Cached 14321876 kB
SwapCached 3064 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-30 16:30:42
DTS_END                         2020-10-30 16:30:42.9740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/41598
TRN_ID                          239406
ATT_ID                          627
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: 9498.28 23644 1139794
customer order: creation 168.42 360 20210
customer order: refuse 14.82 351 1778
order to supplier: creation 45.38 1242 5445
order to supplier: removal 3.68 102386 441
invoice (draft): creation 44.92 3052 5390
invoice (draft): removal 56.10 749 6732
invoice accept: apply 41.18 17664 4942
invoice accept: cancel 50.55 747 6066
customer reserve: creation 3816.38 174 457965
customer reserve: removal 4956.83 59 594820
realization accept: apply 83.01 170 9961
realization accept: cancel 89.93 104 10792
payment from customer: creation 57.66 102 6919
payment from customer: removal 5.36 77 643
payment to supplier: creation 48.89 117 5867
payment to supplier: removal 3.26 57 391
service: total inventory turnovers 4.65 1160 558
service: total monetary turnovers 6.38 61 765
service: aggregate perf. data 0.88 17191 105
service: refresh index statistics 0.03 327056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-10-2020 16:32:01 to 30-10-2020 16:32:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1778
TEST_TIME 2 1587
TEST_TIME 3 2978
TEST_TIME 4 3791
TEST_TIME 5 3910
TEST_TIME 6 3851
TEST_TIME 7 3893
TEST_TIME 8 4251
TEST_TIME 9 4351
TEST_TIME 10 4528
TEST_TIME 11 4757
TEST_TIME 12 5072
TEST_TIME 13 5137
TEST_TIME 14 5603
TEST_TIME 15 5814
TEST_TIME 16 6069
TEST_TIME 17 6831
TEST_TIME 18 7291
TEST_TIME 19 7909
TEST_TIME 20 8209
TEST_TIME 21 8057
TEST_TIME 22 8840
TEST_TIME 23 8788
TEST_TIME 24 8791
TEST_TIME 25 9213
TEST_TIME 26 9434
TEST_TIME 27 10300
TEST_TIME 28 10716
TEST_TIME 29 10633
TEST_TIME 30 10615
TEST_TIME 31 10522
TEST_TIME 32 10278
TEST_TIME 33 10044
TEST_TIME 34 10172
TEST_TIME 35 10086
TEST_TIME 36 9978
TEST_TIME 37 9878
TEST_TIME 38 10063
TEST_TIME 39 9975
TEST_TIME 40 9967
TEST_TIME 41 9830
TEST_TIME 42 9883
TEST_TIME 43 9807
TEST_TIME 44 9893
TEST_TIME 45 9757
TEST_TIME 46 9742
TEST_TIME 47 9747
TEST_TIME 48 9766
TEST_TIME 49 9796
TEST_TIME 50 9846
TEST_TIME 51 9824
TEST_TIME 52 9885
TEST_TIME 53 9877
TEST_TIME 54 10007
TEST_TIME 55 9994
TEST_TIME 56 9934
TEST_TIME 57 10029
TEST_TIME 58 10068
TEST_TIME 59 10113
TEST_TIME 60 9979
TEST_TIME 61 9928
TEST_TIME 62 9859
TEST_TIME 63 9804
TEST_TIME 64 9853
TEST_TIME 65 9758
TEST_TIME 66 9716
TEST_TIME 67 9741
TEST_TIME 68 9763
TEST_TIME 69 9712
TEST_TIME 70 9663
TEST_TIME 71 9708
TEST_TIME 72 9797
TEST_TIME 73 9820
TEST_TIME 74 9793
TEST_TIME 75 9809
TEST_TIME 76 9733
TEST_TIME 77 9848
TEST_TIME 78 9840
TEST_TIME 79 9803
TEST_TIME 80 9809
TEST_TIME 81 9711
TEST_TIME 82 9706
TEST_TIME 83 9717
TEST_TIME 84 9712
TEST_TIME 85 9656
TEST_TIME 86 9599
TEST_TIME 87 9521
TEST_TIME 88 9542
TEST_TIME 89 9460
TEST_TIME 90 9534
TEST_TIME 91 9628
TEST_TIME 92 9655
TEST_TIME 93 9666
TEST_TIME 94 9638
TEST_TIME 95 9667
TEST_TIME 96 9698
TEST_TIME 97 9727
TEST_TIME 98 9691
TEST_TIME 99 9715
TEST_TIME 100 9698
TEST_TIME 101 9710
TEST_TIME 102 9683
TEST_TIME 103 9679
TEST_TIME 104 9772
TEST_TIME 105 9757
TEST_TIME 106 9683
TEST_TIME 107 9654
TEST_TIME 108 9652
TEST_TIME 109 9642
TEST_TIME 110 9613
TEST_TIME 111 9588
TEST_TIME 112 9539
TEST_TIME 113 9525
TEST_TIME 114 9486
TEST_TIME 115 9517
TEST_TIME 116 9489
TEST_TIME 117 9556
TEST_TIME 118 9530
TEST_TIME 119 9516
TEST_TIME 120 9499

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-10-2020 16:32:05 to 30-10-2020 16:32:08.

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 1564728 1564728 0 0.00 0 21184 6 0 0
doc_list_biud 1564727 1564727 0 0.00 0 56580 28 0 0
sp_add_invoice_to_stock 5067 4942 125 2.46 25 193702 17665 0 125
sp_cancel_adding_invoice 6066 6066 0 0.00 4 59320 747 0 0
sp_cancel_client_order 1778 1778 0 0.00 4 20304 351 0 0
sp_cancel_customer_reserve 594820 594820 0 0.00 1 56581 60 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 6508 77 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 2467 58 0 0
sp_cancel_supplier_invoice 6742 6732 10 0.14 2 40954 750 0 10
sp_cancel_supplier_order 441 441 0 0.00 7 1135841 102386 0 0
sp_cancel_write_off 10792 10792 0 0.00 1 19563 105 0 0
sp_client_order 20210 20210 0 0.00 4 26723 360 0 0
sp_customer_reserve 457965 457965 0 0.00 0 119096 175 0 0
sp_fill_shopping_cart 31035 31035 0 0.00 1 10971 36 0 0
sp_fill_shopping_cart_clo_res 457963 457963 0 0.00 0 35100 12 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 21938 270 0 0
sp_kill_cost_storno 17892 17892 0 0.00 0 7551 54 0 0
sp_kill_qstorno_ret_qs2qd 1207605 1207605 0 0.00 0 56575 32 0 0
sp_kill_qty_storno 635529 635529 0 0.00 0 56580 69 0 0
sp_lock_selected_doc 20614 20614 0 0.00 0 10043 5 0 0
sp_make_cost_storno 27686 27686 0 0.00 0 18214 47 0 0
sp_multiply_rows_for_pdistr 27686 27686 0 0.00 0 6580 26 0 0
sp_multiply_rows_for_qdistr 20210 20210 0 0.00 0 26713 167 0 0
sp_pay_from_customer 6919 6919 0 0.00 4 15263 102 0 0
sp_pay_to_supplier 5867 5867 0 0.00 5 14751 117 0 0
sp_payment_common 12786 12786 0 0.00 1 15226 27 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 20279 320 0 0
sp_qd_handle_on_invoice_upd_sts 11005 11005 0 0.00 0 29149 344 0 0
sp_qd_handle_on_reserve_upd_sts 9961 9961 0 0.00 0 26001 70 0 0
sp_reserve_write_off 9961 9961 0 0.00 5 26146 171 0 0
sp_supplier_invoice 5390 5390 0 0.00 6 84170 3052 0 0
sp_supplier_order 5445 5445 0 0.00 6 48744 1242 0 0
srv_aggregate_perf_data 105 105 0 0.00 2040 181812 17191 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1010 198765 49752 0 0
srv_find_qd_qs_mism 1093222 1093222 0 0.00 0 21183 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 46 120010 1161 0 0
srv_make_money_saldo 765 765 0 0.00 1 20372 61 0 0
srv_recalc_idx_stat 4 4 0 0.00 149269 520287 327056 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4942 4942 0 0.00 0 11664 178 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 2033 61 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 1569 10 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2771 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 26612 46 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 211 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 2670 22 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 57007 1532 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 6273 13 0 0
t$perf-norm:sp_client_order 20210 20210 0 0.00 0 8012 17 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 2775 9 0 0
t$perf-norm:sp_pay_from_customer 6919 6919 0 0.00 0 8148 12 0 0
t$perf-norm:sp_pay_to_supplier 5867 5867 0 0.00 0 3973 9 0 0
t$perf-norm:sp_reserve_write_off 9961 9961 0 0.00 0 9114 13 0 0
t$perf-norm:sp_supplier_invoice 5390 5390 0 0.00 0 8113 15 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 3153 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 306 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 644 12 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 4272 28 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 3518 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10124 9874 250 2.46 0 6294 19 0 250
v_all_customers 36934 36934 0 0.00 0 42 1 0 0
v_all_suppliers 29956 29956 0 0.00 0 22 1 0 0
v_all_wares 40418 40418 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1175 7 0 0
v_cancel_client_order 3556 3556 0 0.00 0 5038 6 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1676 5 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 558 4 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1437 7 0 0
v_cancel_supplier_invoice 756 736 20 2.64 0 5602 23 0 20
v_cancel_supplier_order 882 882 0 0.00 0 398 3 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5054 14 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 7719 3 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1481 14 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 5749 3 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 2877 5 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 3934 4 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 7962 11 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 2642 4 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 4469 5 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 14636 19 0 0
v_min_non_paid_realizn 6919 6919 0 0.00 0 11675 14 0 0
v_our_firm 10094 10094 0 0.00 0 15 1 0 0
v_reserve_write_off 19922 19922 0 0.00 0 13627 6 0 0
x_lock_depdocs_on_canc_invoice 12430 12430 0 0.00 0 13172 116 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 11071 353 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 2 48736 1152 0 0
x_make_qstorno_1200_2000 5390 5390 0 0.00 3 84163 2937 0 0
x_make_qstorno_2100_3300 457965 457965 0 0.00 0 119096 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2020 16:32:09 to 30-10-2020 16:32:13.

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
14:00:46 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:47 26147 8192000000 10348661344 10666741760 1400244432 14.597 100 87 174 1 1434270640 5723920 28302288
14:03:20 18613 8192000000 10426824768 10757206016 1480446464 15.305 100 84 168 0 1516022224 8664256 26911504
14:04:45 12164 8192000000 10475798768 10816131072 1536920320 15.797 100 85 170 1 1572713184 9784048 26008816
14:06:10 13940 8192000000 10555645040 10914189312 1611173712 16.435 100 74 148 0 1644598944 10869584 22555648
14:07:28 6878 8192000000 10635939984 11008708608 1698298720 17.171 100 74 147 1 1730778048 10871680 21607648
14:08:38 8790 8192000000 10712003248 11093630976 1774658064 17.805 100 73 145 0 1807147008 12613184 19875760
14:09:48 2447 8192000000 10771207920 11151065088 1822767424 18.200 100 85 170 0 1861957888 14842928 24347536
14:10:51 2280 8192000000 10802261072 11189379072 1861021440 18.512 100 91 176 0 1898326768 13171088 24134240
14:11:56 1371 8192000000 10812426848 11199967232 1866921168 18.559 100 97 192 2 1907941456 13692064 27328224
14:12:59 9884 8192000000 10785666336 11199463424 1868103072 18.569 100 43 87 0 1887173792 5020112 14050608
14:14:10 8290 8192000000 10775632800 11205181440 1872162784 18.602 100 19 38 3 1882931168 5633264 5135120
14:15:21 13201 8192000000 10786370480 11221233664 1882182896 18.683 100 21 42 1 1893817440 5963712 5670832
14:16:39 54137 8192000000 10813621184 11244781568 1892963712 18.770 100 30 60 2 1908145024 6243152 8938160
14:18:34 6674 8192000000 10805273120 11237126144 1875326640 18.627 99 34 68 0 1893006720 8053296 9626784
14:19:42 8427 8192000000 10803555856 11242962944 1879878224 18.664 99 17 35 1 1890095280 5362752 4854304
14:20:55 9007 8192000000 10766833104 11200692224 1847677904 18.403 99 27 54 0 1860906256 4845584 8382768
14:22:04 7905 8192000000 10750889248 11181268992 1813666288 18.126 98 19 38 0 1824744816 5763184 5315344
14:23:16 5097 8192000000 10724133056 11146645504 1783848544 17.881 98 24 48 4 1796072304 5434624 6789136
14:24:23 4583 8192000000 10718202448 11129847808 1792430272 17.952 100 24 48 0 1805393856 6156912 6806672
14:25:28 7610 8192000000 10701907648 11119919104 1794679200 17.970 100 35 70 1 1809743072 5129632 9934240
14:26:36 24090 8192000000 10722961440 11151720448 1815947152 18.145 100 19 38 1 1827115760 5928992 5239616
14:28:01 4805 8192000000 10768058720 11186040832 1846494912 18.394 100 37 74 0 1864040992 7533616 10012464
14:29:07 12078 8192000000 10766382976 11200548864 1856285744 18.473 100 19 38 3 1867730464 5567568 5877152
14:30:21 22491 8192000000 10796070640 11226296320 1885775824 18.712 100 19 38 4 1895541888 4764064 5002000
14:32:16 64941 8192000000 10847778336 11276988416 1921335680 18.998 100 40 78 0 1944792816 11962560 11494576
14:34:27 18022 8192000000 10890372240 11309051904 1940869312 19.154 100 57 107 4 1972288224 14432720 16986192
14:35:49 11412 8192000000 10910917712 11338604544 1966670608 19.359 100 48 96 3 1996836752 15735328 14430816
14:37:09 13395 8192000000 10914870784 11344936960 1974794608 19.423 100 47 92 1 2009182656 20309664 14078384
14:38:28 5881 8192000000 10955540960 11382128640 1998662736 19.612 100 59 117 1 2039196256 22207888 18325632
14:39:38 8075 8192000000 10938459760 11361660928 1970199376 19.387 99 56 112 1 2012313856 24896256 17218224
14:40:53 8117 8192000000 10961575872 11391242240 2002870496 19.645 100 58 116 1 2050925872 30516304 17539072
14:42:06 2721 8192000000 10972718432 11398557696 2004340656 19.657 100 72 144 0 2059969296 33467616 22161024
14:43:14 3031 8192000000 10986570128 11406934016 2010607664 19.706 100 72 140 0 2068654608 36157424 21889520
14:44:25 2915 8192000000 11024012752 11446538240 2033048528 19.883 100 86 171 1 2106076672 47635552 25392592
14:45:32 3448 8192000000 10983919264 11410317312 2004721424 19.660 100 70 138 0 2074489456 47105456 22662576
14:46:45 6877 8192000000 10947516320 11362676736 1925198816 19.028 98 80 160 1 2004871376 54005664 25666896
14:48:03 1009 8192000000 10973526736 11383095296 1969880944 19.385 100 94 188 0 2053461600 54230832 29349824
14:49:09 3228 8192000000 10948777392 11357642752 1949111488 19.219 100 78 153 1 2024480720 51582560 23786672
14:50:15 17715 8192000000 10914533120 11357794304 1901478832 18.838 97 29 58 1 1965239216 53714192 10046192
14:51:36 23228 8192000000 10919684672 11354918912 1921281248 18.997 99 33 66 1 1987493920 55236304 10976368
14:53:01 25275 8192000000 10913460672 11365871616 1936580160 19.119 99 31 62 4 1986995328 40579216 9835952
14:54:27 27025 8192000000 10916811328 11365138432 1941838672 19.161 100 24 48 1 2000263280 49939312 8485296
14:55:56 28455 8192000000 10905626848 11366125568 1916136720 18.956 99 20 40 1 1975837840 51763296 7937824
14:57:41 43198 8192000000 10854880544 11314683904 1882843776 18.688 97 11 22 0 1923884704 37591104 3449824
14:59:24 98714 8192000000 10888973856 11335942144 1928658976 19.056 99 28 57 1 1951907920 15705296 7543648
15:02:16 117783 8192000000 10891228016 11355807744 1938610752 19.136 99 23 46 1 1956481808 11430352 6440704
15:06:38 27954 8192000000 10893736816 11339526144 1937068768 19.123 98 26 51 0 1953324656 9349616 6906272
15:08:15 26567 8192000000 10907914176 11379187712 1979365120 19.460 100 21 41 1 1995534656 9337120 6832416
15:09:43 129247 8192000000 10906073488 11374604288 1950292992 19.229 98 31 61 2 1972889920 13401056 9195872
15:12:58 23226 8192000000 10910046704 11386642432 1981326304 19.475 100 10 22 0 1997434432 13083904 3024224
15:14:21 52171 8192000000 10903386672 11383664640 1986212048 19.514 100 15 30 1 2005629296 15017728 4399520
15:16:18 52422 8192000000 10899918128 11381149696 1928924512 19.058 97 13 26 1 1948050208 15273728 3851968
15:18:11 73849 8192000000 10907905664 11384279040 1959419680 19.301 99 14 26 1 1975926352 12570736 3935936
15:20:25 66820 8192000000 10926912896 11404595200 1969291648 19.380 99 21 43 0 1995317456 19030128 6995680
15:22:41 45806 8192000000 10923780080 11393794048 1986871808 19.519 100 11 22 0 2003295024 13473664 2949552
15:24:29 99201 8192000000 10918461072 11393695744 1950866880 19.233 98 7 14 0 1963916368 11341248 1708240
15:27:09 94251 8192000000 10906485792 11372957696 1934913888 19.106 98 13 26 0 1955601664 17055264 3632512
15:29:45 124811 8192000000 10869950000 11340984320 1931770912 19.081 98 21 42 0 1952390176 14848864 5770400
15:32:51 32180 8192000000 10889885888 11349569536 1928620784 19.056 98 20 40 1 1944495168 10388560 5485824
15:34:24 63472 8192000000 10859031984 11340763136 1910777904 18.913 98 9 18 2 1927065536 13940896 2346736
15:36:28 60566 8192000000 10832567936 11308666880 1871419360 18.596 96 11 23 0 1882622880 8177872 3025648
15:38:29 65688 8192000000 10853406480 11312308224 1834608592 18.297 95 18 36 0 1847173312 7942688 4622032
15:40:36 36682 8192000000 10877254000 11318382592 1878025056 18.649 97 47 94 2 1905157568 14489584 12642928
15:42:14 95829 8192000000 10853966400 11316744192 1842739120 18.363 96 14 28 0 1864840272 18449376 3651776
15:45:30 36486 8192000000 10847349504 11305963520 1870930512 18.592 97 13 26 1 1883613856 9276496 3406848
15:47:07 49016 8192000000 10871123472 11346980864 1924496640 19.023 99 37 74 1 1951613376 16927312 10189424
15:49:00 30023 8192000000 10889786992 11343147008 1949443600 19.222 100 33 65 0 1968754944 9814736 9496608
15:50:30 124335 8192000000 10888204336 11359735808 1944886992 19.186 99 30 60 3 1966396496 12696848 8812656
15:53:45 18554 8192000000 10886223024 11374067712 1966237184 19.356 100 18 36 0 1987773296 16503872 5032240
15:55:04 60907 8192000000 10898054208 11369492480 1974886864 19.424 100 13 26 2 1987258160 8826480 3544816
15:57:14 81173 8192000000 10921772256 11407831040 1990504336 19.548 100 22 44 3 2014176416 16542272 7129808
16:00:40 70487 8192000000 10907857376 11406618624 1979186272 19.458 100 10 20 0 2001110592 18956864 2967456
16:02:54 89273 8192000000 10924179872 11398549504 1999040752 19.615 100 16 32 0 2017679728 14271344 4367632
16:05:24 63641 8192000000 10942120736 11408928768 2000070704 19.623 100 28 56 1 2026239360 18635648 7533008
16:08:36 32871 8192000000 10926793792 11381243904 1988659632 19.533 100 38 76 1 2010782576 11764688 10358256
16:10:16 90770 8192000000 10889769776 11358564352 1934117456 19.100 98 14 27 1 1950530512 12977184 3435872
16:12:55 27275 8192000000 10906163904 11364851712 1961199120 19.316 100 37 74 3 1986709168 14770896 10739152
16:14:33 198765 8192000000 10887238304 11357089792 1861022416 18.512 94 14 28 1 1881853504 17060608 3770480
16:18:56 26577 8192000000 10900287776 11341455360 1923652320 19.016 98 32 64 0 1939890032 6865088 9372624
16:20:27 51490 8192000000 10864797520 11341774848 1942005008 19.163 99 8 16 1 1957317424 12883888 2428528
16:22:18 54307 8192000000 10877249328 11349209088 1919745568 18.985 98 20 40 1 1938994448 13616912 5631968
16:24:32 75082 8192000000 10853614352 11331940352 1921702288 19.000 99 18 36 1 1944480512 17257920 5520304
16:27:21 89704 8192000000 10822331760 11282317312 1776173024 17.818 92 19 37 1 1794658416 13272192 5213200
16:30:08 36415 8192000000 10238057312 10639847424 1269588784 13.418 66 25 50 2 1285340704 9019504 6732416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-10-2020 16:32:15 to 30-10-2020 16:32:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 125 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-10-2020 16:32:31 to 30-10-2020 16:32:33.

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          239653
MON$OLDEST_ACTIVE               239654
MON$OLDEST_SNAPSHOT             239654
MON$NEXT_TRANSACTION            239654
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                       2035168
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.33383 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33383 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33383 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33383 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Oct 30 16:30:59 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

30.10.20 16:32:34. 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-30 16:32:36 No severe PSQL-related problems occured
30.10.20 16:32:37. End of report.