2 Replies Latest reply: Sep 11, 2012 2:24 PM by rdoogan-Oracle RSS

    Filesystem Restore is getting failed "NDMP server reported a general error"

    veijar
      When i performing filesystem restore to different location, its getting failed with the error message "NDMP server reported a general error (name not found?)" whereas restoring
      in the same location is getting success without any error.

      Please find the attached transcript output for the failed job with debug on.


      ob>catxcr -fl0 admin/80
      2012/09/04.13:17:33 ______________________________________________________________________
      2012/09/04.13:17:33
      2012/09/04.13:17:33 Transcript for job admin/80 running on backup-server
      2012/09/04.13:17:33
      2012/09/04.13:17:33 (amh) qdv__automount_in_mh entered
      2012/09/04.13:17:33 (amh) qdv__automount_in_mh tape at 2012/09/04.13:17:33, flags 0x100
      2012/09/04.13:17:33 (amh) mount volume options list contains:
      2012/09/04.13:17:33 (amh) vtype 1 (rd), vid DC-ORCL-MF-000001, vs_create 1346566310, family (null), retain (null), size 0,

      mediainfo 2, scratch 0
      2012/09/04.13:17:34 (amh) don't preserve previous mh automount state
      2012/09/04.13:17:34 (gep) getting reservation for element 0x1 (dte)
      2012/09/04.13:17:34 (una) unload_anywhere entered
      2012/09/04.13:17:34 (fal) find_and_load entered
      2012/09/04.13:17:34 (fal) calling find_vid2 for volume DC-ORCL-MF-000001
      2012/09/04.13:17:34 (fal) find_vid2 worked - volume DC-ORCL-MF-000001 in se11 (not in drive)
      2012/09/04.13:17:34 (fal) moving volume FL-MF-000001 from se11 to dte1 (tape)
      2012/09/04.13:18:12 (fal) load of tape worked; returning to do automount
      2012/09/04.13:18:12 (fal) find_and_load exited
      2012/09/04.13:18:12 (atv) qdv__automount_this_vol entered
      2012/09/04.13:18:12 (atv) calling qdv__mount
      2012/09/04.13:18:12 (mt) qdv__read_mount_db() succeeded, found vol_oid 0
      2012/09/04.13:18:20 (mt) qdv__read_label() succeeded; read 65536 bytes
      2012/09/04.13:18:20 (mt) exp time obtained from label
      2012/09/04.13:18:20 (mt) qdb__label_event() returned vol_oid 137
      2012/09/04.13:18:20 (mt) setting vol_oid in mount_info to 137
      2012/09/04.13:18:20 (mt) updated volume close time from db
      2012/09/04.13:18:20 (atv) qdv__mount succeeded
      2012/09/04.13:18:20 (atv) automount worked
      2012/09/04.13:18:20 (atv) qdv__automount_this_vol exited
      2012/09/04.13:18:20 (gep) getting reservation for element 0x1 (dte)
      2012/09/04.13:18:20 (amh) 0 automount worked - returning
      2012/09/04.13:18:20 (amh) end of automount at 2012/09/04.13:18:20 (0x0)
      2012/09/04.13:18:20 (amh) returning from qdv__automount_in_mh
      2012/09/04.13:18:20 Info: volume in tape is usable for this operation.
      13:18:20 OBTR: obtar version 10.4.0.1.0 (Solaris) -- Fri Sep 23 23:41:16 PDT 2011
      Copyright (c) 1992, 2011, Oracle. All rights reserved.
      13:18:20 OBTR: obtar -Xjob:admin/80 -Xob:10.4 -xOz -Xbga:admin/80 -JJJJv -y /usr/tmp/admin@80.obstat -Xrdf:admin/80 -e DC-ORCL-

      MF-000001 -F3 -f tape -Xrescookie:0xBE1A8F2 -H client01 -u
      13:18:20 RRDF: restore "/wdn/file01" as "/restore", pos 000043290003
      13:18:20 OBTR: running as root/root
      13:18:20 OBTR: record storage set to internal memory
      13:18:20 ATAL: reserved drive tape, cookie 0xBE1A8F2
      13:18:20 OBTR: obsd=1, is_job=1, is_priv=0, os=3
      13:18:20 OBTR: rights established for user admin, class admin
      13:18:20 SUUI: user info root/root, ??/??
      13:18:21 MAIN: using blocking factor 128 from media defaults/policies
      13:18:21 STTY: background terminal I/O or is a tty
      13:18:21 MAIN: interactive
      13:18:21 DOLM: nop (for tape (raw device "/dev/obt1"))
      13:18:21 DOLM: ok
      13:18:22 RLE: connecting to volume/archive database host
      13:18:22 RLE: device tape (raw device "/dev/obt1")
      13:18:22 RLE: mount_info is valid
      13:18:22 RLE: qdb__device_spec_se reports vol_oid 0, arch_oid 0
      13:18:22 A_O: using max blocking factor 128 from media defaults/policies
      13:18:22 A_O: tape device is local
      13:18:22 A_O: Devname: HP,Ultrium 4-SCSI,H61W
      13:18:22 Info version: 11
      13:18:22 WS version: 10.4
      13:18:22 Driver version: 10.4
      13:18:22 Max DMA: 2097152
      13:18:22 Blocksize in use: 65536
      13:18:22 Query frequency: 134217728
      13:18:22 Rewind on close: false
      13:18:22 Can compress: true
      13:18:22 Compression enabled: true
      13:18:22 Device supports encryption: true
      13:18:22 8200 media: false
      13:18:22 Remaining tape: 819375104

      13:18:22 A_GB: ar_block at 0x100352000, size=2097152
      13:18:22 A_GB: ar_block_enc at 0x100554000, size=2097152
      13:18:22 ADMS: reset library tape selection state
      13:18:22 ADMS: reset complete
      13:18:22 GLMT: returning "", code = 0x0
      13:18:22 VLBR: from chk_lm_tag: "", code = 0x0
      13:18:22 VLBR: tag on label just read: ""
      13:18:22 VLBR: master tag now ""
      13:18:22 RLE: noticed volume TEST-MF-000001, file 1, section 1, vltime 1346566310, vowner root, voltag
      13:18:22 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 369
      (alv) backup image label is valid, file 1, section 1
      (ial) invalidate backup image label (was valid)
      13:18:22 RSMD: rewrote mount db for tape
      13:18:22 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:18:22 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:18:22 CALE: created backup section oid list entry for oid 369
      13:18:22 PF: here's the label at the current position:
      Volume label:
      Intro time: Fri May 04 13:35:03 2012
      Volume UUID: d40ea6c6-d6c2-102f-bf51-da716418c062
      Volume ID: TEST-MF-000001
      Volume sequence: 1
      Volume set owner: root
      Volume set created: Sun Sep 02 11:56:50 2012
      Volume set closes: Sat Dec 01 11:56:50 2012 (no writes after this time)
      Volume set expires: Sat Mar 02 11:56:50 2013
      Media family: TEST-MF
      Original UUID: d40ea6c6-d6c2-102f-bf51-da716418c062

      Archive label:
      File number: 1
      File section: 1
      Owner: root
      Client host: client01
      Backup level: 0
      S/w compression: no
      Archive created: Sun Sep 02 11:56:50 2012
      Archive owner UUID: f32ac938-6410-102f-a3d5-b94c4468403b
      Owner class UUID: f32a3504-6410-102f-a3d5-b94c4468403b
      Encryption: off

      Searching tape for requested file. Please wait...
      13:18:22 PF: spacing forward 2 FMs
      13:18:24 VLBR: not at bot: 0x90000000
      13:18:24 VLBR: tag on label just read: ""
      13:18:24 VLBR: master tag now ""
      13:18:24 RLE: noticed volume TEST-MF-000001, file 3, section 1, vltime 1346566310, vowner root, voltag
      13:18:24 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 380
      (alv) backup image label is not valid
      13:18:24 ULVI: set mh db volume id "TEST-MF-000001" (retid ""), volume oid 137, code 0
      13:18:24 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:18:24 CALE: created backup section oid list entry for oid 380
      13:18:24 VLBR: setting last section flag for backup section oid 369
      13:18:24 PF: here's the label at the current position:
      Volume label:
      Intro time: Fri May 04 13:35:03 2012
      Volume UUID: d40ea6c6-d6c2-102f-bf51-da716418c062
      Volume ID: TEST-MF-000001
      Volume sequence: 1
      Volume set owner: root
      Volume set created: Sun Sep 02 11:56:50 2012
      Volume set closes: Sat Dec 01 11:56:50 2012 (no writes after this time)
      Volume set expires: Sat Mar 02 11:56:50 2013
      Media family: TEST-MF
      Original UUID: d40ea6c6-d6c2-102f-bf51-da716418c062

      Archive label:
      File number: 3
      File section: 1
      Owner: root
      Client host: client01
      Backup level: 0
      S/w compression: no
      Archive created: Tue Sep 04 11:53:17 2012
      Archive owner UUID: f32ac938-6410-102f-a3d5-b94c4468403b
      Owner class UUID: f32a3504-6410-102f-a3d5-b94c4468403b
      Encryption: off

      13:18:24 PF: at desired location

      13:18:24 ACFD: positioning (SCSI LOCATE) is available for this device
      13:18:24 ADMS: reset library tape selection state
      13:18:24 ADMS: reset complete
      13:18:24 VLBR: not at bot: 0x90000000
      13:18:24 VLBR: tag on label just read: ""
      13:18:24 VLBR: master tag now ""
      13:18:24 RLE: noticed volume DC-ORCL-MF-000001, file 3, section 1, vltime 1346566310, vowner root, voltag
      13:18:24 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 380
      (alv) backup image label is not valid
      13:18:25 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:18:25 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:18:25 CALE: found existing backup section oid list entry for oid 380
      13:18:25 ADMS: reset library tape selection state
      13:18:25 ADMS: reset complete
      13:18:25 RLE: read volume DC-ORCL-MF-000001, file 3, section 1, vltime 1346566310, vowner root, voltag
      13:18:25 RLE: qdb__read_se reports vol_oid 137, arch_oid 380
      (alv) backup image label is not valid
      13:18:25 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:18:25 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:18:25 PTNI: positioning to "/wdn/file01" at 000043290003
      13:18:27 CNPC: data host reports this butype_info:
      13:18:27 CNPC: tar (attr 0x2C78: B_DIRECT, R_DIRECT, B_INCR, R_INCR, B_FH_DIR)
      13:18:27 CNPC: DIRECT = y
      13:18:27 CNPC: HISTORY = y
      13:18:27 CNPC: LEVEL = 0
      13:18:28 SNP: connection types supported by mover -
      13:18:28 tcp
      13:18:28 SNP: connection types supported by data service -
      13:18:28 tcp
      13:18:28 SNP: selected data connection type: tcp
      13:18:28 SNP: using separate data and tape/mover connections
      13:18:28 SNP: the NDMP protocol version for tape/mover is 4, for data is 4
      13:18:28 SNP: backup-server's NDMP tape/mover service session id is 7844
      13:18:28 RONPC: begin OSB NDMP data restore
      13:18:28 RONPC: need to restore from "/wdn/file01" tree:
      13:18:28 RONPC: tape position before restore is 000043290004
      13:18:28 MGS: ms.record_size 65536, ms.record_num 0x0, ms.bytes_moved 0x0
      13:18:28 RONPC: about to start restore; here are the environment variables:
      13:18:28 RONPC: env BEGINTREE=1
      13:18:28 RONPC: env NAME=/wdn/file01
      13:18:28 RONPC: env AS=/restore
      13:18:28 RONPC: env POSLEN=6
      13:18:28 RONPC: env POS=
      13:18:28 RONPC: env BLEVEL=0
      13:18:28 RONPC: env FIRSTCH=1
      13:18:28 RONPC: env POS_HERE=1
      13:18:28 RONPC: env EX2KTYPE=
      13:18:28 RONPC: env DATA_BLOCK_SIZE=64
      13:18:28 RONPC: env SKIP_RECORDS=3
      13:18:28 RONPC: env LABEL_VERSION=0000012
      13:18:28 SMW: setting NDMP mover window to offset 0x0, length 0xFFFFFFFFFFFFFFFF
      13:18:28 MLIS: mover listen ok for tcp connection; these addresses were reported:
      13:18:28 MLIS: 0.0.0.0:58243
      13:18:28 MLIS: 192.168.3.109:58243
      13:18:28 RONPC: tape fileno/blockno before restore are 0/0
      13:18:28 APNI: a preferred network interface does not apply to this connection
      13:18:28 DPNI: load balancing is in use, skipping default PNI
      13:18:28 RONPC: directing data service to connect to mover
      13:18:01 PPVL: obtar option OB_JOB = admin/80
      13:18:01 PPVL: obtar option OB_RB = 10.4
      13:18:01 PPVL: obtar option OB_EXTR = 1
      13:18:01 PPVL: obtar option OB_EXTRACT_ONCE = 1
      13:18:01 PPVL: obtar option OB_DEBUG = 1
      13:18:01 PPVL: obtar option OB_DEBUG = 1
      13:18:01 PPVL: obtar option OB_DEBUG = 1
      13:18:01 PPVL: obtar option OB_DEBUG = 1
      13:18:01 PPVL: obtar option OB_VERBOSE = 1
      13:18:01 PPVL: obtar option OB_CLIENT = client01
      13:18:01 PPVL: obtar option OB_HONOR_IN_USE_LOCK = 1
      13:18:01 PPVL: obtar option OB_STAT = 1
      13:18:01 PPVL: obtar option OB_VOLUME_LABEL = 1
      13:18:01 PPVL: obtar option OB_SKIP_CDFS = 1
      13:18:01 PPVL: obtar option OB_DEVICE = tape
      13:18:01 PPVL: obtar option OB_BLOCKING_FACTOR = 128
      13:18:01 PPVL: obtar option OB_VERIFY_ARCHIVE = no
      13:18:01 PPVL: obtar option OB_PQT = 134217728
      13:18:01 DSIN: 2GB+ files are supported, 2GB+ directories are supported
      13:18:01 SETC: identity is already root/root
      13:18:28 qtarndmp__ssl_setup: SSL has been disabled via the security policy
      13:18:28 RONPC: issuing NDMP_DATA_START_RECOVER
      13:18:33 RONPC: started NDMP restore
      13:18:33 MNPO: received NDMP_NOTIFY_DATA_READ, offset 0x0, length 0xFFFFFFFFFFFFFFFF
      13:18:33 MNPO: sent corresponding NDMP_MOVER_READ
      13:18:33 QTOS: received osb_stats message for job admin/80, kbytes 64, nfiles 0
      13:18:33 await_ndmp_event: sending progress update
      13:18:33 SPU: sending progress update
      Error: Could not make file /restore: Is a directory
      13:19:27 MNPO: jumped over filemark fence
      13:19:27 VLBR: not at bot: 0x90000000
      13:19:27 VLBR: tag on label just read: ""
      13:19:27 QTOS: received osb_stats message for job admin/80, kbytes 3145856, nfiles 0
      13:19:27 VLBR: master tag now ""
      13:19:27 RLE: set kb remaining to 819375104
      13:19:27 RLE: qdb__set_kb_rem_se reports vol_oid 0, arch_oid 0
      13:19:27 RLE: noticed nil label
      13:19:27 RLE: qdb__noticed_se reports vol_oid 0, arch_oid 0
      13:19:27 VLBR: setting last section flag for backup section oid 380
      13:19:27 MNPO: sent successful mover close
      13:19:27 MNPO: data service halted with reason=internal error
      13:19:27 SNPD: Data Service reported bytes processed 0xC0020000
      13:19:27 SNPD: stopping NDMP data service (to transition to idle state)
      13:19:27 MNPO: mover halted with reason=connection closed
      13:19:27 MGS: ms.record_size 65536, ms.record_num 0xC002, ms.bytes_moved 0xC0020000
      Error: NDMP operation failed: unspecified error reported (see above)
      13:19:27 RONPC: finished NDMP restore with status 97
      13:19:27 RONPC: NDMP read-ahead positioned tape past filemark; backing up
      13:19:27 RONPC: We believe this because initial file # 0 isn't end file # 1
      13:19:27 RONPC: the section-relative block number at end of restore is 0x1
      13:19:27 RONPC: tape position after restore is 0001032B0080
      13:19:27 QREX: exit status upon entry is 97
      13:19:27 QREX: released reservation on tape drive tape
      13:19:27 RDB: reading volume record for oid 137
      13:19:27 RDB: reading section record for oid 369
      13:19:27 RDB: adding record for oid 369 (file 1, section 1) to section list
      13:19:27 RDB: reading section record for oid 378
      13:19:27 RDB: adding record for oid 378 (file 2, section 1) to section list
      13:19:27 RDB: reading section record for oid 380
      13:19:27 RDB: adding record for oid 380 (file 3, section 1) to section list
      13:19:27 RDB: file 1 has all 1 required sections; clearing incomplete backup flags
      13:19:27 RDB: reading section record for oid 369
      13:19:27 RDB: file 2 has all 1 required sections; clearing incomplete backup flags
      13:19:27 RDB: reading section record for oid 378
      13:19:27 RDB: file 3 has all 1 required sections; clearing incomplete backup flags
      13:19:27 RDB: reading section record for oid 380
      13:19:27 RDB: 1 volumes in volume list
      13:19:27 RDB: volume oid 137 reports first:last files of 1:3
      13:19:27 RDB: marking volume oid 137 as authoritative
      13:19:27 VMA: reading volume record for oid 137
      13:19:27 RLYX: exit status 97; checking allocs...
      13:19:27 RLYX: from mm__check_all: 1
      ---------------------------------------------------------------------------------------------------------------------------------
      ob> catxcr -fl0 admin/81
      2012/09/04.13:19:29 ______________________________________________________________________
      2012/09/04.13:19:29
      2012/09/04.13:19:29 Transcript for job admin/81 running on backup-server
      2012/09/04.13:19:29
      2012/09/04.13:19:30 Info: mount data verified.
      2012/09/04.13:19:30 Info: volume in tape is usable for this operation.
      13:19:31 OBTR: obtar version 10.4.0.1.0 (Solaris) -- Fri Sep 23 23:41:16 PDT 2011
      Copyright (c) 1992, 2011, Oracle. All rights reserved.
      13:19:31 OBTR: obtar -Xjob:admin/81 -Xob:10.4 -xOz -Xbga:admin/81 -JJJJv -y /usr/tmp/admin@81.obstat -Xrdf:admin/81 -e DC-ORCL-

      MF-000001 -F1 -f tape -Xrescookie:0xBE1A8F6 -H client01 -u
      13:19:31 RRDF: restore "/wdn/testf" as "/restore", pos 000000010003
      13:19:31 OBTR: running as root/root
      13:19:31 OBTR: record storage set to internal memory
      13:19:31 ATAL: reserved drive tape, cookie 0xBE1A8F6
      13:19:31 OBTR: obsd=1, is_job=1, is_priv=0, os=3
      13:19:31 OBTR: rights established for user admin, class admin
      13:19:31 SUUI: user info root/root, ??/??
      13:19:31 MAIN: using blocking factor 128 from media defaults/policies
      13:19:31 STTY: background terminal I/O or is a tty
      13:19:31 MAIN: interactive
      13:19:31 DOLM: nop (for tape (raw device "/dev/obt1"))
      13:19:31 DOLM: ok
      13:19:32 RLE: connecting to volume/archive database host
      13:19:32 RLE: device tape (raw device "/dev/obt1")
      13:19:32 RLE: mount_info is valid
      13:19:32 RLE: qdb__device_spec_se reports vol_oid 0, arch_oid 0
      13:19:32 A_O: using max blocking factor 128 from media defaults/policies
      13:19:32 A_O: tape device is local
      13:19:32 A_O: Devname: HP,Ultrium 4-SCSI,H61W
      13:19:32 Info version: 11
      13:19:32 WS version: 10.4
      13:19:32 Driver version: 10.4
      13:19:32 Max DMA: 2097152
      13:19:32 Blocksize in use: 65536
      13:19:32 Query frequency: 134217728
      13:19:32 Rewind on close: false
      13:19:32 Can compress: true
      13:19:32 Compression enabled: true
      13:19:32 Device supports encryption: true
      13:19:32 8200 media: false
      13:19:32 Remaining tape: 819375104

      13:19:32 A_GB: ar_block at 0x100352000, size=2097152
      13:19:32 A_GB: ar_block_enc at 0x100554000, size=2097152
      13:19:32 ADMS: reset library tape selection state
      13:19:32 ADMS: reset complete
      13:19:35 ACFD: positioning (SCSI LOCATE) is available for this device
      13:19:35 GLMT: returning "", code = 0x0
      13:19:35 VLBR: from chk_lm_tag: "", code = 0x0
      13:19:35 VLBR: tag on label just read: ""
      13:19:35 VLBR: master tag now ""
      13:19:35 RLE: noticed volume DC-ORCL-MF-000001, file 1, section 1, vltime 1346566310, vowner root, voltag
      13:19:35 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 369
      (alv) backup image label is valid, file 4, section 1
      (ial) invalidate backup image label (was valid)
      13:19:35 RSMD: rewrote mount db for tape
      13:19:35 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:19:35 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:19:35 CALE: created backup section oid list entry for oid 369
      13:19:35 PF: here's the label at the current position:
      Volume label:
      Intro time: Fri May 04 13:35:03 2012
      Volume UUID: d40ea6c6-d6c2-102f-bf51-da716418c062
      Volume ID: DC-ORCL-MF-000001
      Volume sequence: 1
      Volume set owner: root
      Volume set created: Sun Sep 02 11:56:50 2012
      Volume set closes: Sat Dec 01 11:56:50 2012 (no writes after this time)
      Volume set expires: Sat Mar 02 11:56:50 2013
      Media family: DC-ORCL-MF
      Original UUID: d40ea6c6-d6c2-102f-bf51-da716418c062

      Archive label:
      File number: 1
      File section: 1
      Owner: root
      Client host: client01
      Backup level: 0
      S/w compression: no
      Archive created: Sun Sep 02 11:56:50 2012
      Archive owner UUID: f32ac938-6410-102f-a3d5-b94c4468403b
      Owner class UUID: f32a3504-6410-102f-a3d5-b94c4468403b
      Encryption: off

      13:19:35 PF: at desired location
      13:19:35 BT: resid is 1
      13:19:35 ACFD: positioning (SCSI LOCATE) is available for this device
      13:19:35 ADMS: reset library tape selection state
      13:19:35 ADMS: reset complete
      13:19:35 GLMT: returning "", code = 0x0
      13:19:35 VLBR: from chk_lm_tag: "", code = 0x0
      13:19:35 VLBR: tag on label just read: ""
      13:19:35 VLBR: master tag now ""
      13:19:35 RLE: noticed volume DC-ORCL-MF-000001, file 1, section 1, vltime 1346566310, vowner root, voltag
      13:19:35 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 369
      (alv) backup image label is not valid
      13:19:35 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:19:35 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:19:35 CALE: found existing backup section oid list entry for oid 369
      13:19:35 ADMS: reset library tape selection state
      13:19:35 ADMS: reset complete
      13:19:35 RLE: read volume DC-ORCL-MF-000001, file 1, section 1, vltime 1346566310, vowner root, voltag
      13:19:35 RLE: qdb__read_se reports vol_oid 137, arch_oid 369
      (alv) backup image label is not valid
      13:19:35 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:19:36 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:19:36 PTNI: positioning to "/wdn/testf" at 000000010003
      13:19:37 CNPC: data host reports this butype_info:
      13:19:37 CNPC: tar (attr 0x2C78: B_DIRECT, R_DIRECT, B_INCR, R_INCR, B_FH_DIR)
      13:19:37 CNPC: DIRECT = y
      13:19:37 CNPC: HISTORY = y
      13:19:37 CNPC: LEVEL = 0
      13:19:38 SNP: connection types supported by mover -
      13:19:38 tcp
      13:19:38 SNP: connection types supported by data service -
      13:19:38 tcp
      13:19:38 SNP: selected data connection type: tcp
      13:19:38 SNP: using separate data and tape/mover connections
      13:19:38 SNP: the NDMP protocol version for tape/mover is 4, for data is 4
      13:19:38 SNP: backup-server's NDMP tape/mover service session id is 7935
      13:19:38 RONPC: begin OSB NDMP data restore
      13:19:38 RONPC: need to restore from "/wdn/testf" tree:
      13:19:38 RONPC: tape position before restore is 000000010004
      13:19:38 MGS: ms.record_size 65536, ms.record_num 0x0, ms.bytes_moved 0x0
      13:19:38 RONPC: about to start restore; here are the environment variables:
      13:19:38 RONPC: env BEGINTREE=1
      13:19:38 RONPC: env NAME=/wdn/testf
      13:19:38 RONPC: env AS=/restore
      13:19:38 RONPC: env POSLEN=6
      13:19:38 RONPC: env POS=
      13:19:38 RONPC: env BLEVEL=0
      13:19:38 RONPC: env FIRSTCH=1
      13:19:38 RONPC: env POS_HERE=1
      13:19:38 RONPC: env EX2KTYPE=
      13:19:38 RONPC: env DATA_BLOCK_SIZE=64
      13:19:38 RONPC: env SKIP_RECORDS=3
      13:19:38 RONPC: env LABEL_VERSION=0000012
      13:19:38 SMW: setting NDMP mover window to offset 0x0, length 0xFFFFFFFFFFFFFFFF
      13:19:38 MLIS: mover listen ok for tcp connection; these addresses were reported:
      13:19:38 MLIS: 192.168.3.109:58303
      13:19:38 MLIS: 0.0.0.0:58303
      13:19:38 RONPC: tape fileno/blockno before restore are 0/0
      13:19:38 APNI: a preferred network interface does not apply to this connection
      13:19:38 DPNI: load balancing is in use, skipping default PNI
      13:19:38 RONPC: directing data service to connect to mover
      13:19:11 PPVL: obtar option OB_JOB = admin/81
      13:19:11 PPVL: obtar option OB_RB = 10.4
      13:19:11 PPVL: obtar option OB_EXTR = 1
      13:19:11 PPVL: obtar option OB_EXTRACT_ONCE = 1
      13:19:11 PPVL: obtar option OB_DEBUG = 1
      13:19:11 PPVL: obtar option OB_DEBUG = 1
      13:19:11 PPVL: obtar option OB_DEBUG = 1
      13:19:11 PPVL: obtar option OB_DEBUG = 1
      13:19:11 PPVL: obtar option OB_VERBOSE = 1
      13:19:11 PPVL: obtar option OB_CLIENT = client01
      13:19:11 PPVL: obtar option OB_HONOR_IN_USE_LOCK = 1
      13:19:11 PPVL: obtar option OB_STAT = 1
      13:19:11 PPVL: obtar option OB_VOLUME_LABEL = 1
      13:19:11 PPVL: obtar option OB_SKIP_CDFS = 1
      13:19:11 PPVL: obtar option OB_DEVICE = tape
      13:19:11 PPVL: obtar option OB_BLOCKING_FACTOR = 128
      13:19:11 PPVL: obtar option OB_VERIFY_ARCHIVE = no
      13:19:11 PPVL: obtar option OB_PQT = 134217728
      13:19:11 DSIN: 2GB+ files are supported, 2GB+ directories are supported
      13:19:11 SETC: identity is already root/root
      13:19:38 qtarndmp__ssl_setup: SSL has been disabled via the security policy
      13:19:38 RONPC: issuing NDMP_DATA_START_RECOVER
      13:19:43 RONPC: started NDMP restore
      13:19:43 MNPO: received NDMP_NOTIFY_DATA_READ, offset 0x0, length 0xFFFFFFFFFFFFFFFF
      13:19:43 MNPO: sent corresponding NDMP_MOVER_READ
      13:19:43 QTOS: received osb_stats message for job admin/81, kbytes 64, nfiles 0
      13:19:43 await_ndmp_event: sending progress update
      13:19:43 SPU: sending progress update
      /restore
      Error: Could not make file /restore: Is a directory
      13:19:44 MNPO: jumped over filemark fence
      13:19:44 VLBR: not at bot: 0x90000000
      13:19:44 VLBR: tag on label just read: ""
      13:19:44 QTOS: received osb_stats message for job admin/81, kbytes 51328, nfiles 0
      13:19:44 VLBR: master tag now ""
      13:19:44 RLE: noticed volume DC-ORCL-MF-000001, file 2, section 1, vltime 1346566310, vowner root, voltag
      13:19:44 RLE: qdb__noticed_se reports vol_oid 137, arch_oid 378
      (alv) backup image label is not valid
      13:19:45 ULVI: set mh db volume id "DC-ORCL-MF-000001" (retid ""), volume oid 137, code 0
      13:19:45 ULTG: set mh db tag "" (retid "DC-ORCL-MF-000001"), volume oid 137, code 0
      13:19:45 CALE: created backup section oid list entry for oid 378
      13:19:45 VLBR: setting last section flag for backup section oid 369
      13:19:45 MNPO: sent successful mover close
      13:19:45 MNPO: data service halted with reason=internal error
      13:19:45 SNPD: Data Service reported bytes processed 0x3220000
      13:19:45 SNPD: stopping NDMP data service (to transition to idle state)
      13:19:45 MNPO: mover halted with reason=connection closed
      13:19:45 MGS: ms.record_size 65536, ms.record_num 0x322, ms.bytes_moved 0x3220000
      Error: NDMP operation failed: unspecified error reported (see above)
      13:19:45 RONPC: finished NDMP restore with status 97
      13:19:45 RONPC: NDMP read-ahead positioned tape past filemark; backing up
      13:19:45 RONPC: We believe this because initial file # 0 isn't end file # 1
      13:19:45 RONPC: the section-relative block number at end of restore is 0x1
      13:19:45 RONPC: tape position after restore is 000003230080
      13:19:45 QREX: exit status upon entry is 97
      13:19:45 QREX: released reservation on tape drive tape
      13:19:45 RDB: reading volume record for oid 137
      13:19:45 RDB: reading section record for oid 369
      13:19:45 RDB: adding record for oid 369 (file 1, section 1) to section list
      13:19:45 RDB: reading section record for oid 378
      13:19:45 RDB: adding record for oid 378 (file 2, section 1) to section list
      13:19:45 RDB: reading section record for oid 380
      13:19:45 RDB: adding record for oid 380 (file 3, section 1) to section list
      13:19:45 RDB: file 1 has all 1 required sections; clearing incomplete backup flags
      13:19:45 RDB: reading section record for oid 369
      13:19:45 RDB: file 2 has all 1 required sections; clearing incomplete backup flags
      13:19:45 RDB: reading section record for oid 378
      13:19:45 RDB: file 3 has all 1 required sections; clearing incomplete backup flags
      13:19:45 RDB: reading section record for oid 380
      13:19:45 RDB: 1 volumes in volume list
      13:19:45 RDB: volume oid 137 reports first:last files of 1:3
      13:19:45 RDB: marking volume oid 137 as authoritative
      13:19:45 VMA: reading volume record for oid 137
      13:19:45 RLYX: exit status 97; checking allocs...
      13:19:45 RLYX: from mm__check_all: 1
      ob>
      --------------------------------------------------------------------------------------------------------------

      Please help me to resolve the issue...

      Thanks,
      Sam