Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 19: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 17526636 kB
MemAvailable 31536476 kB
Buffers 0 kB
Cached 14153136 kB
SwapCached 3048 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-23 19:26:59
DTS_END                         2020-10-23 19:26:59.0740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/52036
TRN_ID                          249854
ATT_ID                          691
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: 9610.33 19930 1153240
customer order: creation 180.36 324 21643
customer order: refuse 15.34 281 1841
order to supplier: creation 47.73 1197 5727
order to supplier: removal 3.68 80040 441
invoice (draft): creation 49.03 2969 5883
invoice (draft): removal 54.48 624 6537
invoice accept: apply 44.16 15968 5299
invoice accept: cancel 48.71 698 5845
customer reserve: creation 4092.82 158 491138
customer reserve: removal 4771.48 48 572577
realization accept: apply 86.19 159 10343
realization accept: cancel 80.98 110 9718
payment from customer: creation 61.48 100 7377
payment from customer: removal 6.29 57 755
payment to supplier: creation 51.16 132 6139
payment to supplier: removal 3.64 85 437
service: total inventory turnovers 5.15 974 618
service: total monetary turnovers 6.83 39 819
service: aggregate perf. data 0.83 18974 99
service: refresh index statistics 0.03 275667 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2020 19:27:38 to 23-10-2020 19:27:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3259
TEST_TIME 2 3357
TEST_TIME 3 3472
TEST_TIME 4 4207
TEST_TIME 5 4932
TEST_TIME 6 5250
TEST_TIME 7 5610
TEST_TIME 8 6063
TEST_TIME 9 6331
TEST_TIME 10 6343
TEST_TIME 11 6417
TEST_TIME 12 6460
TEST_TIME 13 6743
TEST_TIME 14 7186
TEST_TIME 15 7355
TEST_TIME 16 7865
TEST_TIME 17 8103
TEST_TIME 18 9166
TEST_TIME 19 9018
TEST_TIME 20 9147
TEST_TIME 21 9857
TEST_TIME 22 10380
TEST_TIME 23 10847
TEST_TIME 24 10869
TEST_TIME 25 10692
TEST_TIME 26 10623
TEST_TIME 27 10731
TEST_TIME 28 10842
TEST_TIME 29 10539
TEST_TIME 30 10317
TEST_TIME 31 10032
TEST_TIME 32 10264
TEST_TIME 33 10320
TEST_TIME 34 10269
TEST_TIME 35 10132
TEST_TIME 36 9958
TEST_TIME 37 9862
TEST_TIME 38 9886
TEST_TIME 39 9897
TEST_TIME 40 9942
TEST_TIME 41 9923
TEST_TIME 42 9929
TEST_TIME 43 9941
TEST_TIME 44 9946
TEST_TIME 45 10162
TEST_TIME 46 10136
TEST_TIME 47 10222
TEST_TIME 48 10125
TEST_TIME 49 10079
TEST_TIME 50 10242
TEST_TIME 51 10160
TEST_TIME 52 10062
TEST_TIME 53 9985
TEST_TIME 54 9897
TEST_TIME 55 9871
TEST_TIME 56 9791
TEST_TIME 57 9827
TEST_TIME 58 9781
TEST_TIME 59 9676
TEST_TIME 60 9645
TEST_TIME 61 9574
TEST_TIME 62 9601
TEST_TIME 63 9601
TEST_TIME 64 9540
TEST_TIME 65 9615
TEST_TIME 66 9698
TEST_TIME 67 9665
TEST_TIME 68 9766
TEST_TIME 69 9742
TEST_TIME 70 9705
TEST_TIME 71 9755
TEST_TIME 72 9795
TEST_TIME 73 9787
TEST_TIME 74 9900
TEST_TIME 75 9882
TEST_TIME 76 9836
TEST_TIME 77 9743
TEST_TIME 78 9752
TEST_TIME 79 9680
TEST_TIME 80 9644
TEST_TIME 81 9675
TEST_TIME 82 9660
TEST_TIME 83 9609
TEST_TIME 84 9579
TEST_TIME 85 9580
TEST_TIME 86 9605
TEST_TIME 87 9639
TEST_TIME 88 9617
TEST_TIME 89 9619
TEST_TIME 90 9619
TEST_TIME 91 9638
TEST_TIME 92 9704
TEST_TIME 93 9678
TEST_TIME 94 9711
TEST_TIME 95 9738
TEST_TIME 96 9741
TEST_TIME 97 9763
TEST_TIME 98 9747
TEST_TIME 99 9755
TEST_TIME 100 9675
TEST_TIME 101 9641
TEST_TIME 102 9622
TEST_TIME 103 9603
TEST_TIME 104 9583
TEST_TIME 105 9561
TEST_TIME 106 9551
TEST_TIME 107 9513
TEST_TIME 108 9469
TEST_TIME 109 9464
TEST_TIME 110 9465
TEST_TIME 111 9440
TEST_TIME 112 9446
TEST_TIME 113 9453
TEST_TIME 114 9476
TEST_TIME 115 9477
TEST_TIME 116 9543
TEST_TIME 117 9528
TEST_TIME 118 9511
TEST_TIME 119 9559
TEST_TIME 120 9616

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2020 19:27:41 to 23-10-2020 19:27:45.

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 1607795 1607795 0 0.00 0 26057 6 0 0
doc_list_biud 1607793 1607793 0 0.00 0 35437 22 0 0
sp_add_invoice_to_stock 5414 5299 115 2.12 32 203814 15969 0 115
sp_cancel_adding_invoice 5845 5845 0 0.00 5 62426 698 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 15999 282 0 0
sp_cancel_customer_reserve 572577 572577 0 0.00 1 35439 48 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 2 3860 58 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 13846 86 0 0
sp_cancel_supplier_invoice 6554 6537 17 0.25 2 25078 625 0 17
sp_cancel_supplier_order 441 441 0 0.00 5 732799 80041 0 0
sp_cancel_write_off 9718 9718 0 0.00 1 26067 110 0 0
sp_client_order 21643 21643 0 0.00 4 19320 325 0 0
sp_customer_reserve 491138 491138 0 0.00 0 113677 158 0 0
sp_fill_shopping_cart 33251 33251 0 0.00 1 18270 38 0 0
sp_fill_shopping_cart_clo_res 491127 491127 0 0.00 0 21370 13 0 0
sp_get_clo_for_invoice 5288 5288 0 0.00 0 32965 283 0 0
sp_kill_cost_storno 16755 16755 0 0.00 0 26007 48 0 0
sp_kill_qstorno_ret_qs2qd 1161850 1161850 0 0.00 0 35423 26 0 0
sp_kill_qty_storno 612590 612590 0 0.00 0 35437 57 0 0
sp_lock_selected_doc 21710 21710 0 0.00 0 8052 4 0 0
sp_make_cost_storno 29147 29147 0 0.00 0 13713 41 0 0
sp_multiply_rows_for_pdistr 29147 29147 0 0.00 0 23866 28 0 0
sp_multiply_rows_for_qdistr 21642 21642 0 0.00 0 18425 153 0 0
sp_pay_from_customer 7377 7377 0 0.00 4 24542 101 0 0
sp_pay_to_supplier 6139 6139 0 0.00 5 24752 132 0 0
sp_payment_common 13516 13516 0 0.00 1 24605 32 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 15989 255 0 0
sp_qd_handle_on_invoice_upd_sts 11133 11133 0 0.00 0 24829 302 0 0
sp_qd_handle_on_reserve_upd_sts 10343 10343 0 0.00 0 17801 58 0 0
sp_reserve_write_off 10343 10343 0 0.00 7 20308 159 0 0
sp_supplier_invoice 5883 5883 0 0.00 5 92123 2969 0 0
sp_supplier_order 5727 5727 0 0.00 5 28143 1198 0 0
srv_aggregate_perf_data 99 99 0 0.00 2961 271764 18975 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2109 160815 51398 0 0
srv_find_qd_qs_mism 1102842 1102842 0 0.00 0 18460 5 0 0
srv_make_invnt_saldo 618 618 0 0.00 21 106642 975 0 0
srv_make_money_saldo 819 819 0 0.00 1 13080 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 165986 371413 275667 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5299 5299 0 0.00 0 17525 155 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 24188 155 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 5524 15 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1709 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 512 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 150 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 3150 20 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 15804 1251 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 2140 12 0 0
t$perf-norm:sp_client_order 21643 21643 0 0.00 0 8470 15 0 0
t$perf-norm:sp_customer_reserve 1122 1122 0 0.00 0 745 5 0 0
t$perf-norm:sp_pay_from_customer 7377 7377 0 0.00 0 5713 8 0 0
t$perf-norm:sp_pay_to_supplier 6139 6139 0 0.00 0 5941 11 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 21001 12 0 0
t$perf-norm:sp_supplier_invoice 5883 5883 0 0.00 0 4814 15 0 0
t$perf-norm:sp_supplier_order 5727 5727 0 0.00 0 2885 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 183 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 16555 275 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 810 13 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 2694 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10804 10574 230 2.12 0 8070 21 0 230
v_all_customers 39204 39204 0 0.00 0 178 1 0 0
v_all_suppliers 31592 31592 0 0.00 0 91 1 0 0
v_all_wares 43282 43282 0 0.00 0 189 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1359 6 0 0
v_cancel_client_order 3682 3682 0 0.00 0 4957 8 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 3785 7 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 760 3 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1664 7 0 0
v_cancel_supplier_invoice 884 850 34 3.84 0 6643 27 0 34
v_cancel_supplier_order 882 882 0 0.00 0 3332 9 0 0
v_cancel_write_off 2118 2118 0 0.00 0 3106 15 0 0
v_max_id_clo_ord 5727 5727 0 0.00 0 5797 2 0 0
v_max_id_clo_res 1122 1122 0 0.00 0 13359 31 0 0
v_max_id_ord_sup 5883 5883 0 0.00 0 6110 7 0 0
v_max_non_paid_invoice 1953 1953 0 0.00 0 3737 4 0 0
v_max_non_paid_realizn 3977 3977 0 0.00 0 1487 3 0 0
v_min_id_clo_ord 5727 5727 0 0.00 0 13367 17 0 0
v_min_id_clo_res 1122 1122 0 0.00 0 4946 12 0 0
v_min_id_ord_sup 5883 5883 0 0.00 0 3412 3 0 0
v_min_non_paid_invoice 6139 6139 0 0.00 0 24589 26 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 24476 20 0 0
v_our_firm 10878 10878 0 0.00 0 18 1 0 0
v_reserve_write_off 20686 20686 0 0.00 0 5040 8 0 0
x_lock_depdocs_on_canc_invoice 11957 11957 0 0.00 0 19257 98 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 11913 299 0 0
x_make_qstorno_1000_1200 5727 5727 0 0.00 1 28119 1114 0 0
x_make_qstorno_1200_2000 5883 5883 0 0.00 2 91935 2842 0 0
x_make_qstorno_2100_3300 491138 491138 0 0.00 0 113674 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 19:27:46 to 23-10-2020 19:27:50.

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
16:57:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:02 31454 8192000000 10371799696 10695163904 1422242192 14.793 100 87 174 0 1456773104 6020160 28510752
16:59:40 1573 8192000000 10442814704 10775044096 1500739504 15.483 100 90 180 0 1537700512 8438224 28522784
17:00:54 19560 8192000000 10513201040 10857582592 1565569024 16.044 100 90 181 0 1603235072 10231280 27434768
17:02:18 12384 8192000000 10603550576 10962452480 1654256736 16.800 100 80 160 4 1690218816 12235776 23726304
17:03:32 7989 8192000000 10650725200 11021611008 1709196624 17.262 100 78 157 3 1744796656 12571712 23028320
17:04:42 5588 8192000000 10713178016 11094843392 1775152448 17.810 100 76 150 1 1809053232 12650400 21250384
17:05:50 2921 8192000000 10762750736 11144495104 1818566512 18.166 100 86 172 1 1857313616 14660448 24086656
17:06:55 2518 8192000000 10795134800 11189784576 1857935520 18.487 100 73 146 2 1890022928 11419664 20667744
17:07:59 22159 8192000000 10803204848 11210555392 1875666880 18.630 100 56 112 2 1902201488 9619936 16914672
17:09:23 2529 8192000000 10826895408 11234914304 1891912496 18.761 100 59 119 0 1923980992 14022416 18046080
17:10:28 5993 8192000000 10811437888 11233136640 1894505600 18.782 100 31 62 2 1909500528 6352400 8642528
17:11:35 6192 8192000000 10822927888 11248857088 1904106416 18.859 100 27 54 1 1917666032 5963616 7596000
17:12:43 5758 8192000000 10824476688 11262214144 1916796560 18.961 100 23 46 2 1929983056 6624336 6562160
17:13:49 58814 8192000000 10876171920 11285876736 1924306656 19.021 100 40 79 1 1942223248 6079728 11836864
17:15:48 5985 8192000000 10800559456 11229044736 1857044400 18.479 97 26 52 0 1871457136 6689024 7723712
17:16:56 13711 8192000000 10809190400 11236274176 1851400624 18.434 97 26 52 0 1863900768 5242912 7257232
17:18:11 29144 8192000000 10754800608 11156443136 1726726416 17.408 93 34 69 0 1741199024 5526992 8945616
17:19:42 5155 8192000000 10696918656 11109769216 1716361104 17.322 95 35 71 3 1732100000 5478336 10260560
17:20:48 7464 8192000000 10692758816 11110232064 1731800384 17.450 96 21 42 2 1743845104 5846000 6198720
17:21:57 10380 8192000000 10732696688 11153244160 1808036704 18.080 99 28 53 1 1821791072 6277568 7476800
17:23:12 28103 8192000000 10738836272 11173584896 1840765744 18.347 100 23 44 2 1852341712 5413552 6162416
17:24:40 7743 8192000000 10778117856 11208953856 1866663376 18.557 100 25 51 1 1880361184 5718592 7979216
17:25:48 5582 8192000000 10800536288 11223982080 1882936080 18.689 100 27 53 3 1896156288 5601712 7618496
17:26:54 5855 8192000000 10797912368 11231563776 1887903248 18.729 100 17 34 2 1898566656 6015568 4647840
17:28:05 24175 8192000000 10864810816 11280388096 1933157744 19.092 100 55 106 3 1956367504 7894832 15314928
17:29:31 62968 8192000000 10863243840 11299385344 1937137776 19.124 100 29 57 1 1955352640 9890880 8323984
17:31:42 20572 8192000000 10893745168 11325833216 1951128400 19.235 99 34 68 4 1973294352 11872160 10293792
17:33:06 6348 8192000000 10918054640 11345948672 1983679152 19.494 100 53 104 1 2011603440 12736016 15188272
17:34:16 10466 8192000000 10915492480 11352276992 1967792656 19.368 99 46 91 1 1995660512 14831696 13036160
17:35:31 10391 8192000000 10938923248 11368558592 1993311248 19.570 100 53 105 2 2028014656 19634304 15069104
17:36:43 3550 8192000000 10967205600 11388653568 2004519216 19.658 100 70 140 4 2049394080 23728576 21146288
17:37:50 7537 8192000000 10957642592 11389992960 2000588704 19.627 100 63 121 3 2043895504 25426208 17880592
17:39:00 2983 8192000000 10950398912 11368595456 1986301648 19.515 100 79 158 0 2041807712 30304752 25201312
17:40:07 5743 8192000000 10932011168 11359739904 1961874848 19.321 99 64 128 1 2021352480 39300640 20176992
17:41:20 3379 8192000000 10918962080 11336495104 1936961488 19.123 99 76 149 2 1997538272 37110416 23466368
17:42:27 2108 8192000000 10950481312 11367092224 1953849600 19.257 99 77 152 4 2024255984 46074208 24332176
17:43:31 3655 8192000000 10935722528 11353157632 1952499872 19.246 100 68 137 1 2021664672 47060992 22103808
17:44:42 14959 8192000000 10922814608 11369279488 1957747264 19.288 100 29 57 2 2008166032 40951536 9467232
17:46:11 39041 8192000000 10915216576 11369996288 1952497376 19.246 100 31 62 1 2004566176 41467600 10601200
17:48:11 39712 8192000000 10877526544 11347222528 1911809088 18.921 99 12 24 1 1953203232 36905024 4489120
17:49:54 26534 8192000000 10871382704 11316400128 1906416448 18.878 98 30 59 2 1926219616 10328832 9474336
17:51:20 96923 8192000000 10866418656 11323387904 1871117216 18.593 97 21 41 0 1886436736 10176128 5143392
17:54:03 144740 8192000000 10878989744 11330969600 1905366544 18.869 99 18 35 0 1931566304 21296144 4903616
17:58:02 97476 8192000000 10847166592 11294633984 1844901120 18.381 95 33 66 1 1863485600 9779424 8805056
18:00:42 33961 8192000000 10841631648 11306090496 1909005776 18.899 99 10 20 1 1920702544 9025040 2671728
18:02:37 34543 8192000000 10863463952 11323617280 1943057024 19.171 100 12 25 1 1956079856 9737120 3285712
18:04:12 29964 8192000000 10882700768 11363082240 1950396976 19.230 100 5 11 0 1965231024 13663840 1170208
18:05:42 54460 8192000000 10901919712 11374395392 1974872272 19.424 100 30 60 2 1995376464 11614528 8889664
18:07:37 51976 8192000000 10937426672 11390025728 1983822336 19.495 100 21 42 1 2003451808 13458352 6171120
18:09:31 85058 8192000000 10941956832 11405893632 1999872416 19.622 100 19 38 0 2020216704 15031680 5312608
18:12:21 98666 8192000000 10924159824 11403694080 1935697952 19.112 97 17 34 1 1950824480 10533504 4593024
18:15:03 22514 8192000000 10928470432 11379240960 1969792608 19.384 99 30 60 0 1988538032 10500944 8244480
18:16:31 96326 8192000000 10873169936 11360145408 1883445072 18.693 96 10 20 0 1909322928 22669392 3208464
18:19:19 67450 8192000000 10903545968 11363213312 1948581312 19.215 99 30 60 0 1964365616 7552544 8231760
18:21:27 56247 8192000000 10901815712 11387101184 1982015072 19.481 100 7 14 0 1995231760 11198304 2018384
18:23:38 50852 8192000000 10878395328 11347943424 1914772640 18.945 97 19 38 2 1929722752 9351248 5598864
18:25:39 49251 8192000000 10885190688 11359227904 1960426080 19.309 100 20 40 0 1975115952 8699648 5990224
18:27:29 70427 8192000000 10879814448 11350368256 1893068576 18.771 96 7 14 1 1902035696 7378496 1588624
18:29:45 36914 8192000000 10866985968 11322261504 1910800176 18.913 98 18 36 0 1926624496 11331168 4493152
18:31:23 48538 8192000000 10878775648 11334758400 1918956672 18.978 98 22 44 1 1939467472 13718784 6792016
18:33:13 73101 8192000000 10874862064 11333857280 1856738992 18.477 95 30 60 1 1876278032 11597216 7941824
18:35:41 32823 8192000000 10880403168 11331928064 1921282704 18.997 99 28 56 0 1939702304 10551936 7867664
18:37:17 16688 8192000000 10861648368 11321901056 1870449104 18.588 96 29 58 3 1891547280 13440176 7658000
18:38:35 137400 8192000000 10859986912 11334868992 1830909536 18.267 94 17 34 0 1853930912 18344848 4676528
18:41:53 100673 8192000000 10847460464 11323756544 1895758576 18.792 98 15 30 1 1911409568 11468528 4182464
18:45:06 52266 8192000000 10881627888 11342536704 1941775584 19.161 100 16 32 0 1960375488 14173952 4425952
18:47:05 89367 8192000000 10866195504 11350716416 1915232512 18.949 98 5 10 0 1933081424 16133680 1715232
18:49:39 54554 8192000000 10872468704 11349151744 1950671152 19.232 100 16 32 1 1969907248 14806896 4429200
18:51:47 67212 8192000000 10869115392 11340742656 1937641456 19.128 99 24 48 1 1957181552 12134144 7405952
18:54:08 61287 8192000000 10906373840 11366473728 1949446464 19.222 99 22 44 0 1967343872 11697968 6199440
18:56:12 47165 8192000000 10916431808 11373940736 1958191312 19.292 99 20 40 0 1977386256 13390400 5804544
18:58:52 53157 8192000000 10914618896 11387355136 1985989200 19.512 100 20 40 2 2005552144 13638848 5924096
19:00:46 95811 8192000000 10926486848 11404746752 1978518368 19.453 99 13 26 0 2001663440 19027616 4117456
19:03:22 76801 8192000000 10914024848 11379408896 1926837328 19.042 97 17 32 1 1941475024 10572160 4065536
19:05:43 160815 8192000000 10904486160 11382992896 1957484448 19.286 99 22 44 1 1972652192 9349056 5818688
19:09:25 56427 8192000000 10889368288 11370762240 1947212352 19.204 98 28 56 1 1968694880 13628432 7854096
19:12:22 69128 8192000000 10869805248 11336482816 1909336864 18.901 97 16 32 1 1926879632 13224080 4318688
19:14:36 99048 8192000000 10853769792 11349458944 1882716944 18.687 96 11 22 0 1900850272 14928272 3205056
19:17:17 40358 8192000000 10878995392 11344740352 1917813216 18.969 98 15 30 0 1930148240 8175920 4159104
19:18:57 30159 8192000000 10860770160 11334758400 1915407904 18.950 98 30 60 1 1934388208 9754544 9225760
19:20:32 39536 8192000000 10869334688 11343568896 1928689360 19.056 99 17 34 1 1950296288 16444912 5162016
19:22:12 51732 8192000000 10861807104 11303464960 1836137856 18.309 95 19 36 0 1848921264 7488080 5295328
19:24:06 47479 8192000000 10873728464 11343450112 1934825232 19.105 100 35 70 2 1962592896 17670512 10097152
19:25:55 65994 8192000000 9210647776 9460543488 368997712 4.310 19 3 6 0 374112864 4698416 416736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-10-2020 19:27:52 to 23-10-2020 19:28:06.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2020 19:28:07 to 23-10-2020 19:28:09.

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          250122
MON$OLDEST_ACTIVE               250123
MON$OLDEST_SNAPSHOT             250123
MON$NEXT_TRANSACTION            250123
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                       2020672
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.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Fri Oct 23 19:27:06 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
--- end of diff output ---

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

23.10.20 19:28:10. 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-23 19:28:12 No severe PSQL-related problems occured
23.10.20 19:28:14. End of report.