Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2020 16:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17364232 kB
MemAvailable 31549468 kB
Buffers 0 kB
Cached 14296848 kB
SwapCached 5440 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.7
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-10-19 16:27:00
DTS_END                         2020-10-19 16:27:00.2160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/41950
TRN_ID                          251965
ATT_ID                          640
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: 9889.74 23008 1186769
customer order: creation 179.92 320 21590
customer order: refuse 15.16 252 1819
order to supplier: creation 48.44 1106 5813
order to supplier: removal 3.97 77979 476
invoice (draft): creation 49.02 2726 5882
invoice (draft): removal 59.23 595 7107
invoice accept: apply 43.24 15100 5189
invoice accept: cancel 53.21 601 6385
customer reserve: creation 3981.06 152 477727
customer reserve: removal 5133.40 48 616008
realization accept: apply 87.72 147 10526
realization accept: cancel 98.49 86 11819
payment from customer: creation 63.02 102 7562
payment from customer: removal 5.93 46 711
payment to supplier: creation 51.24 92 6149
payment to supplier: removal 3.85 59 462
service: total inventory turnovers 5.09 1126 611
service: total monetary turnovers 6.86 23 823
service: aggregate perf. data 0.88 19765 106
service: refresh index statistics 0.03 339839 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9895
TEST_TIME 2 8091
TEST_TIME 3 6930
TEST_TIME 4 6990
TEST_TIME 5 6436
TEST_TIME 6 7427
TEST_TIME 7 7557
TEST_TIME 8 7772
TEST_TIME 9 7939
TEST_TIME 10 7721
TEST_TIME 11 8659
TEST_TIME 12 8604
TEST_TIME 13 8787
TEST_TIME 14 9263
TEST_TIME 15 9134
TEST_TIME 16 9397
TEST_TIME 17 10010
TEST_TIME 18 10347
TEST_TIME 19 10398
TEST_TIME 20 11282
TEST_TIME 21 11345
TEST_TIME 22 12766
TEST_TIME 23 13155
TEST_TIME 24 12809
TEST_TIME 25 12671
TEST_TIME 26 12222
TEST_TIME 27 12057
TEST_TIME 28 12210
TEST_TIME 29 12009
TEST_TIME 30 12014
TEST_TIME 31 11716
TEST_TIME 32 11583
TEST_TIME 33 11513
TEST_TIME 34 11813
TEST_TIME 35 11635
TEST_TIME 36 11528
TEST_TIME 37 11453
TEST_TIME 38 11231
TEST_TIME 39 11167
TEST_TIME 40 11068
TEST_TIME 41 10833
TEST_TIME 42 10819
TEST_TIME 43 10677
TEST_TIME 44 10660
TEST_TIME 45 10517
TEST_TIME 46 10617
TEST_TIME 47 10607
TEST_TIME 48 10619
TEST_TIME 49 10595
TEST_TIME 50 10627
TEST_TIME 51 10671
TEST_TIME 52 10620
TEST_TIME 53 10692
TEST_TIME 54 10777
TEST_TIME 55 10791
TEST_TIME 56 10723
TEST_TIME 57 10754
TEST_TIME 58 10611
TEST_TIME 59 10609
TEST_TIME 60 10585
TEST_TIME 61 10518
TEST_TIME 62 10423
TEST_TIME 63 10359
TEST_TIME 64 10417
TEST_TIME 65 10444
TEST_TIME 66 10395
TEST_TIME 67 10400
TEST_TIME 68 10368
TEST_TIME 69 10407
TEST_TIME 70 10399
TEST_TIME 71 10390
TEST_TIME 72 10377
TEST_TIME 73 10444
TEST_TIME 74 10506
TEST_TIME 75 10529
TEST_TIME 76 10504
TEST_TIME 77 10592
TEST_TIME 78 10527
TEST_TIME 79 10440
TEST_TIME 80 10430
TEST_TIME 81 10372
TEST_TIME 82 10362
TEST_TIME 83 10292
TEST_TIME 84 10266
TEST_TIME 85 10212
TEST_TIME 86 10208
TEST_TIME 87 10110
TEST_TIME 88 10100
TEST_TIME 89 10092
TEST_TIME 90 10253
TEST_TIME 91 10279
TEST_TIME 92 10262
TEST_TIME 93 10254
TEST_TIME 94 10254
TEST_TIME 95 10258
TEST_TIME 96 10302
TEST_TIME 97 10283
TEST_TIME 98 10311
TEST_TIME 99 10253
TEST_TIME 100 10331
TEST_TIME 101 10330
TEST_TIME 102 10320
TEST_TIME 103 10305
TEST_TIME 104 10216
TEST_TIME 105 10161
TEST_TIME 106 10182
TEST_TIME 107 10143
TEST_TIME 108 10100
TEST_TIME 109 10125
TEST_TIME 110 10077
TEST_TIME 111 10050
TEST_TIME 112 10054
TEST_TIME 113 10029
TEST_TIME 114 10002
TEST_TIME 115 10018
TEST_TIME 116 10000
TEST_TIME 117 10045
TEST_TIME 118 10018
TEST_TIME 119 9981
TEST_TIME 120 9898

Performance per minute, chart


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

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 1632525 1632525 0 0.00 0 14447 5 0 0
doc_list_biud 1632525 1632525 0 0.00 0 43558 23 0 0
sp_add_invoice_to_stock 5322 5189 133 2.49 19 193898 15101 0 133
sp_cancel_adding_invoice 6385 6385 0 0.00 4 62767 602 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 9938 253 0 0
sp_cancel_customer_reserve 616008 616008 0 0.00 1 31614 48 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 5244 46 0 0
sp_cancel_pay_to_supplier 462 462 0 0.00 3 3957 60 0 0
sp_cancel_supplier_invoice 7123 7107 16 0.22 1 43567 596 0 16
sp_cancel_supplier_order 476 476 0 0.00 8 865568 77979 0 0
sp_cancel_write_off 11819 11819 0 0.00 1 7827 86 0 0
sp_client_order 21590 21590 0 0.00 3 26237 320 0 0
sp_customer_reserve 477727 477727 0 0.00 0 56095 152 0 0
sp_fill_shopping_cart 33279 33279 0 0.00 1 17829 33 0 0
sp_fill_shopping_cart_clo_res 477702 477702 0 0.00 0 13190 12 0 0
sp_get_clo_for_invoice 5160 5160 0 0.00 0 23197 250 0 0
sp_kill_cost_storno 19377 19377 0 0.00 0 6716 41 0 0
sp_kill_qstorno_ret_qs2qd 1251418 1251418 0 0.00 0 43553 26 0 0
sp_kill_qty_storno 659300 659300 0 0.00 0 43556 55 0 0
sp_lock_selected_doc 21800 21800 0 0.00 0 6677 5 0 0
sp_make_cost_storno 29397 29397 0 0.00 0 14444 42 0 0
sp_multiply_rows_for_pdistr 29397 29397 0 0.00 0 6429 22 0 0
sp_multiply_rows_for_qdistr 21590 21590 0 0.00 0 26225 146 0 0
sp_pay_from_customer 7562 7562 0 0.00 4 11843 103 0 0
sp_pay_to_supplier 6149 6149 0 0.00 4 11671 92 0 0
sp_payment_common 13711 13711 0 0.00 1 9982 27 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 9931 226 0 0
sp_qd_handle_on_invoice_upd_sts 11545 11545 0 0.00 0 15376 248 0 0
sp_qd_handle_on_reserve_upd_sts 10526 10526 0 0.00 0 13810 52 0 0
sp_reserve_write_off 10526 10526 0 0.00 6 14595 148 0 0
sp_supplier_invoice 5882 5882 0 0.00 6 85955 2726 0 0
sp_supplier_order 5813 5813 0 0.00 5 41269 1107 0 0
srv_aggregate_perf_data 106 106 0 0.00 3349 275238 19765 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2202 135290 51722 0 0
srv_find_qd_qs_mism 1138476 1138476 0 0.00 0 7958 4 0 0
srv_make_invnt_saldo 611 611 0 0.00 26 117982 1126 0 0
srv_make_money_saldo 823 823 0 0.00 0 3037 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 236225 466858 339840 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5189 5189 0 0.00 0 16055 148 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 7373 99 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 8653 16 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 6056 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 2313 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 462 462 0 0.00 0 243 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 2173 13 0 0
t$perf-norm:sp_cancel_supplier_order 476 476 0 0.00 0 24360 1327 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 447 2 0 0
t$perf-norm:sp_client_order 21590 21590 0 0.00 0 5750 14 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 8552 13 0 0
t$perf-norm:sp_pay_from_customer 7562 7562 0 0.00 0 3792 8 0 0
t$perf-norm:sp_pay_to_supplier 6149 6149 0 0.00 0 2802 7 0 0
t$perf-norm:sp_reserve_write_off 10526 10526 0 0.00 0 9676 11 0 0
t$perf-norm:sp_supplier_invoice 5882 5882 0 0.00 0 23415 21 0 0
t$perf-norm:sp_supplier_order 5813 5813 0 0.00 0 9167 13 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 246 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 11296 34 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 3016 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10580 10314 266 2.51 0 8100 19 0 266
v_all_customers 39512 39512 0 0.00 0 17 0 0 0
v_all_suppliers 32348 32348 0 0.00 0 20 1 0 0
v_all_wares 43178 43178 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1207 7 0 0
v_cancel_client_order 3638 3638 0 0.00 0 5989 8 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1293 3 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 8033 9 0 0
v_cancel_payment_to_supplier 924 924 0 0.00 0 2368 5 0 0
v_cancel_supplier_invoice 904 872 32 3.53 0 7537 29 0 32
v_cancel_supplier_order 952 952 0 0.00 0 1989 7 0 0
v_cancel_write_off 2046 2046 0 0.00 0 444 10 0 0
v_max_id_clo_ord 5813 5813 0 0.00 0 477 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 1279 8 0 0
v_max_id_ord_sup 5876 5876 0 0.00 0 4020 3 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 2355 5 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 3524 3 0 0
v_min_id_clo_ord 5813 5813 0 0.00 0 2135 5 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 1365 7 0 0
v_min_id_ord_sup 5876 5876 0 0.00 0 5492 9 0 0
v_min_non_paid_invoice 6149 6149 0 0.00 0 6092 15 0 0
v_min_non_paid_realizn 7562 7562 0 0.00 0 5652 19 0 0
v_our_firm 10908 10908 0 0.00 0 10 1 0 0
v_reserve_write_off 21052 21052 0 0.00 0 5961 5 0 0
x_lock_depdocs_on_canc_invoice 13056 13056 0 0.00 0 15999 95 0 0
x_lock_depdocs_on_canc_sup_ord 476 476 0 0.00 0 17401 306 0 0
x_make_qstorno_1000_1200 5813 5813 0 0.00 2 41260 1045 0 0
x_make_qstorno_1200_2000 5882 5882 0 0.00 3 80516 2606 0 0
x_make_qstorno_2100_3300 477727 477727 0 0.00 0 56094 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2020 16:28:17 to 19-10-2020 16:28:21.

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
13:57:04 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:05 24582 8192000000 10372879936 10692296704 1422419984 14.794 100 83 166 1 1455202704 5909488 26873232
13:59:35 33301 8192000000 10442434064 10773454848 1495122640 15.434 100 91 182 0 1532876624 8851472 28902512
14:01:11 7551 8192000000 10488943744 10832326656 1547116064 15.885 100 84 169 0 1582810752 9641696 26052992
14:02:22 8387 8192000000 10551559920 10909216768 1604557376 16.378 100 63 126 2 1634048688 10388560 19102752
14:03:32 8007 8192000000 10653126304 11023454208 1717023760 17.327 100 88 176 1 1755568912 13375808 25169344
14:04:43 3790 8192000000 10716159872 11091722240 1775376224 17.811 100 83 163 2 1811461616 12392048 23693344
14:05:49 2886 8192000000 10750441824 11133083648 1813312368 18.123 100 85 168 1 1849721024 13084720 23323936
14:06:54 1370 8192000000 10806239168 11189899264 1855118512 18.464 100 91 182 0 1893884528 12629744 26136272
14:07:56 7898 8192000000 10769678432 11193483264 1861080864 18.512 100 48 94 2 1881945584 7686416 13178304
14:09:04 5970 8192000000 10799695488 11216670720 1870055104 18.585 100 19 38 0 1881940064 5711584 6173376
14:10:13 4795 8192000000 10787917712 11220566016 1883526896 18.694 100 31 63 1 1898657664 6632864 8497904
14:11:18 64173 8192000000 10832852960 11246247936 1893418432 18.773 100 44 86 1 1916321536 10100128 12802976
14:13:23 6511 8192000000 10802159056 11239739392 1897635504 18.807 100 24 48 0 1910127520 5802480 6689536
14:14:30 8219 8192000000 10796534768 11228368896 1860747776 18.509 98 26 52 3 1874053232 6452320 6853136
14:15:39 15587 8192000000 10777721264 11207520256 1805434832 18.058 96 18 34 4 1815380464 4888672 5056960
14:16:55 8739 8192000000 10768917376 11198455808 1855484800 18.467 100 34 69 0 1873309024 7967568 9856656
14:18:04 4914 8192000000 10729903136 11134758912 1746035056 17.569 96 27 54 0 1759975392 6345792 7594544
14:19:10 10228 8192000000 10700413024 11116220416 1769670784 17.764 99 18 36 1 1780276688 5238752 5367152
14:20:20 38233 8192000000 10723511504 11144126464 1782990896 17.874 98 29 58 0 1797102080 5508576 8602608
14:22:07 6773 8192000000 10739496640 11170684928 1835721456 18.306 100 23 46 2 1848201872 5657152 6823264
14:23:14 7526 8192000000 10767504352 11195035648 1851745536 18.436 100 20 40 0 1862154512 4798544 5610432
14:24:26 11431 8192000000 10776303888 11208814592 1863724624 18.533 100 16 30 3 1873109968 5516112 3869232
14:25:38 59195 8192000000 10779849456 11218030592 1877139152 18.642 100 31 61 6 1890591504 4616080 8836272
14:27:41 4779 8192000000 10851670080 11269439488 1919714752 18.985 100 54 109 1 1943288512 8253136 15320624
14:28:54 14953 8192000000 10883369856 11298844672 1938829584 19.137 100 53 105 0 1964194192 10890080 14474528
14:30:17 30052 8192000000 10893630240 11329146880 1966364400 19.357 100 39 78 2 1992086160 13646960 12074800
14:31:51 16327 8192000000 10911416768 11351408640 1977243232 19.443 100 34 67 1 2006444976 18739920 10461824
14:33:16 9058 8192000000 10922409152 11360387072 1985882688 19.511 100 54 109 1 2024739632 23042368 15814576
14:34:27 9676 8192000000 10941298496 11377467392 1997173952 19.600 100 54 110 0 2036745872 23413616 16158304
14:35:41 4036 8192000000 10950430432 11373150208 1990294544 19.546 100 67 134 2 2037310800 27070368 19945888
14:36:49 3241 8192000000 10951172704 11370373120 1983495504 19.492 100 84 166 6 2039858240 31225344 25137392
14:37:55 5027 8192000000 10973893920 11404722176 2004122496 19.655 100 63 126 2 2062514272 39208368 19183408
14:39:03 6064 8192000000 10977532528 11412135936 2013918144 19.732 100 69 131 0 2069700032 35161360 20620528
14:40:12 2202 8192000000 10958628112 11380523008 1979056640 19.457 100 69 137 1 2043141456 43266240 20818576
14:41:18 2674 8192000000 10962621040 11377586176 1954594656 19.263 99 67 132 0 2028683712 52641200 21447856
14:42:26 14527 8192000000 10927362176 11359260672 1921254544 18.997 98 37 75 0 1986804192 53233968 12315680
14:43:48 12323 8192000000 10938882128 11373101056 1952008336 19.242 100 40 80 1 2023601376 58687088 12905952
14:45:07 23880 8192000000 10928991888 11382636544 1919331680 18.981 98 29 58 1 1989534688 60801952 9401056
14:46:41 31122 8192000000 10907987104 11367706624 1910975792 18.914 99 22 44 1 1985345424 66077312 8292320
14:48:21 50817 8192000000 10898662896 11347853312 1884185152 18.699 98 14 28 0 1944160976 54685600 5290224
14:50:13 58432 8192000000 10859574896 11318808576 1866925968 18.559 97 10 20 0 1884768544 14487568 3355008
14:52:12 74666 8192000000 10865498448 11321352192 1932481504 19.087 100 15 30 0 1944524480 7841040 4201936
14:54:36 100160 8192000000 10872590256 11329966080 1913953872 18.938 98 16 32 1 1931750464 13303712 4492880
14:57:16 113293 8192000000 10929967200 11377393664 1962585040 19.327 99 24 48 1 1984950544 15909200 6456304
15:00:16 75615 8192000000 10913813920 11398909952 1972145104 19.402 99 19 38 1 2004045904 26116944 5783856
15:02:33 70747 8192000000 10905723936 11376807936 1984607904 19.501 100 12 24 1 1998437968 10503872 3326192
15:04:44 48389 8192000000 10884226464 11357675520 1965017728 19.346 99 11 22 0 1974102848 6049760 3035360
15:06:33 58434 8192000000 10915335056 11390693376 1988788512 19.534 100 14 28 0 2006188608 13255328 4144768
15:08:34 75421 8192000000 10915311856 11395727360 1999145408 19.616 100 21 42 2 2015632832 10608112 5879312
15:10:49 62514 8192000000 10909997504 11396898816 1983116688 19.489 99 19 38 1 1996965968 8451392 5397888
15:12:52 57811 8192000000 10910045712 11391279104 1979016880 19.457 99 22 44 2 1995939568 9791728 7130960
15:14:50 53893 8192000000 10931545920 11406827520 1989203280 19.537 99 28 56 1 2007607728 10776992 7627456
15:16:46 72579 8192000000 10951859216 11428745216 2017401776 19.760 100 18 36 1 2033940272 11055360 5483136
15:19:08 64993 8192000000 10944840576 11421208576 1993088576 19.568 99 19 38 1 2015365392 16811920 5464896
15:21:17 77012 8192000000 10900094880 11364818944 1832133328 18.277 91 15 30 1 1847648640 11358416 4156896
15:23:34 51159 8192000000 10857072640 11315216384 1805612800 18.060 92 17 34 0 1820110592 9684208 4813584
15:25:43 69580 8192000000 10851369056 11325431808 1869062208 18.577 96 23 46 1 1890851360 14122208 7666944
15:28:28 59815 8192000000 10859714160 11338014720 1912846432 18.929 98 16 30 2 1927182048 10382032 3953584
15:30:34 66710 8192000000 10873982864 11350183936 1873599792 18.613 96 9 18 0 1889668304 13663328 2405184
15:32:41 39171 8192000000 10842685520 11305959424 1870977776 18.592 97 12 24 1 1881715712 7551056 3186880
15:34:20 64761 8192000000 10829266784 11285561344 1819721472 18.175 95 25 50 2 1834750432 8011968 7016992
15:36:26 54358 8192000000 10863130864 11321327616 1893310528 18.772 98 29 58 1 1911508368 10057872 8139968
15:38:26 30118 8192000000 10843819584 11297918976 1864358432 18.539 97 36 72 0 1885825712 11390528 10076752
15:39:57 56453 8192000000 10866234256 11336835072 1900164400 18.828 98 28 56 1 1927943616 19644400 8134816
15:41:57 118136 8192000000 10875461584 11326365696 1885033600 18.706 97 16 32 1 1904634752 15038912 4562240
15:44:59 78629 8192000000 10862677696 11326709760 1918622432 18.976 99 14 28 0 1931627024 8848816 4155776
15:48:03 43862 8192000000 10880057424 11342974976 1931988832 19.083 99 23 46 3 1950531456 11599456 6943168
15:49:54 65911 8192000000 10868766368 11346296832 1941533264 19.159 99 12 24 1 1954611088 9844304 3233520
15:52:00 88324 8192000000 10911100640 11377442816 1955713616 19.272 99 19 38 1 1980390432 18405008 6271808
15:54:30 50192 8192000000 10921737632 11391057920 1986408832 19.515 100 27 53 1 2019224080 24482848 8332400
15:56:21 97010 8192000000 10901900384 11390427136 1956822368 19.281 99 12 25 0 1985466496 24945904 3698224
15:58:59 116329 8192000000 10915086784 11384774656 1951273360 19.237 98 9 18 1 1962671424 9164880 2233184
16:02:06 29868 8192000000 10924975568 11380989952 1954327200 19.261 98 48 95 1 1977093296 9955424 12810672
16:03:37 82035 8192000000 10916608544 11395600384 1927476336 19.047 97 10 20 0 1947814832 17624992 2713504
16:05:59 44060 8192000000 10912890576 11375996928 1933142608 19.092 97 25 50 1 1948455344 8501104 6811632
16:07:46 61839 8192000000 10886477392 11360182272 1907830256 18.889 97 10 20 0 1922494208 12011840 2652112
16:09:49 135290 8192000000 10868904960 11325165568 1844245248 18.375 94 13 26 2 1861502720 13980160 3277312
16:13:07 66286 8192000000 10882374208 11351023616 1948476096 19.214 100 16 30 2 1963881072 10921920 4483056
16:15:17 61469 8192000000 10857038608 11332182016 1871153136 18.594 96 7 14 0 1888522256 15611680 1757440
16:17:34 41870 8192000000 10866545328 11344547840 1927479344 19.047 99 20 41 1 1942255312 9284976 5490992
16:19:46 39616 8192000000 10866184480 11321171968 1858655152 18.492 95 21 40 0 1870885344 6298928 5931264
16:21:27 39818 8192000000 10842892608 11305521152 1875963968 18.633 97 26 53 0 1892847424 9412560 7470896
16:23:09 41132 8192000000 10822904688 11271446528 1840616064 18.346 97 19 38 2 1854641664 8661392 5364208
16:24:58 56324 8192000000 10848710304 11310891008 1879672656 18.662 98 18 36 0 1894877456 10427440 4777360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2020 16:28:23 to 19-10-2020 16:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 133 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2020 16:28:39 to 19-10-2020 16:28:41.

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          249466
MON$OLDEST_ACTIVE               252071
MON$OLDEST_SNAPSHOT             252071
MON$NEXT_TRANSACTION            252071
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                       2039456
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.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:03 2020
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:133: 
:134: 
:135: mscs-test-161.budzdorov.ru    Mon Oct 19 16:27:12 2020
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
--- end of diff output ---

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

19.10.20 16:28:42. 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-19 16:28:44 No severe PSQL-related problems occured
19.10.20 16:28:45. End of report.