Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2020 22:29
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 17356028 kB
MemAvailable 31658036 kB
Buffers 0 kB
Cached 14360864 kB
SwapCached 5752 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-10 22:26:59
DTS_END                         2020-10-10 22:26:59.5590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(601:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/42074
TRN_ID                          250456
ATT_ID                          709
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: 9925.20 18230 1191024
customer order: creation 181.21 362 21745
customer order: refuse 15.48 293 1858
order to supplier: creation 47.42 1123 5690
order to supplier: removal 3.63 77898 435
invoice (draft): creation 47.48 2742 5697
invoice (draft): removal 57.33 531 6880
invoice accept: apply 43.90 17297 5268
invoice accept: cancel 51.70 672 6204
customer reserve: creation 4086.42 170 490370
customer reserve: removal 5078.99 44 609479
realization accept: apply 87.44 166 10493
realization accept: cancel 87.38 97 10485
payment from customer: creation 62.80 107 7536
payment from customer: removal 5.88 79 705
payment to supplier: creation 52.08 126 6250
payment to supplier: removal 3.70 61 444
service: total inventory turnovers 4.88 1080 585
service: total monetary turnovers 6.61 37 793
service: aggregate perf. data 0.86 21310 103
service: refresh index statistics 0.03 240412 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-10-2020 22:29:45 to 10-10-2020 22:29:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5412
TEST_TIME 2 5903
TEST_TIME 3 6423
TEST_TIME 4 8689
TEST_TIME 5 7503
TEST_TIME 6 7413
TEST_TIME 7 7035
TEST_TIME 8 7065
TEST_TIME 9 7022
TEST_TIME 10 7396
TEST_TIME 11 7519
TEST_TIME 12 7782
TEST_TIME 13 8233
TEST_TIME 14 8671
TEST_TIME 15 8851
TEST_TIME 16 8887
TEST_TIME 17 9255
TEST_TIME 18 9399
TEST_TIME 19 9673
TEST_TIME 20 9960
TEST_TIME 21 10096
TEST_TIME 22 10340
TEST_TIME 23 10441
TEST_TIME 24 10210
TEST_TIME 25 10414
TEST_TIME 26 11040
TEST_TIME 27 11646
TEST_TIME 28 11415
TEST_TIME 29 11570
TEST_TIME 30 11443
TEST_TIME 31 11295
TEST_TIME 32 11143
TEST_TIME 33 11214
TEST_TIME 34 11255
TEST_TIME 35 11248
TEST_TIME 36 11287
TEST_TIME 37 11340
TEST_TIME 38 11151
TEST_TIME 39 11042
TEST_TIME 40 10959
TEST_TIME 41 11016
TEST_TIME 42 10831
TEST_TIME 43 10706
TEST_TIME 44 10645
TEST_TIME 45 10749
TEST_TIME 46 10626
TEST_TIME 47 10558
TEST_TIME 48 10721
TEST_TIME 49 10570
TEST_TIME 50 10401
TEST_TIME 51 10535
TEST_TIME 52 10495
TEST_TIME 53 10427
TEST_TIME 54 10318
TEST_TIME 55 10199
TEST_TIME 56 10172
TEST_TIME 57 10056
TEST_TIME 58 10186
TEST_TIME 59 10147
TEST_TIME 60 10157
TEST_TIME 61 10141
TEST_TIME 62 10326
TEST_TIME 63 10308
TEST_TIME 64 10372
TEST_TIME 65 10440
TEST_TIME 66 10395
TEST_TIME 67 10463
TEST_TIME 68 10458
TEST_TIME 69 10380
TEST_TIME 70 10472
TEST_TIME 71 10423
TEST_TIME 72 10322
TEST_TIME 73 10327
TEST_TIME 74 10287
TEST_TIME 75 10225
TEST_TIME 76 10215
TEST_TIME 77 10141
TEST_TIME 78 10117
TEST_TIME 79 10060
TEST_TIME 80 9988
TEST_TIME 81 9957
TEST_TIME 82 9979
TEST_TIME 83 9974
TEST_TIME 84 9960
TEST_TIME 85 9985
TEST_TIME 86 10063
TEST_TIME 87 10059
TEST_TIME 88 10067
TEST_TIME 89 10035
TEST_TIME 90 10043
TEST_TIME 91 10227
TEST_TIME 92 10220
TEST_TIME 93 10266
TEST_TIME 94 10255
TEST_TIME 95 10250
TEST_TIME 96 10195
TEST_TIME 97 10173
TEST_TIME 98 10164
TEST_TIME 99 10115
TEST_TIME 100 10051
TEST_TIME 101 10002
TEST_TIME 102 9953
TEST_TIME 103 9929
TEST_TIME 104 9940
TEST_TIME 105 9933
TEST_TIME 106 9902
TEST_TIME 107 9923
TEST_TIME 108 9901
TEST_TIME 109 9918
TEST_TIME 110 9958
TEST_TIME 111 9931
TEST_TIME 112 10048
TEST_TIME 113 10016
TEST_TIME 114 10022
TEST_TIME 115 10031
TEST_TIME 116 10086
TEST_TIME 117 10018
TEST_TIME 118 9966
TEST_TIME 119 9929
TEST_TIME 120 9929

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2020 22:29:49 to 10-10-2020 22:29:52.

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 1645289 1645289 0 0.00 0 35053 6 0 0
doc_list_biud 1645288 1645288 0 0.00 0 50017 21 0 0
sp_add_invoice_to_stock 5366 5268 98 1.82 27 163132 17298 0 98
sp_cancel_adding_invoice 6204 6204 0 0.00 5 49763 672 0 0
sp_cancel_client_order 1858 1858 0 0.00 4 13721 293 0 0
sp_cancel_customer_reserve 609479 609479 0 0.00 1 50018 45 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 2 7313 79 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 2856 61 0 0
sp_cancel_supplier_invoice 6888 6880 8 0.11 2 28414 532 0 8
sp_cancel_supplier_order 435 435 0 0.00 8 739206 77899 0 0
sp_cancel_write_off 10485 10485 0 0.00 1 13671 98 0 0
sp_client_order 21745 21745 0 0.00 3 51061 363 0 0
sp_customer_reserve 490370 490370 0 0.00 0 44478 171 0 0
sp_fill_shopping_cart 33127 33127 0 0.00 1 10954 32 0 0
sp_fill_shopping_cart_clo_res 490349 490349 0 0.00 0 12359 13 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 23580 298 0 0
sp_kill_cost_storno 17838 17838 0 0.00 0 11277 44 0 0
sp_kill_qstorno_ret_qs2qd 1236758 1236758 0 0.00 0 50014 24 0 0
sp_kill_qty_storno 651080 651080 0 0.00 0 50016 52 0 0
sp_lock_selected_doc 21864 21864 0 0.00 0 8843 5 0 0
sp_make_cost_storno 29526 29526 0 0.00 0 35043 52 0 0
sp_multiply_rows_for_pdistr 29526 29526 0 0.00 0 15305 27 0 0
sp_multiply_rows_for_qdistr 21745 21745 0 0.00 0 51022 168 0 0
sp_pay_from_customer 7536 7536 0 0.00 4 13914 108 0 0
sp_pay_to_supplier 6250 6250 0 0.00 4 35065 126 0 0
sp_payment_common 13786 13786 0 0.00 1 13908 27 0 0
sp_qd_handle_on_cancel_clo 1858 1858 0 0.00 0 13715 262 0 0
sp_qd_handle_on_invoice_upd_sts 11450 11450 0 0.00 0 30025 286 0 0
sp_qd_handle_on_reserve_upd_sts 10493 10493 0 0.00 0 16454 49 0 0
sp_reserve_write_off 10493 10493 0 0.00 6 16471 167 0 0
sp_supplier_invoice 5697 5697 0 0.00 6 70755 2743 0 0
sp_supplier_order 5690 5690 0 0.00 5 41584 1124 0 0
srv_aggregate_perf_data 103 103 0 0.00 2905 479644 21310 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1460 179506 47845 0 0
srv_find_qd_qs_mism 1140726 1140726 0 0.00 0 12835 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 32 32105 1080 0 0
srv_make_money_saldo 793 793 0 0.00 1 6514 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 78711 369043 240412 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5268 5268 0 0.00 0 27027 211 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 4770 98 0 0
t$perf-norm:sp_cancel_client_order 1858 1858 0 0.00 0 2064 8 0 0
t$perf-norm:sp_cancel_customer_reserve 771 771 0 0.00 0 2128 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 4960 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 1493 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2162 21 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 23405 1358 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 3215 9 0 0
t$perf-norm:sp_client_order 21745 21745 0 0.00 0 27141 23 0 0
t$perf-norm:sp_customer_reserve 1060 1060 0 0.00 0 4161 12 0 0
t$perf-norm:sp_pay_from_customer 7536 7536 0 0.00 0 7813 16 0 0
t$perf-norm:sp_pay_to_supplier 6250 6250 0 0.00 0 13203 13 0 0
t$perf-norm:sp_reserve_write_off 10493 10493 0 0.00 0 27070 16 0 0
t$perf-norm:sp_supplier_invoice 5697 5697 0 0.00 0 8349 17 0 0
t$perf-norm:sp_supplier_order 5690 5690 0 0.00 0 8872 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1082 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 624 10 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 3005 25 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 959 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10682 10486 196 1.83 0 7712 20 0 196
v_all_customers 39896 39896 0 0.00 0 18 1 0 0
v_all_suppliers 31770 31770 0 0.00 0 21 1 0 0
v_all_wares 43490 43490 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2041 9 0 0
v_cancel_client_order 3716 3716 0 0.00 0 7770 9 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 4850 9 0 0
v_cancel_customer_reserve 1542 1542 0 0.00 0 5310 10 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 1934 5 0 0
v_cancel_supplier_invoice 896 880 16 1.78 0 4930 20 0 16
v_cancel_supplier_order 870 870 0 0.00 0 237 3 0 0
v_cancel_write_off 2082 2082 0 0.00 0 5011 16 0 0
v_max_id_clo_ord 5690 5690 0 0.00 0 1630 2 0 0
v_max_id_clo_res 1060 1060 0 0.00 0 3437 18 0 0
v_max_id_ord_sup 5692 5692 0 0.00 0 2508 3 0 0
v_max_non_paid_invoice 1747 1747 0 0.00 0 1619 3 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 3155 4 0 0
v_min_id_clo_ord 5690 5690 0 0.00 0 3569 6 0 0
v_min_id_clo_res 1060 1060 0 0.00 0 2146 6 0 0
v_min_id_ord_sup 5692 5692 0 0.00 0 8480 10 0 0
v_min_non_paid_invoice 6250 6250 0 0.00 0 5556 15 0 0
v_min_non_paid_realizn 7536 7536 0 0.00 0 8295 18 0 0
v_our_firm 10910 10910 0 0.00 0 26 1 0 0
v_reserve_write_off 20986 20986 0 0.00 0 10346 7 0 0
x_lock_depdocs_on_canc_invoice 12644 12644 0 0.00 0 13531 93 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 6595 274 0 0
x_make_qstorno_1000_1200 5690 5690 0 0.00 1 41038 1055 0 0
x_make_qstorno_1200_2000 5697 5697 0 0.00 3 70727 2631 0 0
x_make_qstorno_2100_3300 490370 490370 0 0.00 0 44476 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2020 22:29:53 to 10-10-2020 22:29:57.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
19:57:02 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:03 25527 8192000000 10365235168 10684358656 1406940384 14.657 100 87 176 0 1441943760 5913616 29089760
19:59:35 26961 8192000000 10426776736 10758336512 1482978768 15.327 100 87 174 1 1519242048 7540000 28723280
20:01:05 12556 8192000000 10488476736 10829262848 1541751840 15.839 100 84 168 1 1577311808 9219088 26340880
20:02:23 18006 8192000000 10566738624 10931286016 1627836624 16.577 100 72 143 2 1659587120 10294384 21456112
20:03:42 7741 8192000000 10657234800 11029467136 1719303712 17.346 100 85 170 0 1756689696 12373472 25012512
20:04:52 5212 8192000000 10709417232 11098071040 1778857968 17.840 100 84 165 2 1814987088 12895792 23233328
20:05:58 6408 8192000000 10757929568 11147575296 1817925440 18.161 100 75 149 4 1852302112 13255280 21121392
20:07:06 2790 8192000000 10817508368 11204673536 1868378016 18.571 100 92 185 1 1908073408 14084592 25610800
20:08:09 6471 8192000000 10789685728 11209887744 1875706368 18.630 100 50 99 1 1897754480 8880768 13167344
20:09:19 10085 8192000000 10788334160 11223842816 1885184112 18.707 100 21 42 1 1897510400 6752320 5573968
20:10:31 53575 8192000000 10800836544 11246923776 1900814080 18.833 100 36 72 0 1916921024 5002304 11104640
20:12:25 10342 8192000000 10824585392 11264307200 1912344880 18.925 100 19 37 0 1923384608 5798096 5241632
20:13:38 10713 8192000000 10841400608 11272843264 1920161248 18.988 100 21 42 0 1931975584 5234464 6579872
20:14:53 15332 8192000000 10831328208 11266326528 1903850192 18.857 99 18 35 2 1915814544 5694080 6270272
20:16:11 31189 8192000000 10808001280 11234631680 1820854608 18.185 95 31 62 1 1835386144 6197264 8334272
20:17:44 7543 8192000000 10729008704 11150934016 1766751936 17.740 96 21 42 1 1778320448 5708928 5859584
20:18:54 11846 8192000000 10718961920 11137134592 1775324928 17.811 98 20 40 0 1786206000 5312784 5568288
20:20:08 66624 8192000000 10692829728 11114790912 1694860512 17.142 94 29 58 1 1709397408 6038304 8498592
20:22:24 9317 8192000000 10759625552 11181166592 1842605136 18.362 100 33 65 1 1858280576 5424560 10250880
20:23:34 7823 8192000000 10770112800 11199619072 1859385136 18.498 100 19 37 2 1870694400 5355424 5953840
20:24:45 14922 8192000000 10781626144 11212349440 1863973968 18.535 100 15 30 1 1873058736 4987168 4097600
20:26:07 9580 8192000000 10778848960 11217039360 1870402416 18.588 100 33 66 2 1886691008 6422352 9866240
20:27:19 19989 8192000000 10839883232 11253432320 1904278176 18.861 100 47 94 0 1926513936 9736352 12499408
20:28:43 8080 8192000000 10887513200 11298209792 1938906656 19.138 100 70 138 0 1970338560 12189280 19242624
20:30:01 14240 8192000000 10873890320 11306598400 1949187328 19.220 100 65 123 1 1977371264 10923760 17260176
20:31:26 9468 8192000000 10878154928 11312762880 1952623920 19.247 100 45 90 1 1976361104 11434976 12302208
20:32:38 4972 8192000000 10891825520 11317714944 1948010288 19.211 99 63 125 3 1979096496 13310640 17775568
20:33:50 7570 8192000000 10896750416 11316097024 1927013008 19.043 98 70 140 0 1963967200 16491120 20463072
20:35:01 10009 8192000000 10912778960 11349401600 1977167248 19.442 100 47 91 0 2006849120 15897728 13784144
20:36:16 2997 8192000000 10914534768 11338178560 1963535184 19.334 100 65 130 0 2002932368 19847312 19549872
20:37:27 8021 8192000000 10912325072 11344216064 1951170144 19.236 99 62 125 0 1991150688 20525088 19455456
20:38:39 3936 8192000000 10928025856 11346964480 1955716160 19.272 99 69 138 1 2001972720 25444848 20811712
20:39:46 1457 8192000000 10920763200 11343314944 1948164528 19.212 99 71 141 2 2000766224 30860592 21741104
20:40:51 8616 8192000000 10915884672 11339898880 1943820320 19.177 99 68 136 3 1990620176 26147792 20652064
20:42:11 2716 8192000000 10903074720 11330248704 1943904240 19.178 100 55 110 4 1989017792 28633424 16480128
20:43:23 1800 8192000000 10887185792 11303677952 1909465488 18.902 99 64 128 1 1958441040 28910480 20065072
20:44:27 13785 8192000000 10868157424 11295272960 1896892720 18.801 99 30 60 1 1932252240 25851824 9507696
20:45:45 17899 8192000000 10895706096 11339374592 1945963424 19.194 100 34 68 1 1988486656 31124896 11398336
20:47:16 7750 8192000000 10879110368 11302383616 1897547600 18.807 99 53 104 3 1945386736 31588912 16250224
20:48:25 24841 8192000000 10910929376 11356442624 1949953648 19.226 100 24 48 0 1995544928 37732848 7858432
20:50:36 59030 8192000000 10909741552 11360546816 1927598640 19.048 99 34 68 2 1984472080 45961120 10912320
20:52:38 38768 8192000000 10886683616 11358973952 1930834960 19.074 100 11 20 3 1973083840 37862416 4386464
20:54:17 105149 8192000000 10865373536 11328626688 1918978688 18.979 99 12 24 1 1936145600 13585552 3581360
20:57:09 89853 8192000000 10917840016 11378221056 1965917728 19.353 100 12 24 0 1980838464 11673216 3247520
20:59:46 127704 8192000000 10917892032 11383820288 1970145744 19.387 99 4 8 1 1993050768 22072688 832336
21:02:54 145681 8192000000 10956471328 11400781824 2001404128 19.634 100 25 50 2 2021428784 12576608 7448048
21:06:32 57402 8192000000 10956650960 11420459008 2010892816 19.709 100 34 68 2 2034839568 15184416 8762336
21:08:34 19385 8192000000 10945122064 11407986688 1997658288 19.604 99 22 44 1 2012842640 8735536 6448816
21:10:06 54562 8192000000 10946528400 11406598144 1984391952 19.499 98 42 81 0 2007035184 11187600 11455632
21:12:02 48658 8192000000 10939872176 11411959808 2010302256 19.704 100 10 20 1 2025462800 11980256 3180288
21:13:51 53259 8192000000 10903268864 11397337088 1960751408 19.312 98 10 20 0 1977152128 13774640 2626080
21:15:45 41354 8192000000 10900917296 11360243712 1950234992 19.228 98 25 50 1 1965332192 7203920 7893280
21:17:28 81507 8192000000 10908782912 11395633152 1965342528 19.348 99 15 30 1 1985169168 15875568 3951072
21:19:58 69220 8192000000 10901026016 11374612480 1961679216 19.319 98 15 30 1 1975547328 9486272 4381840
21:23:04 58637 8192000000 10899713296 11383119872 1921585584 19.000 97 6 12 0 1937493472 14223888 1684000
21:25:14 52423 8192000000 10903716608 11361366016 1908422688 18.894 97 19 38 0 1925240208 11643376 5174144
21:27:14 73985 8192000000 10890759584 11374362624 1936315520 19.117 98 14 28 1 1958262176 17580560 4366096
21:29:34 58649 8192000000 10875770720 11349696512 1896003920 18.794 97 10 20 3 1915359792 16949408 2406464
21:31:45 60114 8192000000 10877238320 11332665344 1837467056 18.320 93 20 39 2 1852302176 8961920 5873200
21:33:45 69052 8192000000 10861345072 11319336960 1832064976 18.276 94 13 26 0 1845312352 10021280 3226096
21:36:00 14410 8192000000 10865541952 11306180608 1889558896 18.742 98 53 106 0 1917069136 12916432 14593808
21:37:15 50520 8192000000 10893644064 11342999552 1920692816 18.992 99 22 44 1 1935898000 9231552 5973632
21:39:42 70875 8192000000 10833637088 11303337984 1833011008 18.284 95 30 60 2 1852854112 11489920 8353184
21:41:53 51805 8192000000 10825316032 11298185216 1863611728 18.533 97 10 20 0 1879553616 13088992 2852896
21:43:45 41210 8192000000 10848373632 11312029696 1906824224 18.881 99 25 52 1 1921197664 6548576 7824864
21:45:26 79950 8192000000 10864601776 11337523200 1942517296 19.167 100 19 38 0 1955868304 7662592 5688416
21:47:51 52191 8192000000 10877566160 11358904320 1954230576 19.260 100 9 18 0 1968969120 11972752 2765792
21:49:44 50523 8192000000 10872621248 11352215552 1915340592 18.949 97 21 41 1 1934523648 13182960 6000096
21:51:35 51684 8192000000 10892195856 11377442816 1946431456 19.198 99 18 36 1 1966752976 15178448 5143072
21:53:35 27616 8192000000 10918388544 11378778112 1980665840 19.470 100 29 56 0 1997028960 8691456 7671664
21:55:03 42387 8192000000 10919462320 11390824448 1960602784 19.311 98 32 64 0 1987731808 17811776 9317248
21:56:46 118925 8192000000 10921177712 11402481664 1952081600 19.243 98 9 18 0 1976775888 22078144 2616144
21:59:49 179505 8192000000 10921285472 11396108288 1922489584 19.007 96 17 34 2 1940595792 13512384 4593824
22:03:50 50597 8192000000 10889589776 11382124544 1958487312 19.294 99 17 34 1 1978093696 13856960 5749424
22:05:40 33992 8192000000 10893252112 11377496064 1954242720 19.260 99 2 4 1 1967477408 12663824 570864
22:07:14 90525 8192000000 10889255760 11380830208 1940274960 19.149 98 7 14 0 1954323632 12222608 1826064
22:10:00 48580 8192000000 10882534656 11350306816 1892974384 18.770 96 17 33 3 1908853216 10919424 4959408
22:11:49 25637 8192000000 10878604880 11348410368 1943575488 19.175 99 18 35 1 1955342000 7231472 4535040
22:13:15 70025 8192000000 10878826672 11340177408 1900590656 18.831 98 19 38 1 1917396880 11252752 5553472
22:15:29 42786 8192000000 10871731360 11335897088 1926544960 19.039 99 33 66 1 1955825904 19971808 9309136
22:17:13 71271 8192000000 10863335872 11344945152 1878129904 18.650 96 23 45 3 1902818032 18490832 6197296
22:19:33 25190 8192000000 10858529184 11301203968 1895985296 18.794 99 51 100 0 1919847840 9970624 13891920
22:20:58 12016 8192000000 10850635040 11290247168 1891533552 18.758 99 47 94 1 1916730336 12538352 12658432
22:22:12 82609 8192000000 10833189168 11301605376 1884529280 18.702 99 16 32 1 1904831264 16093520 4208464
22:24:55 96759 8192000000 10789669616 11263787008 1802318640 18.033 94 15 30 1 1815728128 8568464 4841024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 98 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 10-10-2020 22:30:15 to 10-10-2020 22:30:16.

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          249298
MON$OLDEST_ACTIVE               250700
MON$OLDEST_SNAPSHOT             250700
MON$NEXT_TRANSACTION            250700
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                       2052832
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.33372 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33372 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33372 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33372 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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Oct 10 22:27:01 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

10.10.20 22:30:18. 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-10 22:30:20 No severe PSQL-related problems occured
10.10.20 22:30:21. End of report.