Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2020 22:28
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 17471444 kB
MemAvailable 31633940 kB
Buffers 0 kB
Cached 14243944 kB
SwapCached 5424 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-13 22:26:58
DTS_END                         2020-10-13 22:26:58.6120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/34000
TRN_ID                          251093
ATT_ID                          694
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: 9670.94 18524 1160513
customer order: creation 179.63 334 21555
customer order: refuse 15.04 289 1805
order to supplier: creation 47.27 1127 5672
order to supplier: removal 3.63 90004 436
invoice (draft): creation 47.39 2896 5687
invoice (draft): removal 54.83 680 6579
invoice accept: apply 43.56 16515 5227
invoice accept: cancel 49.46 667 5935
customer reserve: creation 4061.34 162 487361
customer reserve: removal 4864.15 54 583698
realization accept: apply 87.38 168 10486
realization accept: cancel 81.66 88 9799
payment from customer: creation 62.58 90 7509
payment from customer: removal 6.30 49 756
payment to supplier: creation 50.90 105 6108
payment to supplier: removal 3.30 41 396
service: total inventory turnovers 4.83 887 580
service: total monetary turnovers 6.83 30 819
service: aggregate perf. data 0.84 16282 101
service: refresh index statistics 0.03 240013 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2020 22:28:33 to 13-10-2020 22:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 262
TEST_TIME 2 2088
TEST_TIME 3 3724
TEST_TIME 4 3994
TEST_TIME 5 4398
TEST_TIME 6 4354
TEST_TIME 7 4521
TEST_TIME 8 4769
TEST_TIME 9 5000
TEST_TIME 10 5275
TEST_TIME 11 5800
TEST_TIME 12 6421
TEST_TIME 13 6613
TEST_TIME 14 6913
TEST_TIME 15 7615
TEST_TIME 16 7862
TEST_TIME 17 8847
TEST_TIME 18 9096
TEST_TIME 19 9020
TEST_TIME 20 9578
TEST_TIME 21 10542
TEST_TIME 22 10797
TEST_TIME 23 11699
TEST_TIME 24 11616
TEST_TIME 25 11336
TEST_TIME 26 11712
TEST_TIME 27 11545
TEST_TIME 28 11433
TEST_TIME 29 11183
TEST_TIME 30 10941
TEST_TIME 31 10793
TEST_TIME 32 10872
TEST_TIME 33 10678
TEST_TIME 34 10618
TEST_TIME 35 10627
TEST_TIME 36 10676
TEST_TIME 37 10553
TEST_TIME 38 10541
TEST_TIME 39 10443
TEST_TIME 40 10492
TEST_TIME 41 10561
TEST_TIME 42 10476
TEST_TIME 43 10504
TEST_TIME 44 10546
TEST_TIME 45 10470
TEST_TIME 46 10600
TEST_TIME 47 10635
TEST_TIME 48 10643
TEST_TIME 49 10649
TEST_TIME 50 10643
TEST_TIME 51 10629
TEST_TIME 52 10514
TEST_TIME 53 10388
TEST_TIME 54 10389
TEST_TIME 55 10265
TEST_TIME 56 10288
TEST_TIME 57 10212
TEST_TIME 58 10185
TEST_TIME 59 10067
TEST_TIME 60 9967
TEST_TIME 61 9976
TEST_TIME 62 9927
TEST_TIME 63 9901
TEST_TIME 64 9946
TEST_TIME 65 10020
TEST_TIME 66 9999
TEST_TIME 67 10090
TEST_TIME 68 10195
TEST_TIME 69 10309
TEST_TIME 70 10375
TEST_TIME 71 10296
TEST_TIME 72 10324
TEST_TIME 73 10310
TEST_TIME 74 10229
TEST_TIME 75 10185
TEST_TIME 76 10140
TEST_TIME 77 10060
TEST_TIME 78 9989
TEST_TIME 79 9950
TEST_TIME 80 9913
TEST_TIME 81 10048
TEST_TIME 82 10048
TEST_TIME 83 10012
TEST_TIME 84 10012
TEST_TIME 85 10004
TEST_TIME 86 10017
TEST_TIME 87 9999
TEST_TIME 88 10059
TEST_TIME 89 10061
TEST_TIME 90 10057
TEST_TIME 91 10166
TEST_TIME 92 10069
TEST_TIME 93 10013
TEST_TIME 94 9988
TEST_TIME 95 9948
TEST_TIME 96 9890
TEST_TIME 97 9865
TEST_TIME 98 9927
TEST_TIME 99 9872
TEST_TIME 100 9885
TEST_TIME 101 9844
TEST_TIME 102 9875
TEST_TIME 103 9854
TEST_TIME 104 9852
TEST_TIME 105 9813
TEST_TIME 106 9788
TEST_TIME 107 9848
TEST_TIME 108 9865
TEST_TIME 109 9877
TEST_TIME 110 9847
TEST_TIME 111 9856
TEST_TIME 112 9949
TEST_TIME 113 9866
TEST_TIME 114 9807
TEST_TIME 115 9789
TEST_TIME 116 9772
TEST_TIME 117 9732
TEST_TIME 118 9702
TEST_TIME 119 9673
TEST_TIME 120 9671

Performance per minute, chart


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

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 1612557 1612557 0 0.00 0 37258 5 0 0
doc_list_biud 1612557 1612557 0 0.00 0 38804 25 0 0
sp_add_invoice_to_stock 5331 5227 104 1.95 34 182786 16516 0 104
sp_cancel_adding_invoice 5935 5935 0 0.00 4 49843 668 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 10871 289 0 0
sp_cancel_customer_reserve 583698 583698 0 0.00 1 38742 54 0 0
sp_cancel_pay_from_customer 756 756 0 0.00 3 3677 49 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 1405 41 0 0
sp_cancel_supplier_invoice 6596 6579 17 0.25 2 38808 680 0 17
sp_cancel_supplier_order 436 436 0 0.00 7 996689 90005 0 0
sp_cancel_write_off 9799 9799 0 0.00 1 12755 88 0 0
sp_client_order 21555 21555 0 0.00 3 72442 335 0 0
sp_customer_reserve 487361 487361 0 0.00 0 56441 162 0 0
sp_fill_shopping_cart 32904 32904 0 0.00 1 12834 33 0 0
sp_fill_shopping_cart_clo_res 487361 487361 0 0.00 0 17310 13 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 29390 293 0 0
sp_kill_cost_storno 16886 16886 0 0.00 0 6215 41 0 0
sp_kill_qstorno_ret_qs2qd 1184210 1184210 0 0.00 0 38800 29 0 0
sp_kill_qty_storno 623964 623964 0 0.00 0 38803 63 0 0
sp_lock_selected_doc 21646 21646 0 0.00 0 7509 6 0 0
sp_make_cost_storno 29329 29329 0 0.00 0 36766 42 0 0
sp_multiply_rows_for_pdistr 29329 29329 0 0.00 0 11521 21 0 0
sp_multiply_rows_for_qdistr 21555 21555 0 0.00 0 72428 157 0 0
sp_pay_from_customer 7509 7509 0 0.00 4 16756 90 0 0
sp_pay_to_supplier 6108 6108 0 0.00 5 37283 106 0 0
sp_payment_common 13617 13617 0 0.00 1 21541 24 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 10591 249 0 0
sp_qd_handle_on_invoice_upd_sts 11161 11161 0 0.00 0 38247 293 0 0
sp_qd_handle_on_reserve_upd_sts 10486 10486 0 0.00 0 32568 63 0 0
sp_reserve_write_off 10486 10486 0 0.00 6 32590 169 0 0
sp_supplier_invoice 5687 5687 0 0.00 6 99909 2897 0 0
sp_supplier_order 5672 5672 0 0.00 5 33749 1128 0 0
srv_aggregate_perf_data 101 101 0 0.00 2860 179623 16283 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1368 152748 51308 0 0
srv_find_qd_qs_mism 1110881 1110881 0 0.00 0 12174 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 39 41108 887 0 0
srv_make_money_saldo 819 819 0 0.00 1 9138 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 189700 314449 240014 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5227 5227 0 0.00 0 14413 139 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 1905 68 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 1900 11 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 3450 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 756 756 0 0.00 0 1773 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 6012 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 868 10 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 14739 1400 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1633 8 0 0
t$perf-norm:sp_client_order 21555 21555 0 0.00 0 7954 14 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3531 8 0 0
t$perf-norm:sp_pay_from_customer 7509 7509 0 0.00 0 14679 11 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 13329 13 0 0
t$perf-norm:sp_reserve_write_off 10486 10486 0 0.00 0 11253 9 0 0
t$perf-norm:sp_supplier_invoice 5687 5687 0 0.00 0 7021 19 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 6363 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 634 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 2547 44 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 1250 14 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 497 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 7 5 0 0
v_add_invoice_to_stock 10660 10452 208 1.95 0 17976 24 0 208
v_all_customers 38966 38966 0 0.00 0 112 1 0 0
v_all_suppliers 31764 31764 0 0.00 0 112 1 0 0
v_all_wares 43108 43108 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 142 5 0 0
v_cancel_client_order 3610 3610 0 0.00 0 10794 11 0 0
v_cancel_customer_prepayment 1512 1512 0 0.00 0 3652 6 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3330 6 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 449 3 0 0
v_cancel_supplier_invoice 838 804 34 4.05 0 3180 22 0 34
v_cancel_supplier_order 872 872 0 0.00 0 2262 9 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1554 13 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 693 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 1252 7 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 8033 5 0 0
v_max_non_paid_invoice 1576 1576 0 0.00 0 380 3 0 0
v_max_non_paid_realizn 4161 4161 0 0.00 0 2800 4 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 1008 3 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 2306 8 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 6684 10 0 0
v_min_non_paid_invoice 6108 6108 0 0.00 0 21537 17 0 0
v_min_non_paid_realizn 7509 7509 0 0.00 0 16731 16 0 0
v_our_firm 10838 10838 0 0.00 0 15 1 0 0
v_reserve_write_off 20972 20972 0 0.00 0 15537 9 0 0
x_lock_depdocs_on_canc_invoice 12112 12112 0 0.00 0 15951 97 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 14098 280 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 2 33727 1065 0 0
x_make_qstorno_1200_2000 5687 5687 0 0.00 3 95036 2773 0 0
x_make_qstorno_2100_3300 487361 487361 0 0.00 0 56439 147 0 0

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

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:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:02 19282 8192000000 10345846352 10662391808 1399729552 14.593 100 83 166 0 1433286448 6164608 27392288
19:59:27 20137 8192000000 10409616576 10739609600 1462864784 15.151 100 88 176 2 1500317504 8225168 29227552
20:00:52 20766 8192000000 10481252144 10822422528 1529193200 15.730 100 83 166 0 1565352432 9699808 26459424
20:02:24 8487 8192000000 10556722992 10916978688 1612173472 16.443 100 68 136 1 1643047664 11021984 19852208
20:03:35 7725 8192000000 10633609824 11001475072 1695897232 17.151 100 79 158 1 1733335888 14275776 23162880
20:04:48 3478 8192000000 10698081984 11077124096 1763565904 17.714 100 83 165 0 1799482208 12322736 23593568
20:05:53 1772 8192000000 10750618800 11132248064 1815738640 18.143 100 91 182 0 1855923296 14263136 25921520
20:06:55 1534 8192000000 10812878128 11200102400 1867524016 18.564 100 92 185 0 1906733392 12771680 26437696
20:07:57 24221 8192000000 10803028608 11226603520 1882026704 18.681 100 44 88 0 1910198848 14571664 13600480
20:09:24 4841 8192000000 10802483328 11227299840 1889235280 18.740 100 37 73 1 1906424624 7313952 9875392
20:10:29 8525 8192000000 10802907360 11235586048 1893062944 18.770 100 25 51 0 1906358048 6599472 6695632
20:11:38 8269 8192000000 10818311440 11249131520 1902907152 18.850 100 20 40 1 1914243968 5576560 5760256
20:12:48 45237 8192000000 10850876288 11267055616 1917111136 18.964 100 37 72 3 1933544192 5062080 11370976
20:14:34 4952 8192000000 10825006656 11254075392 1897702768 18.808 99 36 71 1 1914511408 7582112 9226528
20:15:39 5671 8192000000 10812456032 11242446848 1885176208 18.707 99 25 50 1 1898424080 6429680 6818192
20:16:47 6965 8192000000 10771697808 11200675840 1833062000 18.284 98 26 52 2 1846458064 6415232 6980832
20:17:54 36383 8192000000 10657241984 11062689792 1521673680 15.665 83 33 66 0 1539877568 8809664 9394224
20:19:30 5828 8192000000 10704675152 11116179456 1758766944 17.674 98 34 68 2 1774708848 6090400 9851504
20:20:41 6981 8192000000 10726612160 11147681792 1803563312 18.043 99 39 78 2 1821970576 6084304 12322960
20:21:50 73260 8192000000 10735258960 11167928320 1818661312 18.167 99 28 56 0 1832178928 5560960 7956656
20:24:03 6566 8192000000 10772371808 11198664704 1857252608 18.481 100 33 64 1 1872856272 5343248 10260416
20:25:10 8280 8192000000 10785164720 11213537280 1869062432 18.577 100 30 58 3 1883301408 5677456 8561520
20:26:24 9152 8192000000 10789387248 11215286272 1874280048 18.619 100 31 62 1 1889521264 6095248 9145968
20:27:35 61702 8192000000 10844886640 11268620288 1907843200 18.889 100 45 90 1 1930373264 9947808 12582256
20:29:44 9582 8192000000 10857711984 11278880768 1925855200 19.034 100 52 103 1 1953183280 12332800 14995280
20:30:56 14642 8192000000 10892667632 11320168448 1953332528 19.253 100 44 88 3 1979613424 13229360 13051536
20:32:13 6679 8192000000 10908817888 11336450048 1956593328 19.279 99 62 124 1 1994480880 20060384 17827168
20:33:24 17380 8192000000 10899921328 11331358720 1958873232 19.297 99 44 86 1 1993767232 21022864 13871136
20:34:47 16031 8192000000 10881804960 11315437568 1893362464 18.773 96 34 68 1 1931058208 26739024 10956720
20:36:05 9757 8192000000 10934190512 11369377792 1987275792 19.522 100 53 105 0 2031387568 27569536 16542240
20:37:18 1367 8192000000 10980479680 11405242368 2012845680 19.724 100 83 163 3 2073382304 35267680 25268944
20:38:22 4863 8192000000 10967301744 11393413120 1999460688 19.618 100 69 137 0 2058258880 36766176 22032016
20:39:31 2028 8192000000 10921984256 11334037504 1943875184 19.178 99 78 156 1 2006301664 38614512 23811968
20:40:37 2528 8192000000 10935808240 11341803520 1946314720 19.197 100 84 167 1 2018388480 45829024 26244736
20:41:42 11615 8192000000 10941165216 11372711936 1964168128 19.339 100 59 118 2 2033957088 49556448 20232512
20:42:59 3172 8192000000 10952005312 11374362624 1960753888 19.312 100 65 130 0 2034677648 53201984 20721776
20:44:05 10992 8192000000 10898267408 11331608576 1899183520 18.820 98 35 71 2 1956451072 45021008 12246544
20:45:23 22001 8192000000 10895956448 11335446528 1912227168 18.925 99 32 64 1 1967928736 44458256 11243312
20:46:47 32270 8192000000 10930913856 11385999360 1955441536 19.270 100 30 60 1 2016045552 49306416 11297600
20:48:30 31700 8192000000 10865394352 11314016256 1889026256 18.738 98 19 38 0 1928919456 33178608 6714592
20:50:03 95046 8192000000 10868871632 11329597440 1852495168 18.442 96 10 20 1 1870322512 15238432 2588912
20:52:43 71844 8192000000 10856040176 11319066624 1929017376 19.059 100 8 16 0 1948193216 16852688 2323152
20:54:56 75229 8192000000 10891694496 11335196672 1944172816 19.180 100 44 87 2 1964754544 8597664 11984064
20:58:05 46877 8192000000 10883369792 11319787520 1918308080 18.973 98 32 64 1 1933940080 6537248 9094752
20:59:53 13382 8192000000 10885615264 11344805888 1939037200 19.139 99 30 61 3 1959284352 12013296 8233856
21:01:07 58543 8192000000 10920510592 11393118208 1974531792 19.421 100 21 42 1 2002814464 21857472 6425200
21:04:29 44632 8192000000 10883515408 11352506368 1952471920 19.246 99 15 29 0 1970879984 13935696 4472368
21:06:14 58899 8192000000 10888811984 11373817856 1960168496 19.307 99 8 16 0 1973739632 10949520 2621616
21:08:14 62067 8192000000 10908410880 11393024000 1987574800 19.525 100 21 41 2 2005213344 11651392 5987152
21:10:26 39596 8192000000 10947786096 11400015872 1999830752 19.621 100 36 72 1 2017786960 7926400 10029808
21:12:11 73616 8192000000 10912294288 11388620800 1898720288 18.816 95 12 24 0 1915165024 13069040 3375696
21:14:25 77077 8192000000 10888060528 11352203264 1917754672 18.969 97 19 38 0 1930848448 7684960 5408816
21:16:54 50432 8192000000 10885586592 11355250688 1871210448 18.594 95 11 22 0 1883655856 9289072 3156336
21:18:48 115165 8192000000 10863582640 11334438912 1900537728 18.831 97 21 42 1 1918837424 12328832 5970864
21:21:45 35544 8192000000 10862570944 11330383872 1914564448 18.943 99 6 11 2 1924249568 8046448 1638672
21:23:25 51979 8192000000 10835049312 11309174784 1902176336 18.844 98 10 20 0 1914613296 9826064 2610896
21:25:25 78354 8192000000 10841890768 11311198208 1871404448 18.596 97 29 58 0 1888249968 8486336 8359184
21:29:04 40118 8192000000 10852894704 11312762880 1877093632 18.642 97 23 46 1 1893154000 9661776 6398592
21:30:49 51511 8192000000 10865330992 11341529088 1882118464 18.682 97 20 40 1 1904555968 15957664 6479840
21:33:24 74232 8192000000 10875695408 11365466112 1925301104 19.029 99 15 30 2 1949452256 19859856 4291296
21:35:45 86270 8192000000 10866736880 11318718464 1847481568 18.402 95 14 28 0 1867612464 16179392 3951504
21:38:13 54744 8192000000 10889319472 11347607552 1947388480 19.206 100 45 90 2 1976389024 16699392 12301152
21:40:14 50416 8192000000 10911993280 11348197376 1953177344 19.252 100 45 90 1 1977496928 11042560 13277024
21:42:06 50152 8192000000 10858127152 11336044544 1909986096 18.907 98 19 38 1 1925377024 9945472 5445456
21:44:50 62891 8192000000 10885475904 11366227968 1933666864 19.096 99 8 16 0 1946022736 10151472 2204400
21:46:54 90541 8192000000 10838400256 11320758272 1872456288 18.604 97 10 20 0 1887729328 12431696 2841344
21:49:30 41177 8192000000 10877609792 11345891328 1947177456 19.204 100 21 42 0 1961401680 8408864 5815360
21:51:11 28252 8192000000 10906078448 11357757440 1962234144 19.324 100 36 71 0 1980708512 8821744 9652624
21:52:42 110617 8192000000 10899904992 11378761728 1924002400 19.019 98 15 30 1 1937907568 10333904 3571264
21:55:34 74382 8192000000 10893668464 11347443712 1881797952 18.680 96 16 30 0 1900498768 14750672 3950144
21:57:49 152748 8192000000 10920267232 11378180096 1972983936 19.409 100 25 50 0 1992403632 12889232 6530464
22:01:31 97406 8192000000 10910423056 11374620672 1964323792 19.340 99 13 26 1 1981512544 13127632 4061120
22:04:11 73305 8192000000 10900388128 11379396608 1913555872 18.935 97 8 15 1 1929380496 13786144 2038480
22:06:33 48327 8192000000 10886414992 11336880128 1926304912 19.037 98 22 44 0 1941192768 8106480 6781376
22:08:24 88115 8192000000 10846123072 11307466752 1821943456 18.194 94 15 30 1 1835790544 9355776 4491312
22:10:56 40503 8192000000 10854190544 11309813760 1870236864 18.586 97 23 45 2 1884066480 7227952 6601664
22:12:37 52940 8192000000 10844302688 11311153152 1872875344 18.608 97 22 44 1 1893925792 14234656 6815792
22:14:41 61188 8192000000 10859087168 11311882240 1854809088 18.461 96 27 54 3 1871483312 9306512 7367712
22:16:44 50540 8192000000 10855861984 11307663360 1816630656 18.150 94 27 54 0 1840183728 15958016 7595056
22:18:34 127978 8192000000 10852989280 11314659328 1886994704 18.722 99 11 22 0 1916136464 26106576 3035184
22:22:16 56689 8192000000 10832400976 11311546368 1854539840 18.459 96 10 20 0 1863380560 6325632 2515088
22:24:18 59249 8192000000 10830916896 11293671424 1883047664 18.690 98 17 34 2 1898607792 10988144 4571984
22:26:18 47563 8192000000 9745991376 10100723712 881182992 9.711 46 5 10 0 888602864 6466640 953232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2020 22:28:47 to 13-10-2020 22:29:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 104 v_add_invoice_to_stock 335544517
user_exc 17 v_cancel_supplier_invoice 335544517

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

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          251398
MON$OLDEST_ACTIVE               251399
MON$OLDEST_SNAPSHOT             251399
MON$NEXT_TRANSACTION            251399
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                       2038624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

13.10.20 22:29:05. 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-13 22:29:08 No severe PSQL-related problems occured
13.10.20 22:29:09. End of report.