Skip to Main Content

Hardware

Announcement

For appeals, questions and feedback about Oracle Forums, please email oracle-forums-moderators_us@oracle.com. Technical questions should be asked in the appropriate category. Thank you!

vdbench failure during verification: Loop protection in code that prevents lba=0 from being accessed

2982328Apr 30 2018 — edited Apr 30 2018

Hi ,

I've tried to perform  full data verification after i ran writes on device

write ran for 60 seconds w/o any problem.

verifcation fails due to: Loop protection in code that prevents lba=0 from being accessed.

note: when tried to read the data via

write profile:

[root@jnode-phy1 ~]# cat /tmp/vdbenchProfile_NR_PROFILE_brickdrm1190_1525077488.14.conf

data_errors=1

monitor=/tmp/vdb_terminate_profile_xbrickdrm1190

data_errors=1

validate=yes

journal=noflush

messagescan=no

hd=LGDRM2383,system=LGDRM2383,vdbench=/usr/bin,shell=ssh,user=root,jvms=10

sd=sd1,host=LGDRM2383,lun=/dev/mapper/3514f0c5876000071,openflags=o_direct,size=106729937305,journal=/tmp/

wd=profile,sd=*,seekpct=100,rdpct=30,xfersize=8k

rd=NR,iorate=56678.4,wd=*,interval=2,forthreads=8,maxdata=9.81706810514e+11,dist=e

verification profile:

cat /tmp/vdbenchProfile_NR_PROFILE_brickdrm1190_1525078324.57.conf

monitor=/tmp/vdb_terminate_profile_xbrickdrm1190

data_errors=1

journal=(recover,only)

messagescan=no

hd=LGDRM2383,system=LGDRM2383,vdbench=/usr/bin,shell=ssh,user=root,jvms=10

sd=sd1,host=LGDRM2383,lun=/dev/mapper/3514f0c5876000071,openflags=o_direct,size=106729937305,journal=/tmp/

rd=rd1,sd=sd*,iorate=max,rdpct=100,elapsed=60h,seekpct=-1,xfersize=1024

errors:

Copyright (c) 2000, 2016, Oracle and/or its affiliates. All rights reserved.

Vdbench distribution: vdbench50406 Wed July 20 15:49:52 MDT 2016

For documentation, see 'vdbench.pdf'.

12:12:47.861 Created output directory '/tmp/vdb_read'

12:12:47.887 input argument scanned: '-f/tmp/vdbenchProfile_NR_PROFILE_xbrickdrm1190_1525078324.57.conf'

12:12:47.887 input argument scanned: '-e600'

12:12:47.888 input argument scanned: '-o/tmp/vdb_read'

12:12:47.888 input argument scanned: '-i1'

12:12:52.580 12:12:52.509 Beginning of run setup

12:12:52.580 12:12:52.509 **********************

12:12:52.580

12:12:52.580

12:12:52.631 12:12:52.560 Allocating a new Journal file for sd=sd1

12:12:52.635 12:12:52.564 12:12:52.564 Starting journal recovery for sd1

12:12:52.636 12:12:52.565 Restoring Data Validation map from: /tmp/sd1.jnl

12:12:52.729 12:12:52.659 Created new mmap file: /tmp/vdbench.sd1.mmap.0 mmap size: 12.425m

12:12:52.730 12:12:52.659 Allocating Data Validation map: 13,028,556 one-byte entries for each 8,192-byte block.

12:12:52.731 12:12:52.660 Created new DV map: sd1 size: 106,729,937,305 bytes; key block size: 8192; entries: 13,028,556

12:12:52.731 12:12:52.661 Starting JournalThread for key block                0 to       13,028,556

12:12:53.338 12:12:53.268 Data Validation map restored for sd1.jnl. Map contains        1,065,900 known blocks.

12:12:53.588 12:12:53.517 Created new mmap file: /tmp/vdbench.sd1.before.mmap.0 mmap size: 12.425m

12:12:53.589 12:12:53.518 Allocating Data Validation map: 13,028,556 one-byte entries for each 8,192-byte block.

12:12:53.590 12:12:53.519 Applying journal records from /tmp/sd1.jnl

12:12:53.591 12:12:53.520 Completed apply of journal records (before/after): 0/0

12:12:53.591 12:12:53.521 12:12:53.520 Completed journal recovery for sd1. Starting data validation.

12:12:53.596 12:12:53.525 Opening sd=sd1,lun=/dev/mapper/3514f0c5876000071; write: false; flags: OpenFlags: 0x00004000 OtherFlags: 0x00000000

12:12:53.615 12:12:53.544 Started 1 Workload Generator threads.

12:12:53.712 12:12:53.641 Started  8 i/o threads for sd1

12:12:53.712 12:12:53.641 Started a total of  8 i/o threads.

12:12:53.712 12:12:53.641 Waiting for task synchronization

12:12:53.730 12:12:53.641 Requesting full garbage collection

12:12:53.730 12:12:53.642 GcTracker: cum:   5 intv:  0 ms:       0 mss:  0.00% Heap_MB max:   911 curr:   258 used:   146 free:   112

12:12:53.756 12:12:53.685 GcTracker: cum:   7 intv:  2 ms:      40 mss:  0.00% Heap_MB max:   911 curr:   261 used:    16 free:   245

12:12:53.756 12:12:53.685 task_wait_start_complete() end

<a name="_1212899836"></a><i><b>12:12:54.003 Starting RD=journal_recovery; I/O rate: Uncontrolled MAX; elapsed=(none); For loops: None</b></i>

12:12:54.310 12:12:54.239 task_run_all(): 9 tasks

12:12:56.429 12:12:56.358 reason1 block0:                0

12:12:56.429 12:12:56.358 reason2 block in error:        0

12:12:56.429 12:12:56.359 reason3 read skipped:          0

12:12:56.429 12:12:56.359 reason4 block in error:        0

12:12:56.429 12:12:56.359 reason5 busy failed:     100,001

12:12:56.430 12:12:56.359

12:12:56.430 12:12:56.359 Loop protection in code that prevents lba=0 from being accessed.

12:12:56.430 12:12:56.359

12:12:56.430 java.lang.RuntimeException: Loop protection in code that prevents lba=0 from being accessed.

12:12:56.570    at Vdb.common.failure(common.java:335)

12:12:56.570    at Vdb.WG_entry.calculateNextLba(WG_entry.java:878)

12:12:56.570    at Vdb.WG_task.createNormalIO(WG_task.java:292)

12:12:56.570    at Vdb.WG_task.run(WG_task.java:162)    

Comments
Locked Post
New comments cannot be posted to this locked post.
Post Details
Locked on May 28 2018
Added on Apr 30 2018
1 comment
871 views