Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2020 16: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 17534300 kB
MemAvailable 31569432 kB
Buffers 0 kB
Cached 14201200 kB
SwapCached 3044 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-26 16:26:58
DTS_END                         2020-10-26 16:26:58.0130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/41758
TRN_ID                          250734
ATT_ID                          654
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: 9897.97 20688 1187756
customer order: creation 177.86 328 21343
customer order: refuse 14.83 242 1779
order to supplier: creation 47.95 1194 5754
order to supplier: removal 3.63 85348 436
invoice (draft): creation 48.37 2928 5804
invoice (draft): removal 56.69 683 6803
invoice accept: apply 43.75 17054 5250
invoice accept: cancel 51.07 653 6128
customer reserve: creation 4123.11 167 494773
customer reserve: removal 5021.12 48 602534
realization accept: apply 88.83 157 10659
realization accept: cancel 84.61 94 10153
payment from customer: creation 62.98 101 7558
payment from customer: removal 5.85 64 702
payment to supplier: creation 51.41 101 6169
payment to supplier: removal 3.67 63 440
service: total inventory turnovers 4.85 922 582
service: total monetary turnovers 6.58 45 789
service: aggregate perf. data 0.80 21325 96
service: refresh index statistics 0.03 282252 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2020 16:29:01 to 26-10-2020 16:29:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 791
TEST_TIME 2 2213
TEST_TIME 3 4324
TEST_TIME 4 4302
TEST_TIME 5 4825
TEST_TIME 6 5368
TEST_TIME 7 5011
TEST_TIME 8 5141
TEST_TIME 9 5447
TEST_TIME 10 5722
TEST_TIME 11 6051
TEST_TIME 12 6322
TEST_TIME 13 6750
TEST_TIME 14 7689
TEST_TIME 15 7988
TEST_TIME 16 8573
TEST_TIME 17 9115
TEST_TIME 18 9028
TEST_TIME 19 9832
TEST_TIME 20 10559
TEST_TIME 21 11271
TEST_TIME 22 12164
TEST_TIME 23 12389
TEST_TIME 24 12405
TEST_TIME 25 12171
TEST_TIME 26 11894
TEST_TIME 27 11521
TEST_TIME 28 11495
TEST_TIME 29 11321
TEST_TIME 30 11309
TEST_TIME 31 11097
TEST_TIME 32 11283
TEST_TIME 33 11151
TEST_TIME 34 10969
TEST_TIME 35 10859
TEST_TIME 36 10884
TEST_TIME 37 10810
TEST_TIME 38 10906
TEST_TIME 39 10861
TEST_TIME 40 10827
TEST_TIME 41 10947
TEST_TIME 42 10907
TEST_TIME 43 10829
TEST_TIME 44 10850
TEST_TIME 45 10816
TEST_TIME 46 10891
TEST_TIME 47 11117
TEST_TIME 48 10980
TEST_TIME 49 10818
TEST_TIME 50 10734
TEST_TIME 51 10579
TEST_TIME 52 10675
TEST_TIME 53 10577
TEST_TIME 54 10482
TEST_TIME 55 10447
TEST_TIME 56 10423
TEST_TIME 57 10364
TEST_TIME 58 10460
TEST_TIME 59 10443
TEST_TIME 60 10465
TEST_TIME 61 10419
TEST_TIME 62 10417
TEST_TIME 63 10462
TEST_TIME 64 10517
TEST_TIME 65 10480
TEST_TIME 66 10571
TEST_TIME 67 10589
TEST_TIME 68 10705
TEST_TIME 69 10680
TEST_TIME 70 10619
TEST_TIME 71 10654
TEST_TIME 72 10658
TEST_TIME 73 10537
TEST_TIME 74 10546
TEST_TIME 75 10455
TEST_TIME 76 10488
TEST_TIME 77 10426
TEST_TIME 78 10347
TEST_TIME 79 10310
TEST_TIME 80 10217
TEST_TIME 81 10242
TEST_TIME 82 10291
TEST_TIME 83 10261
TEST_TIME 84 10274
TEST_TIME 85 10338
TEST_TIME 86 10308
TEST_TIME 87 10295
TEST_TIME 88 10398
TEST_TIME 89 10366
TEST_TIME 90 10385
TEST_TIME 91 10422
TEST_TIME 92 10391
TEST_TIME 93 10453
TEST_TIME 94 10350
TEST_TIME 95 10273
TEST_TIME 96 10233
TEST_TIME 97 10224
TEST_TIME 98 10167
TEST_TIME 99 10140
TEST_TIME 100 10090
TEST_TIME 101 10044
TEST_TIME 102 10003
TEST_TIME 103 9999
TEST_TIME 104 9969
TEST_TIME 105 9931
TEST_TIME 106 9959
TEST_TIME 107 9956
TEST_TIME 108 9936
TEST_TIME 109 9917
TEST_TIME 110 9967
TEST_TIME 111 9977
TEST_TIME 112 9945
TEST_TIME 113 9967
TEST_TIME 114 9963
TEST_TIME 115 9958
TEST_TIME 116 9982
TEST_TIME 117 10025
TEST_TIME 118 9946
TEST_TIME 119 9883
TEST_TIME 120 9903

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2020 16:29:04 to 26-10-2020 16:29:08.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1645330 1645330 0 0.00 0 19420 6 0 0
doc_list_biud 1645329 1645329 0 0.00 0 21309 22 0 0
sp_add_invoice_to_stock 5354 5250 104 1.94 22 211693 17054 0 104
sp_cancel_adding_invoice 6128 6128 0 0.00 4 72311 653 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 8257 242 0 0
sp_cancel_customer_reserve 602534 602534 0 0.00 1 21311 48 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 6602 64 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 4670 63 0 0
sp_cancel_supplier_invoice 6810 6803 7 0.10 1 18595 684 0 7
sp_cancel_supplier_order 436 436 0 0.00 5 945125 85348 0 0
sp_cancel_write_off 10153 10153 0 0.00 1 9244 95 0 0
sp_client_order 21343 21343 0 0.00 3 21475 328 0 0
sp_customer_reserve 494773 494773 0 0.00 0 101610 167 0 0
sp_fill_shopping_cart 32899 32899 0 0.00 1 7776 33 0 0
sp_fill_shopping_cart_clo_res 494768 494768 0 0.00 0 13469 13 0 0
sp_get_clo_for_invoice 5241 5241 0 0.00 0 15821 250 0 0
sp_kill_cost_storno 17423 17423 0 0.00 0 12145 43 0 0
sp_kill_qstorno_ret_qs2qd 1222460 1222460 0 0.00 0 21304 26 0 0
sp_kill_qty_storno 643730 643730 0 0.00 0 21309 57 0 0
sp_lock_selected_doc 21858 21858 0 0.00 0 8036 8 0 0
sp_make_cost_storno 29624 29624 0 0.00 0 19367 45 0 0
sp_multiply_rows_for_pdistr 29624 29624 0 0.00 0 9658 24 0 0
sp_multiply_rows_for_qdistr 21343 21343 0 0.00 0 21434 150 0 0
sp_pay_from_customer 7558 7558 0 0.00 4 12157 102 0 0
sp_pay_to_supplier 6169 6169 0 0.00 5 10549 102 0 0
sp_payment_common 13727 13727 0 0.00 1 10480 25 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 8249 217 0 0
sp_qd_handle_on_invoice_upd_sts 11366 11366 0 0.00 0 16371 275 0 0
sp_qd_handle_on_reserve_upd_sts 10659 10659 0 0.00 0 15567 51 0 0
sp_reserve_write_off 10659 10659 0 0.00 6 16515 157 0 0
sp_supplier_invoice 5804 5804 0 0.00 7 82995 2929 0 0
sp_supplier_order 5754 5754 0 0.00 5 35085 1194 0 0
srv_aggregate_perf_data 96 96 0 0.00 1932 332233 21325 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2205 138664 51867 0 0
srv_find_qd_qs_mism 1137097 1137097 0 0.00 0 9747 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 23 27865 922 0 0
srv_make_money_saldo 789 789 0 0.00 1 12220 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 229850 322981 282253 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 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5250 5250 0 0.00 0 11951 159 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 9933 123 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 1765 8 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 3168 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1604 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 3826 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 297 6 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 35405 1311 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 3770 8 0 0
t$perf-norm:sp_client_order 21343 21343 0 0.00 0 8175 15 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 3902 8 0 0
t$perf-norm:sp_pay_from_customer 7558 7558 0 0.00 0 10341 8 0 0
t$perf-norm:sp_pay_to_supplier 6169 6169 0 0.00 0 7928 11 0 0
t$perf-norm:sp_reserve_write_off 10659 10659 0 0.00 0 9123 12 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 7542 22 0 0
t$perf-norm:sp_supplier_order 5754 5754 0 0.00 0 4802 12 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 1657 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 4825 91 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1001 17 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 1578 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10682 10474 208 1.94 0 5744 16 0 208
v_all_customers 39384 39384 0 0.00 0 15 0 0 0
v_all_suppliers 32378 32378 0 0.00 0 20 1 0 0
v_all_wares 42686 42686 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 2916 10 0 0
v_cancel_client_order 3558 3558 0 0.00 0 2061 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 6564 9 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 788 3 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 806 4 0 0
v_cancel_supplier_invoice 880 866 14 1.59 0 482 13 0 14
v_cancel_supplier_order 872 872 0 0.00 0 3857 11 0 0
v_cancel_write_off 2010 2010 0 0.00 0 1443 14 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 241 1 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 3098 11 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 6520 6 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 748 3 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 2420 4 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 7381 7 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 3795 8 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 6037 6 0 0
v_min_non_paid_invoice 6169 6169 0 0.00 0 10476 17 0 0
v_min_non_paid_realizn 7558 7558 0 0.00 0 6546 13 0 0
v_our_firm 10546 10546 0 0.00 0 16 1 0 0
v_reserve_write_off 21316 21316 0 0.00 0 6782 6 0 0
x_lock_depdocs_on_canc_invoice 12498 12498 0 0.00 0 16314 111 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 23645 355 0 0
x_make_qstorno_1000_1200 5754 5754 0 0.00 2 35074 1125 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 3 82985 2817 0 0
x_make_qstorno_2100_3300 494773 494773 0 0.00 0 101609 152 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
13:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:03 32397 8192000000 10356945344 10673217536 1403617872 14.627 100 89 178 0 1439352960 6239584 29495504
13:59:42 21616 8192000000 10434323520 10767220736 1485763600 15.352 100 87 174 0 1522651360 8898192 27989568
14:01:11 8591 8192000000 10499439792 10848874496 1562004704 16.013 100 81 162 0 1597208800 10364528 24839568
14:02:23 8632 8192000000 10566640800 10930012160 1629196784 16.588 100 76 150 2 1662849056 11336080 22316192
14:03:35 7828 8192000000 10655365392 11029524480 1716696464 17.325 100 78 156 3 1750705248 11621760 22387024
14:04:46 3618 8192000000 10730659664 11107958784 1788195200 17.917 100 89 177 0 1826557568 13132336 25230032
14:05:51 3500 8192000000 10760169904 11144724480 1817432560 18.157 100 85 168 3 1854019968 13450368 23137040
14:06:56 1231 8192000000 10810718816 11199201280 1866965856 18.560 100 91 183 0 1906553072 13958880 25628336
14:07:58 30074 8192000000 10819376912 11214585856 1874332640 18.619 100 60 121 2 1898824976 6492224 18000112
14:09:28 7284 8192000000 10797213760 11227684864 1883359936 18.692 100 17 34 0 1894587312 6380864 4846512
14:10:38 13830 8192000000 10797656784 11237326848 1893814096 18.777 100 15 30 0 1903370928 5593024 3963808
14:11:55 15360 8192000000 10802814384 11243270144 1899033632 18.819 100 23 45 1 1911089056 4782176 7273248
14:13:12 6244 8192000000 10816710976 11256750080 1907521232 18.887 100 25 50 1 1921220240 6400944 7298064
14:14:21 5347 8192000000 10824903968 11259600896 1914250080 18.941 100 30 60 2 1929732688 7229408 8253200
14:15:28 46988 8192000000 10813527936 11228925952 1836341568 18.311 96 32 64 2 1852424464 6211632 9871264
14:17:16 6156 8192000000 10737132016 11155988480 1817344544 18.156 99 18 36 2 1827581952 5206496 5030912
14:18:23 11529 8192000000 10721575472 11131899904 1762729760 17.707 97 31 62 3 1777326848 5122480 9474608
14:19:37 8622 8192000000 10678047104 11096555520 1741233856 17.529 96 35 70 2 1757597664 5542880 10820928
14:20:47 43552 8192000000 10716413296 11146285056 1787765344 17.913 98 27 54 1 1809119616 13623600 7730672
14:22:31 10478 8192000000 10763824016 11178459136 1837787456 18.323 100 20 40 0 1848293888 4787760 5718672
14:23:42 8783 8192000000 10775299840 11199655936 1857585408 18.484 100 32 64 1 1873539376 6199360 9754608
14:24:53 78735 8192000000 10783483168 11218915328 1875004912 18.625 100 38 76 3 1894944496 9565776 10373808
14:27:14 47481 8192000000 10835383072 11252547584 1898880016 18.817 100 49 97 1 1919689696 6622160 14187520
14:29:05 9690 8192000000 10874507152 11298967552 1936548656 19.119 100 47 94 0 1966833312 16316656 13968000
14:30:18 19085 8192000000 10891798032 11328212992 1963091968 19.331 100 44 88 1 1992705888 17180336 12433584
14:31:43 28541 8192000000 10907960976 11352375296 1971151584 19.395 100 28 56 0 2001843792 22324144 8368064
14:33:18 12314 8192000000 10934811488 11373785088 1988108176 19.529 100 41 81 0 2022916224 23082608 11725440
14:34:36 3799 8192000000 10983512080 11413131264 2014234336 19.735 100 59 118 2 2068632848 37046512 17352000
14:35:49 3129 8192000000 10973086176 11402080256 2010277104 19.704 100 65 130 0 2064608416 35306992 19024320
14:37:07 3779 8192000000 10976103264 11398692864 1987934176 19.527 99 77 153 0 2050041488 39427904 22679408
14:38:14 2203 8192000000 11006710880 11429707776 2021723360 19.794 100 76 152 0 2093905440 48738704 23443376
14:39:18 3013 8192000000 10954718096 11373506560 1953738576 19.256 98 73 146 1 2029559232 52636288 23184368
14:40:25 7709 8192000000 10992416096 11421798400 2005606720 19.667 100 63 123 3 2079028960 54151040 19271200
14:41:38 2428 8192000000 10979888704 11402366976 1972163856 19.403 99 85 170 0 2058232576 59409488 26659232
14:42:43 15097 8192000000 10958349248 11409346560 1971113216 19.394 99 35 69 2 2041283712 58389792 11780704
14:44:03 26816 8192000000 10937450208 11384430592 1950532448 19.231 99 32 65 1 2010287456 48602304 11152704
14:45:39 18256 8192000000 10942280640 11398144000 1971278784 19.396 100 26 52 0 2029372624 48243344 9850496
14:47:00 35441 8192000000 10899119552 11373101056 1923818432 19.017 98 23 44 0 1983381552 51832880 7730240
14:48:42 53550 8192000000 10859366192 11332112384 1857405136 18.482 95 6 13 0 1872727200 13724992 1597072
14:50:36 32410 8192000000 10845274576 11312549888 1890010256 18.746 97 16 31 2 1902690896 8483952 4196688
14:52:23 89411 8192000000 10840703792 11283816448 1835555360 18.305 95 39 78 3 1854486704 7982336 10949008
14:55:52 51013 8192000000 10852085408 11311386624 1901448272 18.838 99 22 44 3 1923882176 15831616 6602288
14:57:43 53649 8192000000 10856802704 11331063808 1942902016 19.170 100 25 50 0 1962405152 12075040 7428096
14:59:37 135552 8192000000 10869545408 11335622656 1903792528 18.857 97 12 24 0 1914728816 7761920 3174368
15:02:53 40795 8192000000 10889010016 11344523264 1941796400 19.161 99 20 41 0 1954585024 7191312 5597312
15:04:45 50413 8192000000 10925854592 11365879808 1972769920 19.407 100 29 59 1 1989954688 9331520 7853248
15:06:37 65953 8192000000 10911539408 11388112896 1983361360 19.491 100 27 54 0 2010135568 19475504 7298704
15:08:48 41382 8192000000 10895856400 11343126528 1886603392 18.718 95 31 59 0 1903367584 8008688 8755504
15:10:34 46931 8192000000 10909442784 11379130368 1973028176 19.409 100 21 42 1 1991292192 12164880 6099136
15:12:52 112362 8192000000 10926852304 11368353792 1948620960 19.215 98 25 50 1 1976046128 20521712 6903456
15:15:48 77492 8192000000 10896274448 11356168192 1948834928 19.217 99 32 62 4 1971715888 13972176 8908784
15:18:06 26063 8192000000 10899102704 11366957056 1928524336 19.055 98 24 48 0 1956800192 20676768 7599088
15:19:39 45574 8192000000 10894382704 11370844160 1948822048 19.217 99 13 26 1 1969539504 16856800 3860656
15:21:30 78903 8192000000 10899823744 11369541632 1965432128 19.349 100 17 34 1 1983649088 13563600 4653360
15:24:43 63728 8192000000 10846149968 11312402432 1848402544 18.409 95 8 16 0 1858117952 7563888 2151520
15:26:48 63442 8192000000 10859780656 11320963072 1871657472 18.598 96 22 44 0 1888410800 10350496 6402832
15:28:52 40346 8192000000 10862544512 11317587968 1892971216 18.770 98 35 70 1 1912804816 10305168 9528432
15:30:51 29547 8192000000 10886119824 11322175488 1889470272 18.742 97 29 58 2 1905403744 8222480 7710992
15:32:21 31083 8192000000 10845831920 11317878784 1904334880 18.861 99 22 44 2 1930722800 19613264 6774656
15:34:03 112372 8192000000 10849986368 11316330496 1812656080 18.118 94 5 10 0 1832466640 18468800 1341760
15:37:07 53436 8192000000 10879845312 11320741888 1896759472 18.800 98 26 51 2 1915176704 11274000 7143232
15:39:00 108457 8192000000 10846747824 11303043072 1865274368 18.546 97 28 56 0 1888593600 15586528 7732704
15:42:03 64409 8192000000 10846421184 11309441024 1918308848 18.973 100 15 30 2 1934883824 11607808 4967168
15:44:08 76230 8192000000 10836039792 11297730560 1868746560 18.574 97 16 32 0 1883761840 10779808 4235472
15:46:24 73052 8192000000 10842812048 11317796864 1919542784 18.983 100 13 26 0 1939388432 15662768 4182880
15:48:51 43290 8192000000 10876012128 11338465280 1944875936 19.186 100 25 49 0 1963057232 10679280 7502016
15:50:36 66838 8192000000 10873427792 11351810048 1934262720 19.101 99 17 34 0 1952901600 13376288 5262592
15:52:43 17297 8192000000 10892983248 11353309184 1960277808 19.308 100 39 74 2 1979183472 8450064 10455600
15:54:00 74479 8192000000 10889741392 11371028480 1930960048 19.075 98 5 10 1 1951339952 18899312 1480592
15:56:15 30829 8192000000 10907102544 11368435712 1956026944 19.274 99 45 90 2 1979067440 10577344 12463152
15:57:46 60587 8192000000 10924853952 11384147968 1948925760 19.218 98 19 38 2 1968275280 14234544 5114976
15:59:54 87090 8192000000 10895392624 11339075584 1888189952 18.731 95 16 32 0 1903527472 10913536 4423984
16:03:04 71150 8192000000 10869527280 11353649152 1937244192 19.125 99 11 22 2 1956772128 16459312 3068624
16:05:16 71120 8192000000 10885547696 11363467264 1940881728 19.154 99 12 24 0 1956121120 11433184 3806208
16:07:48 75423 8192000000 10882034400 11353907200 1943057248 19.171 98 21 42 1 1960227984 11493296 5677440
16:10:07 96763 8192000000 10919727760 11389317120 1983150080 19.490 100 12 24 0 1999344528 12886176 3308272
16:12:57 50989 8192000000 10886452432 11353731072 1884548864 18.702 95 18 36 1 1898097680 8424352 5124464
16:14:51 39305 8192000000 10887808048 11360296960 1924215776 19.021 98 26 52 1 1943259648 12094832 6949040
16:16:30 92376 8192000000 10845333488 11311525888 1866508064 18.556 96 7 14 0 1879615344 11233024 1874256
16:19:09 95400 8192000000 10874234336 11324944384 1821922848 18.193 93 20 40 0 1838822384 11678784 5220752
16:22:02 43940 8192000000 10848904256 11298435072 1824480320 18.214 94 26 52 1 1844839792 12962032 7397440
16:23:47 138664 8192000000 10840642048 11297361920 1869369184 18.579 97 24 48 0 1886140512 10237472 6533856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

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

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

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          249355
MON$OLDEST_ACTIVE               250854
MON$OLDEST_SNAPSHOT             250854
MON$NEXT_TRANSACTION            250854
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                       2024448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:90: 
:91: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:01 2020
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:01 2020
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:02 2020
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:101: 
:102: 
:103: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:06 2020
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:06 2020
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Mon Oct 26 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 26 16:27:13 2020
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Mon Oct 26 16:27:13 2020
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:121: 
--- end of diff output ---

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

26.10.20 16:29:33. 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-26 16:29:35 No severe PSQL-related problems occured
26.10.20 16:29:36. End of report.