Veritas-bu

[Veritas-bu] FW: Mount Cancelled Detected in tpreq

2006-02-17 07:23:41
Subject: [Veritas-bu] FW: Mount Cancelled Detected in tpreq
From: Asiye.Yigit AT gantek DOT com (Asiye Yiğit)
Date: Fri, 17 Feb 2006 14:23:41 +0200
This is a multi-part message in MIME format.

------=_NextPart_000_008C_01C633CD.C084AAB0
Content-Type: text/plain;
        charset="iso-8859-9"
Content-Transfer-Encoding: 7bit

> Hi Gurus,
> 
> I have cerated vault configuration. I have one robot to use write and
> one standalone
> To read. After I  started the vault policy, I am taking 191. I have
> looked at bptm log.
> I have noticed the following. A00009 is in the standalone drive. It
> will be used to read. 
> A00003 is in a robotic libarry. It will be used to write. In fact, I
> hear the robot to try to
> Do someting. However, the mount operation could not be completede. 
> 
> 
> 13:09:04.241 [8528] <2> db_byid: search for media id A00003
> 13:09:04.241 [8528] <2> db_put: write media id A00003, offset = 3
> 13:09:04.265 [8528] <2> select_media: selected media id A00003 for
> backup[0], jupiter(rl = 1) <----------
> 13:09:04.265 [8528] <2> create_data_socket: writing port numbers -1
> -1, blksize 0 to stderr
> 13:09:04.511 [8533] <2> bptm: INITIATING (VERBOSE = 5): -copy -cmd
> -nosig -everything -cn 1 -c jupiter -b jupiter_114017213
> 4 -port -1 -1 jupiter -L
> /usr/openv/netbackup/vault/sessions/Asiye_Vault/sid12/duplicate.log.1
> -en -ru root -rclnt jupiter 
> -jobid 282 -shm -nounload 
> 13:09:04.515 [8533] <2> nb_getsockconnected: host=jupiter
> service=bpjobd address=10.0.0.33 protocol=tcp non-reserved port=1
> 3723
> 13:09:04.719 [8533] <2> read_backup:
> ************************************************
> 13:09:04.719 [8533] <2> read_backup: media id A00009, copy 1, fragment
> 1 (51840 Kbytes) being considered for duplicate
> 13:09:04.722 [8533] <2> setup_bpbkar_info:
> /usr/openv/netbackup/db/config/shm/jupiter_1140172134 file
> successfully created
> 13:09:04.722 [8533] <2> db_byid: search for media id A00009
> 13:09:04.722 [8533] <2> db_byid: A00009 found at offset 1
> 13:09:04.736 [8533] <2> vmdb_query_byID_getpool: server returned:  1
> A00009 ------ 9 -------- -------- 0 -1 NONE --- - 0 0 
> 0 0 0 root root 1 NetBackup - 1140171764 1140172134 1140172135
> 1140173776 0 0 6 0 0 - 0 0 50 0 0 0 0 0 - 0 0 0 0 0 0 0 0 0 
> 0 0 - 0 0 0 0 0 0 0 0 0 0 0 0 - ------
> 13:09:04.739 [8533] <2> mount_open_media: Waiting for mount of media
> id A00009 (copy 1) on server jupiter.
> 13:09:04.739 [8533] <2> mount_open_media: Media id A00009 is not in a
> robotic library; administrative interaction may be re
> quired to satisfy a mount request
> 13:09:05.213 [8533] <2> io_open: report_attr, fl1 0x00000001, fl2
> 0x00000000
> 13:09:05.239 [8533] <2> io_open: file
> /usr/openv/netbackup/db/media/tpreq/A00009 successfully opened (mode
> 0)
> 13:09:05.241 [8533] <2> io_ioctl: command (5)MTREW 1 from
> (bptm.c.7468) on drive index 1
> 13:09:05.288 [8533] <2> io_ioctl: command (1)MTFSF 1 from
> (bptm.c.7721) on drive index 1
> 13:09:05.301 [8533] <2> set_job_details: Done 
> 13:09:05.301 [8533] <2> read_backup_mount_media: media id A00009
> mounted on drive index 1
> 13:09:05.327 [8533] <2> nb_getsockconnected: host=jupiter service=bpcd
> address=10.0.0.33 protocol=tcp reserved port=13782
> 13:09:05.501 [8533] <2> io_position_for_read: positioning A00009 to
> file number 1
> 13:09:05.501 [8533] <2> io_read_back_header: drive index 1, reading
> backup header
> 13:09:06.280 [8528] <2> setup_dup_shm: SHMID = 47, BUF_PTR =
> 0xfe880000, BUF_CONTROL = 0xfe900000, ReadyPtr = 0xfe9000c0, M
> PX_RES_CNTL = 0xfe9000c4
> 13:09:06.281 [8528] <2> mount_open_media: Waiting for mount of media
> id A00003 (copy 2) on server jupiter.
> 13:09:06.281 [8528] <2> set_job_details: Done 
> 13:09:06.281 [8528] <2> set_job_details: Done 
> 13:09:08.306 [8533] <2> read_backup: copy 1, fragment 1 is the last
> fragment for duplicate
> 13:09:08.335 [8533] <4> read_backup: begin reading backup id
> jupiter_1140172134 (duplicate), copy 1, fragment 1 from media 
> id A00009 on drive index 1
> 13:09:08.335 [8533] <2> read_backup: ********** Using Fragment
> **************************
> 13:09:08.335 [8533] <2> read_backup: fragment_num = 1
> 13:09:08.335 [8533] <2> read_backup: Kbytes = 51840
> 13:09:08.335 [8533] <2> read_backup: remainder = 0
> 13:09:08.335 [8533] <2> read_backup: id = A00009
> 13:09:08.336 [8533] <2> read_backup: file_num = 1
> 13:09:08.336 [8533] <2> read_backup: block_size = 65536
> 13:09:08.336 [8533] <2> read_backup: offset = 0
> 13:09:08.336 [8533] <2> read_backup:
> ****************************************************
> 13:09:12.411 [8528] <2> mount_open_media: mount canceled detected in
> tpreq(), signo = 1
> 13:09:12.412 [8528] <2> set_job_details: Done 
> 13:09:12.439 [8528] <16> mount_open_media: media manager terminated
> during mount of media id A00003, possible media mount t
> imeout
> 13:09:14.448 [8533] <16> catch_signal: media manager terminated by
> parent process
> 13:09:14.478 [8528] <16> catch_signal: media manager terminated by
> parent process
> 13:09:14.478 [8528] <2> tpunmount: Check_for_waiting = 0,
> No_tpunmount_after_restore = 0, Media_Unmount_Delay = 0, MediaOff
> set = 3
> 13:09:14.478 [8528] <2> catch_signal: EXITING with status 82
> 13:09:14.581 [8575] <2> bptm: INITIATING (VERBOSE = 5): -mlist -cmd
> -raw 
> 13:09:14.583 [8575] <2> bptm: EXITING with status 0 <----------
> 

------=_NextPart_000_008C_01C633CD.C084AAB0
Content-Type: text/html;
        charset="iso-8859-9"
Content-Transfer-Encoding: quoted-printable

<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN">
<HTML>
<HEAD>
<META HTTP-EQUIV=3D"Content-Type" CONTENT=3D"text/html; =
charset=3Diso-8859-9">
<META NAME=3D"Generator" CONTENT=3D"MS Exchange Server version =
6.0.4630.0">
<TITLE>FW: Mount Cancelled Detected in tpreq</TITLE>
</HEAD>
<BODY>
<!-- Converted from text/rtf format -->

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">Hi =
Gurus,</FONT></SPAN>
</P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">I have cerated vault =
configuration. I have one robot to use write and one =
standalone</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">To read. After =
I&nbsp; started the vault policy, I am taking 191. I have looked at bptm =
log.</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">I have noticed the =
following. A00009 is in the standalone drive. It will be used to read. =
</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">A00003 is in a =
robotic libarry. It will be used to write. In fact, I hear the robot to =
try to</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">Do someting. =
However, the mount operation could not be completede. </FONT></SPAN>
</P>
<BR>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.241 [8528] =
&lt;2&gt; db_byid: search for media id A00003</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.241 [8528] =
&lt;2&gt; db_put: write media id A00003, offset =3D 3</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#FF0000" SIZE=3D2 =
FACE=3D"Arial">13:09:04.265 [8528] &lt;2&gt; select_media: selected =
media id A00003 for backup[0], jupiter(rl =3D 1) =
&lt;----------</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.265 [8528] =
&lt;2&gt; create_data_socket: writing port numbers -1 -1, blksize 0 to =
stderr</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.511 [8533] =
&lt;2&gt; bptm: INITIATING (VERBOSE =3D 5): -copy -cmd -nosig =
-everything -cn 1 -c jupiter -b jupiter_114017213</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">4 -port -1 -1 jupiter =
-L /usr/openv/netbackup/vault/sessions/Asiye_Vault/sid12/duplicate.log.1 =
-en -ru root -rclnt jupiter </FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">-jobid 282 -shm =
-nounload </FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.515 [8533] =
&lt;2&gt; nb_getsockconnected: host=3Djupiter service=3Dbpjobd =
address=3D10.0.0.33 protocol=3Dtcp non-reserved =
port=3D1</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">3723</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.719 [8533] =
&lt;2&gt; read_backup: =
************************************************</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#FF0000" SIZE=3D2 =
FACE=3D"Arial">13:09:04.719 [8533] &lt;2&gt; read_backup: media id =
A00009, copy 1, fragment 1 (51840 Kbytes) being considered for =
duplicate</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.722 [8533] =
&lt;2&gt; setup_bpbkar_info: =
/usr/openv/netbackup/db/config/shm/jupiter_1140172134 file successfully =
created</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.722 [8533] =
&lt;2&gt; db_byid: search for media id A00009</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.722 [8533] =
&lt;2&gt; db_byid: A00009 found at offset 1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.736 [8533] =
&lt;2&gt; vmdb_query_byID_getpool: server returned:&nbsp; 1 A00009 =
------ 9 -------- -------- 0 -1 NONE --- - 0 0 </FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">0 0 0 root root 1 =
NetBackup - 1140171764 1140172134 1140172135 1140173776 0 0 6 0 0 - 0 0 =
50 0 0 0 0 0 - 0 0 0 0 0 0 0 0 0 </FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">0 0 - 0 0 0 0 0 0 0 0 =
0 0 0 0 - ------</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.739 [8533] =
&lt;2&gt; mount_open_media: Waiting for mount of media id A00009 (copy =
1) on server jupiter.</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:04.739 [8533] =
&lt;2&gt; mount_open_media: Media id A00009 is not in a robotic library; =
administrative interaction may be re</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">quired to satisfy a =
mount request</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.213 [8533] =
&lt;2&gt; io_open: report_attr, fl1 0x00000001, fl2 =
0x00000000</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.239 [8533] =
&lt;2&gt; io_open: file /usr/openv/netbackup/db/media/tpreq/A00009 =
successfully opened (mode 0)</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.241 [8533] =
&lt;2&gt; io_ioctl: command (5)MTREW 1 from (bptm.c.7468) on drive index =
1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.288 [8533] =
&lt;2&gt; io_ioctl: command (1)MTFSF 1 from (bptm.c.7721) on drive index =
1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.301 [8533] =
&lt;2&gt; set_job_details: Done </FONT></SPAN>

<BR><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#FF0000" SIZE=3D2 =
FACE=3D"Arial">13:09:05.301 [8533] &lt;2&gt; read_backup_mount_media: =
media id A00009 mounted on drive index 1</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.327 [8533] =
&lt;2&gt; nb_getsockconnected: host=3Djupiter service=3Dbpcd =
address=3D10.0.0.33 protocol=3Dtcp reserved =
port=3D13782</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.501 [8533] =
&lt;2&gt; io_position_for_read: positioning A00009 to file number =
1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:05.501 [8533] =
&lt;2&gt; io_read_back_header: drive index 1, reading backup =
header</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:06.280 [8528] =
&lt;2&gt; setup_dup_shm: SHMID =3D 47, BUF_PTR =3D 0xfe880000, =
BUF_CONTROL =3D 0xfe900000, ReadyPtr =3D 0xfe9000c0, M</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">PX_RES_CNTL =3D =
0xfe9000c4</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#0000FF" SIZE=3D2 =
FACE=3D"Arial">13:09:06.281 [8528] &lt;2&gt; mount_open_media: Waiting =
for mount of media id A00003 (copy 2) on server =
jupiter.</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:06.281 [8528] =
&lt;2&gt; set_job_details: Done </FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:06.281 [8528] =
&lt;2&gt; set_job_details: Done </FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.306 [8533] =
&lt;2&gt; read_backup: copy 1, fragment 1 is the last fragment for =
duplicate</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#FF0000" SIZE=3D2 =
FACE=3D"Arial">13:09:08.335 [8533] &lt;4&gt; read_backup: begin reading =
backup id jupiter_1140172134 (duplicate), copy 1, fragment 1 from media =
</FONT></B></SPAN></P>

<P><SPAN LANG=3D"tr"><B><FONT COLOR=3D"#FF0000" SIZE=3D2 =
FACE=3D"Arial">id A00009 on drive index 1</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.335 [8533] =
&lt;2&gt; read_backup: ********** Using Fragment =
**************************</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.335 [8533] =
&lt;2&gt; read_backup: fragment_num =3D 1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.335 [8533] =
&lt;2&gt; read_backup: Kbytes =3D 51840</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.335 [8533] =
&lt;2&gt; read_backup: remainder =3D 0</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.335 [8533] =
&lt;2&gt; read_backup: id =3D A00009</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.336 [8533] =
&lt;2&gt; read_backup: file_num =3D 1</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.336 [8533] =
&lt;2&gt; read_backup: block_size =3D 65536</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.336 [8533] =
&lt;2&gt; read_backup: offset =3D 0</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:08.336 [8533] =
&lt;2&gt; read_backup: =
****************************************************</FONT></SPAN>

<BR><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#008000" =
FACE=3D"Arial">13:09:12.411 [8528] &lt;2&gt; mount_open_media: mount =
canceled detected in tpreq(), signo =3D 1</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:12.412 [8528] =
&lt;2&gt; set_job_details: Done </FONT></SPAN>

<BR><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#008000" =
FACE=3D"Arial">13:09:12.439 [8528] &lt;16&gt; mount_open_media: media =
manager terminated during mount of media id A00003, possible media mount =
t</FONT></B></SPAN></P>

<P><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#008000" =
FACE=3D"Arial">imeout</FONT></B></SPAN>

<BR><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#008000" =
FACE=3D"Arial">13:09:14.448 [8533] &lt;16&gt; catch_signal: media =
manager terminated by parent process</FONT></B></SPAN>

<BR><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#008000" =
FACE=3D"Arial">13:09:14.478 [8528] &lt;16&gt; catch_signal: media =
manager terminated by parent process</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:14.478 [8528] =
&lt;2&gt; tpunmount: Check_for_waiting =3D 0, No_tpunmount_after_restore =
=3D 0, Media_Unmount_Delay =3D 0, MediaOff</FONT></SPAN></P>

<P><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">set =3D =
3</FONT></SPAN>

<BR><SPAN LANG=3D"en-gb"><B><FONT COLOR=3D"#FF0000" =
FACE=3D"Arial">13:09:14.478 [8528] &lt;2&gt; catch_signal: EXITING with =
status 82</FONT></B></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:14.581 [8575] =
&lt;2&gt; bptm: INITIATING (VERBOSE =3D 5): -mlist -cmd -raw =
</FONT></SPAN>

<BR><SPAN LANG=3D"tr"><FONT SIZE=3D2 FACE=3D"Arial">13:09:14.583 [8575] =
&lt;2&gt; bptm: EXITING with status 0 &lt;----------</FONT></SPAN>
</P>

</BODY>
</HTML>
------=_NextPart_000_008C_01C633CD.C084AAB0--


<Prev in Thread] Current Thread [Next in Thread>
  • [Veritas-bu] FW: Mount Cancelled Detected in tpreq, Asiye Yiğit <=