Video Screencast Help
Symantec to Separate Into Two Focused, Industry-Leading Technology Companies. Learn more.

PUREDISK 6.5- Error bpbrm(pid=2116) from client xxxx: ERR - Read error at byte 95944704 reading 524288 bytes in file /Storage/data/7205.bin. Errno = 5: Input/output error

Created: 18 Sep 2012 • Updated: 23 Sep 2012 | 2 comments

Hi,

I am encountering this error when doing a DR backup on our Puredisk going to our NBU disk storage.

9/18/2012 12:40:37 AM - Error bpbrm(pid=2116) from client xxxPDOS: ERR - Read error at byte 95944704 reading 524288 bytes in file /Storage/data/7205.bin. Errno = 5: Input/output error
9/18/2012 3:34:39 AM - Info bptm(pid=7056) waited for full buffer 485006 times, delayed 814141 times   
9/18/2012 3:35:02 AM - Info bptm(pid=7056) EXITING with status 0 <----------       
9/18/2012 3:35:02 AM - Info bpbrm(pid=2116) validating image for client xxxPDOS       
9/18/2012 3:35:10 AM - end writing; write time: 05:17:27
9/18/2012 3:35:15 AM - Info bpbkar32(pid=0) done. status: 1: the requested operation was partially successful   
the requested operation was partially successful(1)

The job was successfully completed, but some files may have been
busy or unaccessible. See the problems report or the client's logs for more details.

Two .bin files has this error. Is it ok to delete the .bin files that are causing to partially successful?

Cheers!

Discussion Filed Under:

Comments 2 CommentsJump to latest comment

rsakimoto's picture

Here's additional job log from the Puredisk server side via its gui site.

Execute WFAction: Wait Previous Jobs in Workflow
All older policyruns finished.

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280613
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:25 am
endtimejobstep        = September 17, 2012, 10:25 am
workflowstepname      = WaitForWorkflows
status                = SUCCESS

Execute WFAction: If
Param: "{{$drbkpchoice}}"=="nbu"
Evaluate: "nbu"=="nbu"
Result: TRUE

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280614
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:25 am
endtimejobstep        = September 17, 2012, 10:25 am
workflowstepname      = if(Use NetBackup for metadata)
status                = SUCCESS

Execute WFAction: If
Param: {{$agenthasspa}}==1
Evaluate: 1==1
Result: TRUE

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280615
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:25 am
endtimejobstep        = September 17, 2012, 10:25 am
workflowstepname      = if(SPA)
status                = SUCCESS

[2012-Sep-17 10:25:42 PHT] *** Start: DRBackupStoragePoolAuthority ***
[2012-Sep-17 10:25:42 PHT]Setting Content Router Mode to: DEREF=holdforward
 [2012-Sep-17 10:25:43 PHT]Setting Content Router Mode to: DEREF=hold
[2012-Sep-17 10:25:43 PHT]backupDatabase: Executing => su pddb -c "/opt/pddb/bin/pg_dump -Fc -b ca" | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/META/0/1494/1494000000/ca/20120917102541/4" -d "DB ca" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:28:53 PHT]backupDatabase: completed database backup
[2012-Sep-17 10:28:53 PHT]backupLDAPDatabase: dumping LDAP database
[2012-Sep-17 10:28:53 PHT]backupLDAPDatabase: Executing => /bin/tar -cf /Storage/tmp/DRldapdb.1494.1494000000.tar /Storage/tmp/ldap_dumpdir
[2012-Sep-17 10:28:53 PHT]backupLDAPDatabase: Executing => cat /Storage/tmp/DRldapdb.1494.1494000000.tar | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/0/1494/1494000000/ldapdb/20120917102541/4" -d "DB ldapdb" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:31:44 PHT]backupLDAPDatabase: Deleting the tar file
[2012-Sep-17 10:31:44 PHT]backupLDAPDatabase: completed database backup
[2012-Sep-17 10:31:45 PHT]backupVxAT: Executing => /bin/tar -cf /Storage/tmp/DRvxat.1494.1494000000.tar /var/VRTSat/.VRTSat/profile/VRTSatlocal.conf /var/VRTSat/.VRTSat/profile/certstore /var/VRTSat/RBAuthSource /var/VRTSat/ABAuthSource /etc/vx/vss/VRTSat.conf
[2012-Sep-17 10:31:45 PHT]backupVxAT: Executing => cat /Storage/tmp/DRvxat.1494.1494000000.tar | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/0/1494/1494000000/vxat/20120917102541/4" -d "DB vxat" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:34:36 PHT]backupVxAT: Deleting the tar file
[2012-Sep-17 10:34:36 PHT]backupVxAT: completed database backup
[2012-Sep-17 10:34:36 PHT]backupTopology: Executing => /bin/tar -cvf /Storage/tmp/topology.tar /Storage/etc/topology.ini /Storage/etc/topology_nodes.ini
[2012-Sep-17 10:34:36 PHT]backupTopology: Executing => cat /Storage/tmp/topology.tar | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/META/0/TOPOLOGY/1494/20120917102541/4" -d "STORAGEPOOL_TOPOLOGY" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:37:31 PHT]backupTopology: completed topology backup
[2012-Sep-17 10:37:31 PHT]
[2012-Sep-17 10:37:31 PHT] *** Stop: DRBackupStoragePoolAuthority ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280616
agentid               = 1494000000
hostname              = xxxPDOS

starttimejobstep      = September 17, 2012, 10:25 am
endtimejobstep        = September 17, 2012, 10:37 am
workflowstepname      = Backup SPA Database
status                = SUCCESS

Execute WFAction: Wait WorkflowStep
All Jobs in Policyrun reached this State

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280624
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:37 am
endtimejobstep        = September 17, 2012, 10:37 am
workflowstepname      = WaitWorkflowStep 3
status                = SUCCESS

[2012-Sep-17 10:37:35 PHT] *** Start: DRBackupConfigurationFiles ***
[2012-Sep-17 10:37:35 PHT] Performing backup of configuration files
[2012-Sep-17 10:37:35 PHT] Executing => tar -cvf /Storage/tmp/DRcfg.1494.1494000000.tar /etc/puredisk/*.cfg /etc/puredisk/smb.conf /Storage/etc/ldap /Storage/tmp/1494_1494000000_DRlastrun.tmp
[2012-Sep-17 10:37:36 PHT] backupDatabase: Executing => cat /Storage/tmp/DRcfg.1494.1494000000.tar | /opt/pdag/bin/nbpde  -P xxxPDOS_DR_Datastore -p "/PDE/META/0/1494/1494000000/CFG/20120917102541/4" -d "CFG Files" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:40:31 PHT] Deleting the tar file
[2012-Sep-17 10:40:31 PHT] Completed backup configuration file
[2012-Sep-17 10:40:31 PHT] *** Stop: DRBackupConfigurationFiles ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280625
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:37 am
endtimejobstep        = September 17, 2012, 10:40 am
workflowstepname      = Backup Configuration Files
status                = SUCCESS

Execute WFAction: If
Param: {{$agenthasmbe}}==1
Evaluate: 1==1
Result: TRUE

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280626
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:40 am
endtimejobstep        = September 17, 2012, 10:40 am
workflowstepname      = if(MBE)
status                = SUCCESS

[2012-Sep-17 10:40:36 PHT] *** Start: DRBackupMetaBaseEngine ***
[2012-Sep-17 10:40:36 PHT]backupDatabase: Executing => su pddb -c "/opt/pddb/bin/pg_dump -Fc -b mb" | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/META/0/1494/1494000000/mb/20120917102541/4" -d "DB mb" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 10:55:42 PHT]backupDatabase: completed database backup
[2012-Sep-17 10:55:42 PHT] *** Stop: DRBackupMetaBaseEngine ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280627
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:40 am
endtimejobstep        = September 17, 2012, 10:55 am
workflowstepname      = Backup MBE Database
status                = SUCCESS

Execute WFAction: Wait WorkflowStep
All Jobs in Policyrun reached this State

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280628
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:55 am
endtimejobstep        = September 17, 2012, 10:55 am
workflowstepname      = WaitWorkflowStep 6
status                = SUCCESS

Execute WFAction: If
Param: {{$agenthascr}}==1
Evaluate: 1==1
Result: TRUE

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280629
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:55 am
endtimejobstep        = September 17, 2012, 10:55 am
workflowstepname      = if(CR)
status                = SUCCESS

 *** Start: DRPrepareCR ***
[2012-Sep-17 10:55:50 PHT]Full DR Backup
/Storage
[2012-Sep-17 10:55:51 PHT] Setting Content Router Mode to: DELETES=no
 [2012-Sep-17 10:55:51 PHT] Wait till all contentrouters have processed the delete marker.

[2012-Sep-17 10:55:51 PHT]
[2012-Sep-17 10:55:51 PHT] Setting Content Router Mode to: STORAGED=no
[2012-Sep-17 10:55:54 PHT] Setting Content Router Mode to: PUT=holdforward, SYSTEM=holdforward
[2012-Sep-17 10:55:54 PHT] Setting Content Router Mode to: PUT=hold, SYSTEM=hold
[2012-Sep-17 10:55:54 PHT] Synchronize all tlogs.
[2012-Sep-17 10:55:55 PHT] *** Stop: DRPrepareCR ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280630
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:55 am
endtimejobstep        = September 17, 2012, 10:55 am
workflowstepname      = PrepareCR
status                = SUCCESS

Execute WFAction: Wait untill all Jobs with a certain Processor reached this step.
All Jobs in Policyrun reached this Step

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280631
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:56 am
endtimejobstep        = September 17, 2012, 10:56 am
workflowstepname      = WaitForProcessors 10
status                = SUCCESS

[2012-Sep-17 10:56:01 PHT] *** Start: DRBackupCRSpool ***
[2012-Sep-17 10:56:01 PHT] Generating list of files to backup in the spool area.
[2012-Sep-17 10:56:01 PHT] Backup Spool data
[2012-Sep-17 10:56:01 PHT]
*** Start: DRNBUDisasterRecovery.backupCRSpool ***
[2012-Sep-17 10:56:02 PHT]
Backup up of the Spool Area in progress.......
[2012-Sep-17 10:57:18 PHT]
The Spool Area backup was successful
[2012-Sep-17 10:57:18 PHT]
*** Stop: DRNBUDisasterRecovery.backupCRSpool ***
[2012-Sep-17 10:57:18 PHT] Set counter dr_tlogid to 1554794
[2012-Sep-17 10:57:18 PHT] Set counter dr_taskid to 762989
[2012-Sep-17 10:57:18 PHT] Delete temporary files.
[2012-Sep-17 10:57:18 PHT] *** Stop: DRBackupCRSpool ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280632
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:56 am
endtimejobstep        = September 17, 2012, 10:57 am
workflowstepname      = BackupSpool
status                = SUCCESS

[2012-Sep-17 10:57:19 PHT] *** Start: DRBackupContentRouter ***
[2012-Sep-17 10:57:19 PHT]backupDatabase: Executing => su pddb -c "/opt/pddb/bin/pg_dump -Fc -b crdb" | /opt/pdag/bin/nbpde -P xxxPDOS_DR_Datastore -p "/PDE/META/0/1494/1494000000/crdb/20120917102541/4" -d "DB crdb" -k "20120917102541" -C "xxxPDOS" -backup
[2012-Sep-17 12:04:02 PHT]backupDatabase: completed database backup
[2012-Sep-17 12:04:02 PHT] *** Stop: DRBackupContentRouter ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3280633
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 10:57 am
endtimejobstep        = September 17, 2012, 12:04 pm
workflowstepname      = Backup CR Database
status                = SUCCESS

[12:04:03] *** Start: DRBackupSetSystemState ***

[12:04:03] Setting Content Router Mode to: PUT=yes, SYSTEM=yes
 
[12:04:03] *** Stop: DRBackupSetSystemState ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3281782
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 12:04 pm
endtimejobstep        = September 17, 2012, 12:04 pm
workflowstepname      = Set System State
status                = SUCCESS

Execute WFAction: Wait untill all Jobs with a certain Processor reached this step.
All Jobs in Policyrun reached this Step

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3281783
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 12:04 pm
endtimejobstep        = September 17, 2012, 12:04 pm
workflowstepname      = WaitForProcessors 11
status                = SUCCESS

 *** Start: DRSetMode1 ***
[2012-Sep-17 12:04:15 PHT] Setting Content Router Mode to: STORAGED=yes
 [2012-Sep-17 12:04:16 PHT] *** Stop: DRSetMode1 ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3281784
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 12:04 pm
endtimejobstep        = September 17, 2012, 12:04 pm
workflowstepname      = SetMode1
status                = SUCCESS

Execute WFAction: Wait untill all Jobs with a certain Processor reached this step.
All Jobs in Policyrun reached this Step

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3281785
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 12:04 pm
endtimejobstep        = September 17, 2012, 12:04 pm
workflowstepname      = WaitForProcessors 12
status                = SUCCESS

[2012-Sep-17 12:04:26 PHT] ***  Start: DRBackupCRData ***
[2012-Sep-17 12:04:26 PHT] Full Backup
[2012-Sep-17 12:04:26 PHT] Reading storage directory
[2012-Sep-17 12:04:26 PHT]
*** Start: DRNBUDisasterRecovery.backupCRData ***
[2012-Sep-17 12:04:26 PHT]
[12:04:26] Type of backup: full
[2012-Sep-17 12:04:27 PHT]
[12:04:27] Backup of the Content Router data in progress...
[2012-Sep-17 12:04:27 PHT]
[12:04:27] Saving extended attributes
EXIT STATUS 23: socket read failed
EXIT STATUS 1: the requested operation was partially successful
EXIT STATUS 1: the requested operation was partially successful
*** Error Message ***

severity: 6
server: 1494000000
source: DRBackupCRData_DRBackupCRData
description:
 Backup Failed : Unable to backup the CR Data
*** End ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3281786
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 17, 2012, 12:04 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = BackupData
status                = ERROR

 *** Start: DRSetModeError ***
[2012-Sep-18 16:48:23 PHT]Stop Content Router DR Backup Mode
 [2012-Sep-18 16:48:24 PHT] Setting Content Router Mode to: GET=YES,PUT=yes,STORAGED=yes,REROUTE=no,DEREF=yes,SYSTEM=YES
[2012-Sep-18 16:48:24 PHT] Setting Content Router Mode to: DELETE=yes
[2012-Sep-18 16:48:25 PHT] *** Stop: DRSetModeError ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3283231
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 18, 2012, 4:48 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = SetModeError
status                = SUCCESS

[2012-Sep-18 16:48:26 PHT] *** Start: DRRemoveBackupFiles ***
[2012-Sep-18 16:48:26 PHT] DRRemoveBackupFiles: Starting remove backup files
[2012-Sep-18 16:48:26 PHT] removeBackupFiles: Executing => /opt/pdag/bin/nbpde  -p "/PDE/META/0/1494/1494000000/*" -k "20120917102541" -C "xxxPDOS" -delete
[2012-Sep-18 16:48:32 PHT] 
[2012-Sep-18 16:48:32 PHT] removeBackupFiles: Executing => /opt/pdag/bin/nbpde  -p "/PDE/META/0/TOPOLOGY/*" -k "20120917102541" -C "xxxPDOS" -delete
[2012-Sep-18 16:48:34 PHT] DRRemoveBackupFiles: completed remove file
[2012-Sep-18 16:48:34 PHT] *** Stop: DRRemoveBackupFiles ***

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3283232
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 18, 2012, 4:48 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = Remove Backup Files
status                = SUCCESS

Kill Jobs in PolicyRun that are not in state error yet.

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3283233
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 18, 2012, 4:48 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = Kill All Jobs In Policyrun
status                = SUCCESS

Execute WFAction: Mark Error
Job exited with error.

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3283234
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 18, 2012, 4:48 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = MarkError
status                = SUCCESS

Execute WFAction: Exit
 Job exited with 1 errors, 0 warnings, 23 successes

 *** Supportability Summary ***
jobid                 = 648000
jobstepid             = 3283235
agentid               = 1494000000
hostname              = xxxPDOS
starttimejobstep      = September 18, 2012, 4:48 pm
endtimejobstep        = September 18, 2012, 4:48 pm
workflowstepname      = Exit
status                = SUCCESS

rsakimoto's picture

Any Puredisk Expert out there who can help with my problem below encountered while doing a DR backup? :)

2013-Feb-27 17:20:33 PHT] *** Start: DRBackupCRData ***
[2013-Feb-27 17:20:33 PHT] Full Backup
[2013-Feb-27 17:20:33 PHT] Reading storage directory
[2013-Feb-27 17:20:33 PHT]
*** Start: DRNBUDisasterRecovery.backupCRData ***
[2013-Feb-27 17:20:33 PHT]
[17:20:33] Type of backup: full
[2013-Feb-27 17:20:34 PHT]
[17:20:34] Backup of the Content Router data in progress...
[2013-Feb-27 17:20:34 PHT]
[17:20:34] Saving extended attributes
EXIT STATUS 1: the requested operation was partially successful
EXIT STATUS 1: the requested operation was partially successful
EXIT STATUS 1: the requested operation was partially successful
*** Error Message ***

severity: 6
server: 1494000000
source: DRBackupCRData_DRBackupCRData
description:
Backup Failed : Unable to backup the CR Data
*** End ***

*** Supportability Summary ***
jobid = 673089
jobstepid = 3376465
agentid = 1494000000
hostname = xxxPDOS2
starttimejobstep = February 27, 2013, 5:20 pm
endtimejobstep = February 28, 2013, 11:05 pm
workflowstepname = BackupData
status = ERROR