Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 01: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 17379388 kB
MemAvailable 31542240 kB
Buffers 0 kB
Cached 14341464 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-31 01:26:57
DTS_END                         2020-10-31 01:26:57.4360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/47878
TRN_ID                          243212
ATT_ID                          704
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: 9289.43 19407 1114732
customer order: creation 171.88 359 20626
customer order: refuse 14.48 385 1737
order to supplier: creation 44.88 1374 5385
order to supplier: removal 3.51 105204 421
invoice (draft): creation 46.93 3192 5632
invoice (draft): removal 54.69 803 6563
invoice accept: apply 41.67 17197 5000
invoice accept: cancel 48.83 728 5859
customer reserve: creation 3815.48 171 457858
customer reserve: removal 4743.65 60 569238
realization accept: apply 84.06 176 10087
realization accept: cancel 87.92 113 10550
payment from customer: creation 61.03 104 7324
payment from customer: removal 5.53 92 664
payment to supplier: creation 48.89 117 5867
payment to supplier: removal 3.71 87 445
service: total inventory turnovers 4.48 1280 538
service: total monetary turnovers 6.90 28 828
service: aggregate perf. data 0.88 19672 106
service: refresh index statistics 0.03 237004 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2020 01:28:35 to 31-10-2020 01:28:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2137
TEST_TIME 2 2163
TEST_TIME 3 2285
TEST_TIME 4 4947
TEST_TIME 5 5028
TEST_TIME 6 5048
TEST_TIME 7 5116
TEST_TIME 8 5367
TEST_TIME 9 5588
TEST_TIME 10 5488
TEST_TIME 11 5604
TEST_TIME 12 5765
TEST_TIME 13 6238
TEST_TIME 14 6423
TEST_TIME 15 6280
TEST_TIME 16 6304
TEST_TIME 17 6584
TEST_TIME 18 6777
TEST_TIME 19 6852
TEST_TIME 20 6892
TEST_TIME 21 7045
TEST_TIME 22 7274
TEST_TIME 23 7770
TEST_TIME 24 8060
TEST_TIME 25 8298
TEST_TIME 26 8465
TEST_TIME 27 8505
TEST_TIME 28 8650
TEST_TIME 29 9681
TEST_TIME 30 10451
TEST_TIME 31 10306
TEST_TIME 32 10160
TEST_TIME 33 10235
TEST_TIME 34 10376
TEST_TIME 35 10390
TEST_TIME 36 10495
TEST_TIME 37 10475
TEST_TIME 38 10403
TEST_TIME 39 10276
TEST_TIME 40 10179
TEST_TIME 41 10083
TEST_TIME 42 9971
TEST_TIME 43 9822
TEST_TIME 44 9770
TEST_TIME 45 9734
TEST_TIME 46 9705
TEST_TIME 47 9822
TEST_TIME 48 9721
TEST_TIME 49 9795
TEST_TIME 50 9698
TEST_TIME 51 9584
TEST_TIME 52 9568
TEST_TIME 53 9508
TEST_TIME 54 9676
TEST_TIME 55 9612
TEST_TIME 56 9504
TEST_TIME 57 9413
TEST_TIME 58 9327
TEST_TIME 59 9329
TEST_TIME 60 9335
TEST_TIME 61 9356
TEST_TIME 62 9341
TEST_TIME 63 9334
TEST_TIME 64 9350
TEST_TIME 65 9426
TEST_TIME 66 9526
TEST_TIME 67 9505
TEST_TIME 68 9519
TEST_TIME 69 9580
TEST_TIME 70 9526
TEST_TIME 71 9605
TEST_TIME 72 9686
TEST_TIME 73 9699
TEST_TIME 74 9700
TEST_TIME 75 9677
TEST_TIME 76 9555
TEST_TIME 77 9454
TEST_TIME 78 9451
TEST_TIME 79 9409
TEST_TIME 80 9351
TEST_TIME 81 9322
TEST_TIME 82 9253
TEST_TIME 83 9290
TEST_TIME 84 9257
TEST_TIME 85 9268
TEST_TIME 86 9279
TEST_TIME 87 9277
TEST_TIME 88 9251
TEST_TIME 89 9311
TEST_TIME 90 9384
TEST_TIME 91 9393
TEST_TIME 92 9356
TEST_TIME 93 9343
TEST_TIME 94 9368
TEST_TIME 95 9399
TEST_TIME 96 9498
TEST_TIME 97 9438
TEST_TIME 98 9383
TEST_TIME 99 9362
TEST_TIME 100 9348
TEST_TIME 101 9278
TEST_TIME 102 9243
TEST_TIME 103 9225
TEST_TIME 104 9199
TEST_TIME 105 9259
TEST_TIME 106 9249
TEST_TIME 107 9239
TEST_TIME 108 9238
TEST_TIME 109 9202
TEST_TIME 110 9268
TEST_TIME 111 9344
TEST_TIME 112 9342
TEST_TIME 113 9322
TEST_TIME 114 9317
TEST_TIME 115 9333
TEST_TIME 116 9356
TEST_TIME 117 9326
TEST_TIME 118 9412
TEST_TIME 119 9340
TEST_TIME 120 9291

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-10-2020 01:28:38 to 31-10-2020 01:28:42.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1540358 1540358 0 0.00 0 22103 6 0 0
doc_list_biud 1540358 1540358 0 0.00 0 89133 28 0 0
sp_add_invoice_to_stock 5157 5000 157 3.04 45 160391 17197 0 157
sp_cancel_adding_invoice 5859 5859 0 0.00 3 53996 729 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 12828 385 0 0
sp_cancel_customer_reserve 569238 569238 0 0.00 1 89136 61 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 8221 92 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 3769 87 0 0
sp_cancel_supplier_invoice 6569 6563 6 0.09 2 17218 803 0 6
sp_cancel_supplier_order 421 421 0 0.00 6 1047420 105204 0 0
sp_cancel_write_off 10550 10550 0 0.00 1 22104 114 0 0
sp_client_order 20626 20626 0 0.00 3 87505 360 0 0
sp_customer_reserve 457858 457858 0 0.00 0 63512 171 0 0
sp_fill_shopping_cart 31643 31643 0 0.00 1 11059 41 0 0
sp_fill_shopping_cart_clo_res 457855 457855 0 0.00 0 19053 12 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 22935 312 0 0
sp_kill_cost_storno 17518 17518 0 0.00 0 22102 57 0 0
sp_kill_qstorno_ret_qs2qd 1156010 1156010 0 0.00 0 89030 33 0 0
sp_kill_qty_storno 609452 609452 0 0.00 0 89132 71 0 0
sp_lock_selected_doc 20880 20880 0 0.00 0 8207 4 0 0
sp_make_cost_storno 28275 28275 0 0.00 0 21871 51 0 0
sp_multiply_rows_for_pdistr 28275 28275 0 0.00 0 9834 29 0 0
sp_multiply_rows_for_qdistr 20626 20626 0 0.00 0 87495 170 0 0
sp_pay_from_customer 7324 7324 0 0.00 4 10119 104 0 0
sp_pay_to_supplier 5867 5867 0 0.00 5 19888 117 0 0
sp_payment_common 13191 13191 0 0.00 2 10068 32 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 12817 361 0 0
sp_qd_handle_on_invoice_upd_sts 10856 10856 0 0.00 0 21765 320 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 22883 63 0 0
sp_reserve_write_off 10087 10087 0 0.00 6 22910 177 0 0
sp_supplier_invoice 5632 5632 0 0.00 7 87757 3192 0 0
sp_supplier_order 5385 5385 0 0.00 4 25071 1374 0 0
srv_aggregate_perf_data 106 106 0 0.00 2975 194545 19672 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2360 143876 48144 0 0
srv_find_qd_qs_mism 1067756 1067756 0 0.00 0 16519 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 39 68928 1280 0 0
srv_make_money_saldo 828 828 0 0.00 1 1294 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 153833 399897 237005 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5000 5000 0 0.00 0 31464 165 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 11994 99 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 6674 18 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1822 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 6655 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 6921 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1155 15 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 47839 1573 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 6230 19 0 0
t$perf-norm:sp_client_order 20626 20626 0 0.00 0 23886 23 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 17062 22 0 0
t$perf-norm:sp_pay_from_customer 7324 7324 0 0.00 0 3717 8 0 0
t$perf-norm:sp_pay_to_supplier 5867 5867 0 0.00 0 6236 10 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 5573 11 0 0
t$perf-norm:sp_supplier_invoice 5632 5632 0 0.00 0 23907 29 0 0
t$perf-norm:sp_supplier_order 5385 5385 0 0.00 0 2375 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 286 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1024 17 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 1828 26 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 2994 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10306 9992 314 3.04 0 4338 18 0 314
v_all_customers 38046 38046 0 0.00 0 134 1 0 0
v_all_suppliers 30612 30612 0 0.00 0 28 1 0 0
v_all_wares 41252 41252 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 623 7 0 0
v_cancel_client_order 3474 3474 0 0.00 0 2550 5 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2427 8 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 3676 10 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 3758 11 0 0
v_cancel_supplier_invoice 832 820 12 1.44 0 628 15 0 12
v_cancel_supplier_order 842 842 0 0.00 0 948 6 0 0
v_cancel_write_off 2020 2020 0 0.00 0 4746 17 0 0
v_max_id_clo_ord 5385 5385 0 0.00 0 682 1 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 2752 13 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 2870 2 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 2370 6 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 1909 4 0 0
v_min_id_clo_ord 5385 5385 0 0.00 0 5190 10 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 7737 15 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 6863 6 0 0
v_min_non_paid_invoice 5867 5867 0 0.00 0 9806 20 0 0
v_min_non_paid_realizn 7324 7324 0 0.00 0 9846 20 0 0
v_our_firm 10378 10378 0 0.00 0 145 1 0 0
v_reserve_write_off 20174 20174 0 0.00 0 4759 6 0 0
x_lock_depdocs_on_canc_invoice 12012 12012 0 0.00 0 13762 113 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 9227 379 0 0
x_make_qstorno_1000_1200 5385 5385 0 0.00 1 25064 1289 0 0
x_make_qstorno_1200_2000 5632 5632 0 0.00 3 87676 3047 0 0
x_make_qstorno_2100_3300 457858 457858 0 0.00 0 63375 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 01:28:43 to 31-10-2020 01:28:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
22:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:02 27915 8192000000 10352629296 10671546368 1400494336 14.599 100 89 178 0 1435489888 5944800 29050752
22:59:34 14541 8192000000 10422875312 10750357504 1477021808 15.275 100 92 184 1 1514916080 8097360 29796912
23:00:57 21185 8192000000 10489919616 10832670720 1534658576 15.777 100 84 168 2 1570089296 9326320 26104400
23:02:20 14064 8192000000 10567505360 10924572672 1625085088 16.553 100 82 164 2 1660185136 10760448 24339600
23:03:36 7657 8192000000 10632693040 11000774656 1692159920 17.119 100 82 165 3 1727797216 12114784 23522512
23:04:45 4271 8192000000 10700748096 11083640832 1764746432 17.724 100 71 140 1 1794956400 10750816 19459152
23:05:51 5059 8192000000 10765604160 11148419072 1815708688 18.143 100 79 157 1 1850368032 13202848 21456496
23:06:57 2270 8192000000 10811770624 11199889408 1869020832 18.576 100 90 181 0 1907564336 12347344 26196160
23:08:00 38864 8192000000 10798524320 11212161024 1877806960 18.647 100 38 76 1 1896929264 7192768 11929536
23:09:40 5729 8192000000 10801133600 11228041216 1892901584 18.769 100 37 73 4 1909897824 6571504 10424736
23:10:46 3762 8192000000 10815232240 11243077632 1898290432 18.813 100 34 67 0 1915268832 7289424 9688976
23:11:51 8822 8192000000 10812454608 11247964160 1904365840 18.861 100 28 56 3 1922876784 11060000 7450944
23:13:01 56431 8192000000 10854432320 11264819200 1914016352 18.939 100 42 85 1 1932036080 5062112 12957616
23:14:58 5206 8192000000 10834624144 11268521984 1922123728 19.004 100 35 70 3 1939649584 7511792 10014064
23:16:04 5728 8192000000 10812003168 11240251392 1893923664 18.777 100 22 44 1 1905709456 5709328 6076464
23:17:10 7659 8192000000 10760856016 11183685632 1801070608 18.023 97 19 38 0 1812169696 5733424 5365664
23:18:21 40766 8192000000 10701130560 11115458560 1588273904 16.239 86 20 40 0 1599061952 4338064 6449984
23:20:07 5429 8192000000 10709859792 11115397120 1762892944 17.708 98 26 53 2 1776475616 5849728 7732944
23:21:16 10909 8192000000 10754195424 11168047104 1829377152 18.254 100 35 69 0 1845445296 5419216 10648928
23:22:27 16885 8192000000 10750865904 11182309376 1846639712 18.395 100 32 64 0 1862116864 6064048 9413104
23:23:46 17173 8192000000 10761913184 11196362752 1854699568 18.460 100 38 76 1 1872863056 7211104 10952384
23:25:06 5016 8192000000 10781103792 11213709312 1868934448 18.576 100 29 58 3 1883932144 7205648 7792048
23:26:12 9811 8192000000 10800304416 11231592448 1878264048 18.651 100 24 48 2 1894851024 9869136 6717840
23:27:35 2360 8192000000 10844919936 11258568704 1909107040 18.899 100 54 105 3 1930717776 6436896 15173840
23:28:41 8805 8192000000 10864729504 11286073344 1931827424 19.081 100 54 108 0 1957365488 10247040 15291024
23:29:53 57658 8192000000 10888858016 11319271424 1942452608 19.166 100 36 71 2 1966123936 12735984 10935344
23:31:55 11445 8192000000 10912665968 11335401472 1966825024 19.360 100 67 134 1 2001532336 15346992 19360320
23:33:10 11139 8192000000 10920827344 11339968512 1973029776 19.409 100 70 140 0 2006641040 13464560 20146704
23:34:29 4844 8192000000 10926656000 11363631104 1985013696 19.504 100 49 97 1 2019034880 19697168 14324016
23:35:49 15064 8192000000 10915213184 11352727552 1979783984 19.463 100 49 98 1 2012372112 17861760 14726368
23:37:11 13850 8192000000 10942539216 11378503680 1996238224 19.593 100 45 87 6 2029466512 19780160 13448128
23:38:30 2980 8192000000 10952732848 11376734208 1989050304 19.536 100 58 116 1 2033122576 26150208 17922064
23:39:37 3870 8192000000 10951602544 11372642304 1991333264 19.554 100 70 136 2 2033449072 21020848 21094960
23:40:46 14363 8192000000 10944190912 11388223488 1988695264 19.533 99 44 85 2 2024755328 22568976 13491088
23:42:05 3292 8192000000 10971836304 11397165056 1988209024 19.530 99 72 143 2 2043165504 33065008 21891472
23:43:14 10148 8192000000 10924310400 11347226624 1933706544 19.097 98 50 100 0 1986452624 36954992 15791088
23:44:31 6339 8192000000 10940998832 11357347840 1951079968 19.235 99 68 133 2 2011403136 39034224 21288944
23:45:45 2473 8192000000 10983829664 11387813888 1989753648 19.542 100 89 178 1 2057914784 41472688 26688448
23:46:52 2576 8192000000 10970539200 11384012800 1973873248 19.416 100 84 168 4 2051447584 51772272 25802064
23:48:01 2970 8192000000 10962243392 11383492608 1970513840 19.390 100 76 149 1 2050270848 56348992 23408016
23:49:10 20384 8192000000 10963066528 11400269824 1976600608 19.438 100 44 88 1 2055790224 63659584 15530032
23:50:35 4755 8192000000 10946217280 11373363200 1955104032 19.267 100 55 111 2 2031329088 58633104 17591952
23:51:43 25214 8192000000 10940301808 11379191808 1949120688 19.219 99 32 64 1 2011905488 51904624 10880176
23:53:28 39768 8192000000 10899108448 11350212608 1892070112 18.762 97 23 46 3 1956745584 56575136 8100336
23:55:10 36056 8192000000 10892727872 11378388992 1903624656 18.855 99 18 36 2 1972720224 62257344 6838224
23:57:21 33968 8192000000 10872048208 11308744704 1918563408 18.975 99 43 86 1 1940281920 9379984 12338528
23:58:57 35425 8192000000 10886569344 11336577024 1943388256 19.174 100 10 20 0 1958253840 12051280 2814304
00:00:39 80945 8192000000 10897089376 11369021440 1942949328 19.170 99 7 14 0 1963546432 18929840 1667264
00:03:01 77600 8192000000 10912360976 11377967104 1941222400 19.157 98 9 18 1 1958485936 15237920 2025616
00:05:33 111920 8192000000 10903304400 11361107968 1931090176 19.076 97 27 53 0 1949895216 11241600 7563440
00:08:33 110778 8192000000 10915433104 11388157952 1960943968 19.314 98 30 58 1 1981507344 12258624 8304752
00:11:27 40236 8192000000 10930440480 11388608512 1962529600 19.326 98 34 68 1 1989569200 16399024 10640576
00:13:16 19691 8192000000 10904353536 11386028032 1980988896 19.473 100 31 60 3 2003001040 12821696 9190448
00:14:43 64767 8192000000 10916874608 11394715648 1981920112 19.480 100 13 26 0 2006598848 20320192 4358544
00:16:54 65918 8192000000 10933875952 11408842752 1995092432 19.584 100 21 42 1 2013315984 11654704 6568848
00:19:53 50298 8192000000 10916085504 11401973760 1989537680 19.540 100 16 33 0 2016301408 21676656 5087072
00:21:46 35103 8192000000 10902179184 11388661760 1985651120 19.509 100 9 18 1 2000327568 12253328 2423120
00:23:21 60756 8192000000 10895814432 11377770496 1956445952 19.278 98 19 36 3 1967957120 6385840 5125328
00:25:27 84873 8192000000 10905921408 11390402560 1966961376 19.361 99 7 14 1 1977232336 8668240 1602720
00:27:53 45648 8192000000 10900785392 11374288896 1935928832 19.114 97 22 44 1 1950077072 8112272 6035968
00:29:39 60849 8192000000 10892585440 11370467328 1931130448 19.076 98 16 32 2 1947977424 11976928 4870048
00:31:53 34378 8192000000 10888271712 11352977408 1890856336 18.753 96 16 32 0 1907326688 11301408 5168944
00:33:42 20588 8192000000 10858140736 11311067136 1902967104 18.850 98 32 64 2 1919277296 7426048 8884144
00:35:08 78415 8192000000 10857450608 11317153792 1873934336 18.616 97 18 36 0 1888458880 9764800 4759744
00:37:32 59920 8192000000 10856779328 11337297920 1904296912 18.861 98 14 28 3 1926469024 18155008 4017104
00:39:33 50894 8192000000 10890766224 11356930048 1935357584 19.110 99 15 30 0 1955547024 16316864 3872576
00:41:25 143875 8192000000 10820450848 11272323072 1869222880 18.578 96 15 30 1 1891134592 17470944 4440768
00:44:50 59167 8192000000 10807696640 11277733888 1846266560 18.392 96 15 29 2 1856632064 6560912 3804592
00:46:56 69236 8192000000 10844342512 11305832448 1892377808 18.765 98 15 30 2 1906509072 9428336 4702928
00:49:06 58962 8192000000 10840753008 11311570944 1884534480 18.702 98 12 24 0 1895951088 8295440 3121168
00:51:12 36635 8192000000 10851547472 11319992320 1914833120 18.945 99 31 62 0 1932193488 8761616 8598752
00:52:49 42556 8192000000 10858288400 11327655936 1935407168 19.110 100 23 46 2 1951775216 9288304 7079744
00:54:33 63639 8192000000 10863145440 11339714560 1910671968 18.912 98 13 26 0 1923788384 9746592 3369824
00:56:39 81214 8192000000 10889628336 11354456064 1939629200 19.144 99 7 14 0 1956674560 15156144 1889216
00:59:05 80997 8192000000 10902905952 11366313984 1931715120 19.081 98 27 54 0 1951026416 11492704 7818592
01:01:27 107400 8192000000 10896199088 11368656896 1921691904 19.000 97 23 45 0 1951666864 24231392 5743568
01:04:14 77222 8192000000 10888367152 11352215552 1940656048 19.152 98 29 58 1 1959515600 10889056 7970496
01:07:18 68756 8192000000 10879513728 11356549120 1945435104 19.190 99 10 20 0 1962647616 14448608 2763904
01:09:27 82257 8192000000 10912335552 11391459328 1959689424 19.304 99 13 28 1 1978131488 15256352 3185712
01:11:50 48551 8192000000 10876787632 11345264640 1893319696 18.773 96 19 38 0 1905646208 7446768 4879744
01:13:43 63214 8192000000 10878606864 11349172224 1897952416 18.810 97 21 43 2 1916510512 12526960 6031136
01:16:00 78303 8192000000 10890504416 11380289536 1913668976 18.936 97 16 32 2 1934854160 16488528 4696656
01:18:18 62587 8192000000 10887852384 11334062080 1884409712 18.701 96 23 44 0 1900875072 9574304 6891056
01:20:21 73426 8192000000 10879300288 11342270464 1909618752 18.904 98 33 66 1 1936491392 16643760 10228880
01:22:39 72647 8192000000 10893439952 11365040128 1900041632 18.827 97 13 27 1 1919262288 15845536 3375120
01:24:54 138950 8192000000 9595332768 9919348736 742233136 8.307 38 7 12 1 748623680 4822576 1567968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-10-2020 01:28:49 to 31-10-2020 01:29:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 157 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243516
MON$OLDEST_ACTIVE               243517
MON$OLDEST_SNAPSHOT             243517
MON$NEXT_TRANSACTION            243517
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                       2038880
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 ---
--- end of diff output ---

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

31.10.20 01:29:07. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-31 01:29:10 No severe PSQL-related problems occured
31.10.20 01:29:11. End of report.