15:08:32.421 [3349] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5  2010042404
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.421 [3349] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size
15:08:32.421 [3349] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 
15:08:32.421 [3349] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:32.421 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.421 [3349] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:32.421 [3349] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - returning - 5
15:08:32.422 [3349] <2> sbtinit: INF - entering
15:08:32.422 [3349] <2> xbsa_GetEnv: INF - entering
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu
15:08:32.422 [3349] <2> xbsa_GetEnv: INF - leaving (0)

15:08:32.422 [3349] <2> xbsa_GetEnv: INF - entering
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:32.422 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:32.422 [3349] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

15:08:32.422 [3349] <2> xbsa_GetEnv: INF - leaving (80)

15:08:32.422 [3349] <2> int_GetOracleVersion: INF - entering
15:08:32.422 [3349] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
15:08:32.422 [3349] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
15:08:32.422 [3349] <2> int_GetOracleVersion: INF - leaving
15:08:32.422 [3349] <2> int_LogSystemInfo: INF - entering
15:08:32.422 [3349] <2> int_LogSystemInfo: INF - 
Veritas NetBackup for Oracle - Release 6.5 (2007072323)
	System name:	SunOS
	Node name:	romepres-sol10
	Release:	5.10
	Version:	Generic_Virtual
	Machine:	sun4v
	User name:  	oracle
	Client Host: 	romepres-sol10-bu

15:08:32.422 [3349] <2> int_LogSystemInfo: INF - leaving
15:08:32.422 [3349] <2> int_GetMMInfo: INF - entering
15:08:32.422 [3349] <2> int_GetMMInfo: INF - Initialized Signal
15:08:32.422 [3349] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
15:08:32.422 [3349] <2> int_GetMMInfo: INF - leaving
15:08:32.422 [3349] <2> int_InitializeSbtFunctionPointers: INF - entering
15:08:32.422 [3349] <2> int_InitializeSbtFunctionPointers: INF - leaving
15:08:32.422 [3349] <2> sbtinit: INF - leaving
15:08:32.423 [3349] <2> sbtinit2: INF - entering
15:08:32.423 [3349] <2> sbtinit2: INF - leaving
15:08:37.423 [3349] <2> sbtcommand: INF - entering
15:08:37.423 [3349] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:37.424 [3349] <2> int_ProcessCommandString: INF - entering
15:08:37.424 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:37.424 [3349] <2> int_ProcessCommand: INF - entering
15:08:37.424 [3349] <2> xbsa_SetEnv: INF - entering
15:08:37.424 [3349] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:37.424 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:37.424 [3349] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:37.424 [3349] <2> int_ProcessCommand: INF - leaving
15:08:37.424 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:37.424 [3349] <2> int_ProcessCommand: INF - entering
15:08:37.424 [3349] <2> xbsa_SetEnv: INF - entering
15:08:37.424 [3349] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:37.424 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:37.424 [3349] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:37.424 [3349] <2> int_ProcessCommand: INF - leaving
15:08:37.424 [3349] <2> int_ProcessCommandString: INF - leaving
15:08:37.424 [3349] <2> sbtcommand: INF - leaving
15:08:37.587 [3352] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5  2010042404
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.587 [3352] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size
15:08:37.587 [3352] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 
15:08:37.587 [3352] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.587 [3352] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:37.587 [3352] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:37.587 [3352] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - returning - 5
15:08:37.588 [3352] <2> sbtinit: INF - entering
15:08:37.588 [3352] <2> xbsa_GetEnv: INF - entering
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu
15:08:37.588 [3352] <2> xbsa_GetEnv: INF - leaving (0)

15:08:37.588 [3352] <2> xbsa_GetEnv: INF - entering
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:37.588 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:37.588 [3352] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

15:08:37.588 [3352] <2> xbsa_GetEnv: INF - leaving (80)

15:08:37.588 [3352] <2> int_GetOracleVersion: INF - entering
15:08:37.588 [3352] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
15:08:37.588 [3352] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
15:08:37.588 [3352] <2> int_GetOracleVersion: INF - leaving
15:08:37.588 [3352] <2> int_LogSystemInfo: INF - entering
15:08:37.588 [3352] <2> int_LogSystemInfo: INF - 
Veritas NetBackup for Oracle - Release 6.5 (2007072323)
	System name:	SunOS
	Node name:	romepres-sol10
	Release:	5.10
	Version:	Generic_Virtual
	Machine:	sun4v
	User name:  	oracle
	Client Host: 	romepres-sol10-bu

15:08:37.588 [3352] <2> int_LogSystemInfo: INF - leaving
15:08:37.588 [3352] <2> int_GetMMInfo: INF - entering
15:08:37.588 [3352] <2> int_GetMMInfo: INF - Initialized Signal
15:08:37.588 [3352] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
15:08:37.588 [3352] <2> int_GetMMInfo: INF - leaving
15:08:37.588 [3352] <2> int_InitializeSbtFunctionPointers: INF - entering
15:08:37.588 [3352] <2> int_InitializeSbtFunctionPointers: INF - leaving
15:08:37.588 [3352] <2> sbtinit: INF - leaving
15:08:37.588 [3352] <2> sbtinit2: INF - entering
15:08:37.588 [3352] <2> sbtinit2: INF - leaving
15:08:42.425 [3349] <2> sbtcommand: INF - entering
15:08:42.425 [3349] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:42.425 [3349] <2> int_ProcessCommandString: INF - entering
15:08:42.425 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:42.425 [3349] <2> int_ProcessCommand: INF - entering
15:08:42.425 [3349] <2> xbsa_SetEnv: INF - entering
15:08:42.425 [3349] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:42.425 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:42.425 [3349] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:42.425 [3349] <2> int_ProcessCommand: INF - leaving
15:08:42.425 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:42.425 [3349] <2> int_ProcessCommand: INF - entering
15:08:42.425 [3349] <2> xbsa_SetEnv: INF - entering
15:08:42.425 [3349] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:42.425 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:42.425 [3349] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:42.425 [3349] <2> int_ProcessCommand: INF - leaving
15:08:42.425 [3349] <2> int_ProcessCommandString: INF - leaving
15:08:42.425 [3349] <2> sbtcommand: INF - leaving
15:08:42.589 [3352] <2> sbtcommand: INF - entering
15:08:42.589 [3352] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:42.589 [3352] <2> int_ProcessCommandString: INF - entering
15:08:42.589 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:42.590 [3352] <2> int_ProcessCommand: INF - entering
15:08:42.590 [3352] <2> xbsa_SetEnv: INF - entering
15:08:42.590 [3352] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:42.590 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:42.590 [3352] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:42.590 [3352] <2> int_ProcessCommand: INF - leaving
15:08:42.590 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:42.590 [3352] <2> int_ProcessCommand: INF - entering
15:08:42.590 [3352] <2> xbsa_SetEnv: INF - entering
15:08:42.590 [3352] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:42.590 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:42.590 [3352] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:42.590 [3352] <2> int_ProcessCommand: INF - leaving
15:08:42.590 [3352] <2> int_ProcessCommandString: INF - leaving
15:08:42.590 [3352] <2> sbtcommand: INF - leaving
15:08:42.751 [3355] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5  2010042404
15:08:42.751 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API
15:08:42.751 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.751 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID
15:08:42.751 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.751 [3355] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size
15:08:42.751 [3355] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 
15:08:42.752 [3355] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.752 [3355] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:42.752 [3355] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - 5
15:08:42.752 [3355] <2> sbtinit: INF - entering
15:08:42.752 [3355] <2> xbsa_GetEnv: INF - entering
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu
15:08:42.752 [3355] <2> xbsa_GetEnv: INF - leaving (0)

15:08:42.752 [3355] <2> xbsa_GetEnv: INF - entering
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:42.752 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:42.752 [3355] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

15:08:42.752 [3355] <2> xbsa_GetEnv: INF - leaving (80)

15:08:42.752 [3355] <2> int_GetOracleVersion: INF - entering
15:08:42.752 [3355] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
15:08:42.752 [3355] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
15:08:42.752 [3355] <2> int_GetOracleVersion: INF - leaving
15:08:42.752 [3355] <2> int_LogSystemInfo: INF - entering
15:08:42.752 [3355] <2> int_LogSystemInfo: INF - 
Veritas NetBackup for Oracle - Release 6.5 (2007072323)
	System name:	SunOS
	Node name:	romepres-sol10
	Release:	5.10
	Version:	Generic_Virtual
	Machine:	sun4v
	User name:  	oracle
	Client Host: 	romepres-sol10-bu

15:08:42.752 [3355] <2> int_LogSystemInfo: INF - leaving
15:08:42.753 [3355] <2> int_GetMMInfo: INF - entering
15:08:42.753 [3355] <2> int_GetMMInfo: INF - Initialized Signal
15:08:42.753 [3355] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
15:08:42.753 [3355] <2> int_GetMMInfo: INF - leaving
15:08:42.753 [3355] <2> int_InitializeSbtFunctionPointers: INF - entering
15:08:42.753 [3355] <2> int_InitializeSbtFunctionPointers: INF - leaving
15:08:42.753 [3355] <2> sbtinit: INF - leaving
15:08:42.753 [3355] <2> sbtinit2: INF - entering
15:08:42.753 [3355] <2> sbtinit2: INF - leaving
15:08:47.426 [3349] <2> sbtcommand: INF - entering
15:08:47.426 [3349] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:47.426 [3349] <2> int_ProcessCommandString: INF - entering
15:08:47.426 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:47.426 [3349] <2> int_ProcessCommand: INF - entering
15:08:47.426 [3349] <2> xbsa_SetEnv: INF - entering
15:08:47.426 [3349] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:47.426 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.426 [3349] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:47.426 [3349] <2> int_ProcessCommand: INF - leaving
15:08:47.427 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:47.427 [3349] <2> int_ProcessCommand: INF - entering
15:08:47.427 [3349] <2> xbsa_SetEnv: INF - entering
15:08:47.427 [3349] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:47.427 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.427 [3349] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:47.427 [3349] <2> int_ProcessCommand: INF - leaving
15:08:47.427 [3349] <2> int_ProcessCommandString: INF - leaving
15:08:47.427 [3349] <2> sbtcommand: INF - leaving
15:08:47.591 [3352] <2> sbtcommand: INF - entering
15:08:47.591 [3352] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:47.591 [3352] <2> int_ProcessCommandString: INF - entering
15:08:47.591 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:47.591 [3352] <2> int_ProcessCommand: INF - entering
15:08:47.591 [3352] <2> xbsa_SetEnv: INF - entering
15:08:47.591 [3352] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:47.591 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.591 [3352] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:47.591 [3352] <2> int_ProcessCommand: INF - leaving
15:08:47.591 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:47.591 [3352] <2> int_ProcessCommand: INF - entering
15:08:47.591 [3352] <2> xbsa_SetEnv: INF - entering
15:08:47.591 [3352] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:47.591 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.592 [3352] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:47.592 [3352] <2> int_ProcessCommand: INF - leaving
15:08:47.592 [3352] <2> int_ProcessCommandString: INF - leaving
15:08:47.592 [3352] <2> sbtcommand: INF - leaving
15:08:47.753 [3355] <2> sbtcommand: INF - entering
15:08:47.753 [3355] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:47.753 [3355] <2> int_ProcessCommandString: INF - entering
15:08:47.754 [3355] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:47.754 [3355] <2> int_ProcessCommand: INF - entering
15:08:47.754 [3355] <2> xbsa_SetEnv: INF - entering
15:08:47.754 [3355] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:47.754 [3355] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.754 [3355] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:47.754 [3355] <2> int_ProcessCommand: INF - leaving
15:08:47.754 [3355] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:47.754 [3355] <2> int_ProcessCommand: INF - entering
15:08:47.754 [3355] <2> xbsa_SetEnv: INF - entering
15:08:47.754 [3355] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:47.754 [3355] <2> xbsa_SetEnv: INF - leaving (0)

15:08:47.754 [3355] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:47.754 [3355] <2> int_ProcessCommand: INF - leaving
15:08:47.754 [3355] <2> int_ProcessCommandString: INF - leaving
15:08:47.754 [3355] <2> sbtcommand: INF - leaving
15:08:47.905 [3358] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5  2010042404
15:08:47.905 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API
15:08:47.905 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.905 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID
15:08:47.905 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.905 [3358] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size
15:08:47.905 [3358] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 
15:08:47.906 [3358] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.906 [3358] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:47.906 [3358] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - 5
15:08:47.906 [3358] <2> sbtinit: INF - entering
15:08:47.906 [3358] <2> xbsa_GetEnv: INF - entering
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu
15:08:47.906 [3358] <2> xbsa_GetEnv: INF - leaving (0)

15:08:47.906 [3358] <2> xbsa_GetEnv: INF - entering
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:08:47.906 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:47.906 [3358] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

15:08:47.907 [3358] <2> xbsa_GetEnv: INF - leaving (80)

15:08:47.907 [3358] <2> int_GetOracleVersion: INF - entering
15:08:47.907 [3358] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
15:08:47.907 [3358] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
15:08:47.907 [3358] <2> int_GetOracleVersion: INF - leaving
15:08:47.907 [3358] <2> int_LogSystemInfo: INF - entering
15:08:47.907 [3358] <2> int_LogSystemInfo: INF - 
Veritas NetBackup for Oracle - Release 6.5 (2007072323)
	System name:	SunOS
	Node name:	romepres-sol10
	Release:	5.10
	Version:	Generic_Virtual
	Machine:	sun4v
	User name:  	oracle
	Client Host: 	romepres-sol10-bu

15:08:47.907 [3358] <2> int_LogSystemInfo: INF - leaving
15:08:47.907 [3358] <2> int_GetMMInfo: INF - entering
15:08:47.907 [3358] <2> int_GetMMInfo: INF - Initialized Signal
15:08:47.907 [3358] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
15:08:47.907 [3358] <2> int_GetMMInfo: INF - leaving
15:08:47.907 [3358] <2> int_InitializeSbtFunctionPointers: INF - entering
15:08:47.907 [3358] <2> int_InitializeSbtFunctionPointers: INF - leaving
15:08:47.907 [3358] <2> sbtinit: INF - leaving
15:08:47.907 [3358] <2> sbtinit2: INF - entering
15:08:47.907 [3358] <2> sbtinit2: INF - leaving
15:08:52.427 [3349] <2> sbtcommand: INF - entering
15:08:52.428 [3349] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:52.428 [3349] <2> int_ProcessCommandString: INF - entering
15:08:52.428 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:52.428 [3349] <2> int_ProcessCommand: INF - entering
15:08:52.428 [3349] <2> xbsa_SetEnv: INF - entering
15:08:52.428 [3349] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:52.428 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.428 [3349] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:52.428 [3349] <2> int_ProcessCommand: INF - leaving
15:08:52.428 [3349] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:52.428 [3349] <2> int_ProcessCommand: INF - entering
15:08:52.428 [3349] <2> xbsa_SetEnv: INF - entering
15:08:52.428 [3349] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:52.428 [3349] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.428 [3349] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:52.428 [3349] <2> int_ProcessCommand: INF - leaving
15:08:52.428 [3349] <2> int_ProcessCommandString: INF - leaving
15:08:52.428 [3349] <2> sbtcommand: INF - leaving
15:08:52.592 [3352] <2> sbtcommand: INF - entering
15:08:52.592 [3352] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:52.592 [3352] <2> int_ProcessCommandString: INF - entering
15:08:52.592 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:52.592 [3352] <2> int_ProcessCommand: INF - entering
15:08:52.592 [3352] <2> xbsa_SetEnv: INF - entering
15:08:52.592 [3352] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:52.593 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.593 [3352] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:52.593 [3352] <2> int_ProcessCommand: INF - leaving
15:08:52.593 [3352] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:52.593 [3352] <2> int_ProcessCommand: INF - entering
15:08:52.593 [3352] <2> xbsa_SetEnv: INF - entering
15:08:52.593 [3352] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:52.593 [3352] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.593 [3352] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:52.593 [3352] <2> int_ProcessCommand: INF - leaving
15:08:52.593 [3352] <2> int_ProcessCommandString: INF - leaving
15:08:52.593 [3352] <2> sbtcommand: INF - leaving
15:08:52.755 [3355] <2> sbtcommand: INF - entering
15:08:52.755 [3355] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:52.755 [3355] <2> int_ProcessCommandString: INF - entering
15:08:52.755 [3355] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:52.755 [3355] <2> int_ProcessCommand: INF - entering
15:08:52.755 [3355] <2> xbsa_SetEnv: INF - entering
15:08:52.755 [3355] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:52.755 [3355] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.755 [3355] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:52.755 [3355] <2> int_ProcessCommand: INF - leaving
15:08:52.755 [3355] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:52.755 [3355] <2> int_ProcessCommand: INF - entering
15:08:52.755 [3355] <2> xbsa_SetEnv: INF - entering
15:08:52.755 [3355] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:52.755 [3355] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.756 [3355] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:52.756 [3355] <2> int_ProcessCommand: INF - leaving
15:08:52.756 [3355] <2> int_ProcessCommandString: INF - leaving
15:08:52.756 [3355] <2> sbtcommand: INF - leaving
15:08:52.908 [3358] <2> sbtcommand: INF - entering
15:08:52.908 [3358] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
15:08:52.908 [3358] <2> int_ProcessCommandString: INF - entering
15:08:52.908 [3358] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
15:08:52.908 [3358] <2> int_ProcessCommand: INF - entering
15:08:52.908 [3358] <2> xbsa_SetEnv: INF - entering
15:08:52.908 [3358] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
15:08:52.908 [3358] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.908 [3358] <2> int_ProcessCommand: INF - Server <atlbackupmaster> will be used for this API session.
15:08:52.908 [3358] <2> int_ProcessCommand: INF - leaving
15:08:52.908 [3358] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_CLIENT> cmd_val=<rome_pres-bu>
15:08:52.908 [3358] <2> int_ProcessCommand: INF - entering
15:08:52.908 [3358] <2> xbsa_SetEnv: INF - entering
15:08:52.908 [3358] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST
15:08:52.908 [3358] <2> xbsa_SetEnv: INF - leaving (0)

15:08:52.908 [3358] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
15:08:52.908 [3358] <2> int_ProcessCommand: INF - leaving
15:08:52.908 [3358] <2> int_ProcessCommandString: INF - leaving
15:08:52.908 [3358] <2> sbtcommand: INF - leaving
15:08:53.428 [3349] <2> sbtinfo2: INF - entering
15:08:53.428 [3349] <2> sbtinfo2: INF - requesting image info for <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:53.428 [3349] <2> int_GetImageInfo2: INF - entering
15:08:53.428 [3349] <2> int_GetImageInfo2: INF - Image Information for file <iwhprod_20180120090005_ctl_86296_1_965940097> :
15:08:53.429 [3349] <2> xbsa_ValidateFeatureId: INF - entering
15:08:53.429 [3349] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 
15:08:53.429 [3349] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 
15:08:53.429 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:53.429 [3349] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:53.429 [3349] <2> ParseConfigExA: Option (CLIENT_NAME) redefined on line 5, discarding definition on line 4.
15:08:53.429 [3349] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:53.430 [3349] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:53.430 [3349] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:53.455 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:53.455 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:53.500 [3349] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:53.500 [3349] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.61651 TO 10.10.36.33.13724 fd = 19
15:08:53.500 [3349] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.61651 TO 10.10.36.33.13724
15:08:53.500 [3349] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:53.500 [3349] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:53.500 [3349] <2> bprd_connect: no authentication required
15:08:53.500 [3349] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:53.578 [3349] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId
15:08:53.578 [3349] <2> xbsa_ValidateFeatureId: INF - leaving (0)

15:08:53.578 [3349] <2> int_FindBackupImage: INF - entering
15:08:53.578 [3349] <2> int_GetBfsDateRange: INF - entering
15:08:53.578 [3349] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097
15:08:53.578 [3349] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile
15:08:53.578 [3349] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967648133
15:08:53.578 [3349] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518293333
15:08:53.578 [3349] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37
15:08:53.578 [3349] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37
15:08:53.578 [3349] <2> int_logDateRange: INF - leaving
15:08:53.578 [3349] <2> xbsa_BeginTransaction: INF - entering
15:08:53.578 [3349] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - returning - Oracle
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:08:53.578 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:53.579 [3349] <2> xbsa_BeginTransaction: INF - leaving (0)

15:08:53.579 [3349] <2> xbsa_QueryObject: INF - entering
15:08:53.579 [3349] <4> VxBSAQueryObject: INF - entering QueryObject.
15:08:53.579 [3349] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:53.579 [3349] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:08:53.579 [3349] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:08:53.579 [3349] <4> VxBSAGetEnv: INF - returning - 
15:08:53.579 [3349] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName.
15:08:53.579 [3349] <4> getOwnerName: entering getOwnerName.
15:08:53.579 [3349] <4> BuildBprdRequest: entering BuildBprdRequest.
15:08:53.579 [3349] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:53.579 [3349] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:53.580 [3349] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:53.610 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:53.610 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:53.660 [3349] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:53.660 [3349] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.39475 TO 10.10.36.33.13724 fd = 19
15:08:53.660 [3349] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.39475 TO 10.10.36.33.13724
15:08:53.660 [3349] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:53.660 [3349] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:53.660 [3349] <2> bprd_connect: no authentication required
15:08:53.660 [3349] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:54.154 [3349] <4> dbc_GetMediaListByName: 	Media ID : <AL6608>
15:08:54.154 [3349] <4> dbc_GetMediaListByName: 	File Creation Date and Time : <1516498928>
15:08:54.154 [3349] <4> dbc_GetMediaListByName: 	File Expiration Date and Time: <1524534128>
15:08:54.154 [3349] <4> dbc_GetMediaListByName: 	Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:54.154 [3349] <4> dbc_GetMediaListByName: 	Keyword : <iwhprod>
15:08:54.154 [3349] <4> VxBSAQueryObject: INF - Object </iwhprod_20180120090005_ctl_86296_1_965940097> was found on media <AL6608>.
15:08:54.154 [3349] <2> xbsa_QueryObject: INF - leaving (0)

15:08:54.154 [3349] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928>
15:08:54.154 [3349] <2> xbsa_EndTransaction: INF - entering
15:08:54.154 [3349] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:08:54.154 [3349] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:08:54.154 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:08:54.154 [3349] <4> VxBSAGetEnv: INF - returning - dbclient
15:08:54.154 [3349] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:08:54.154 [3349] <4> delete_old_files: entering delete_old_files.
15:08:54.155 [3349] <2> xbsa_EndTransaction: INF - leaving (0)

15:08:54.155 [3349] <2> int_FindBackupImage: INF - leaving
15:08:54.155 [3349] <2> int_SetImageInfoArray: INF - entering
15:08:54.155 [3349] <2> int_SetImageInfoArray: INF - leaving
15:08:54.155 [3349] <2> int_GetImageInfo2: INF - leaving
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - entering
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - Media Information for Backup File : <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - Media Sharing Mode : <Multiple Concurrent Users>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - File Ordering Mode : <Sequential file access>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - Media ID : <AL6608>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - File Creation Method : <Stream>
15:08:54.155 [3349] <2> int_DumpSbtInfo: INF - leaving
15:08:54.155 [3349] <2> sbtinfo2: INF - leaving
15:08:54.593 [3352] <2> sbtinfo2: INF - entering
15:08:54.593 [3352] <2> sbtinfo2: INF - requesting image info for <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:54.593 [3352] <2> int_GetImageInfo2: INF - entering
15:08:54.593 [3352] <2> int_GetImageInfo2: INF - Image Information for file <iwhprod_20180120090005_ctl_86296_1_965940097> :
15:08:54.593 [3352] <2> xbsa_ValidateFeatureId: INF - entering
15:08:54.593 [3352] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 
15:08:54.593 [3352] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 
15:08:54.593 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:54.593 [3352] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:54.594 [3352] <2> ParseConfigExA: Option (CLIENT_NAME) redefined on line 5, discarding definition on line 4.
15:08:54.594 [3352] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle
15:08:54.594 [3352] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:54.594 [3352] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:54.594 [3352] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:54.594 [3352] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:54.594 [3352] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:54.595 [3352] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:54.595 [3352] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:54.595 [3352] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:54.595 [3352] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:54.624 [3352] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:54.624 [3352] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:54.669 [3352] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:54.670 [3352] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.47807 TO 10.10.36.33.13724 fd = 19
15:08:54.670 [3352] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.47807 TO 10.10.36.33.13724
15:08:54.670 [3352] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:54.670 [3352] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:54.670 [3352] <2> bprd_connect: no authentication required
15:08:54.670 [3352] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:54.749 [3352] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId
15:08:54.749 [3352] <2> xbsa_ValidateFeatureId: INF - leaving (0)

15:08:54.750 [3352] <2> int_FindBackupImage: INF - entering
15:08:54.750 [3352] <2> int_GetBfsDateRange: INF - entering
15:08:54.750 [3352] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097
15:08:54.750 [3352] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile
15:08:54.750 [3352] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967648134
15:08:54.750 [3352] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518293334
15:08:54.750 [3352] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37
15:08:54.750 [3352] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37
15:08:54.750 [3352] <2> int_logDateRange: INF - leaving
15:08:54.750 [3352] <2> xbsa_BeginTransaction: INF - entering
15:08:54.750 [3352] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:08:54.750 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - Oracle
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:54.751 [3352] <2> xbsa_BeginTransaction: INF - leaving (0)

15:08:54.751 [3352] <2> xbsa_QueryObject: INF - entering
15:08:54.751 [3352] <4> VxBSAQueryObject: INF - entering QueryObject.
15:08:54.751 [3352] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:54.751 [3352] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:08:54.751 [3352] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:08:54.751 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:54.752 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:54.752 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:08:54.752 [3352] <4> VxBSAGetEnv: INF - returning - 
15:08:54.752 [3352] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName.
15:08:54.752 [3352] <4> getOwnerName: entering getOwnerName.
15:08:54.752 [3352] <4> BuildBprdRequest: entering BuildBprdRequest.
15:08:54.752 [3352] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:54.752 [3352] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:54.753 [3352] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:54.792 [3352] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:54.792 [3352] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:54.840 [3352] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:54.840 [3352] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.55155 TO 10.10.36.33.13724 fd = 19
15:08:54.840 [3352] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.55155 TO 10.10.36.33.13724
15:08:54.840 [3352] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:54.840 [3352] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:54.840 [3352] <2> bprd_connect: no authentication required
15:08:54.840 [3352] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:55.169 [3352] <4> dbc_GetMediaListByName: 	Media ID : <AL6608>
15:08:55.169 [3352] <4> dbc_GetMediaListByName: 	File Creation Date and Time : <1516498928>
15:08:55.169 [3352] <4> dbc_GetMediaListByName: 	File Expiration Date and Time: <1524534128>
15:08:55.169 [3352] <4> dbc_GetMediaListByName: 	Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:55.169 [3352] <4> dbc_GetMediaListByName: 	Keyword : <iwhprod>
15:08:55.169 [3352] <4> VxBSAQueryObject: INF - Object </iwhprod_20180120090005_ctl_86296_1_965940097> was found on media <AL6608>.
15:08:55.169 [3352] <2> xbsa_QueryObject: INF - leaving (0)

15:08:55.169 [3352] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928>
15:08:55.169 [3352] <2> xbsa_EndTransaction: INF - entering
15:08:55.169 [3352] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:08:55.170 [3352] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:08:55.170 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:08:55.170 [3352] <4> VxBSAGetEnv: INF - returning - dbclient
15:08:55.170 [3352] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:08:55.170 [3352] <4> delete_old_files: entering delete_old_files.
15:08:55.170 [3352] <2> xbsa_EndTransaction: INF - leaving (0)

15:08:55.170 [3352] <2> int_FindBackupImage: INF - leaving
15:08:55.170 [3352] <2> int_SetImageInfoArray: INF - entering
15:08:55.170 [3352] <2> int_SetImageInfoArray: INF - leaving
15:08:55.170 [3352] <2> int_GetImageInfo2: INF - leaving
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - entering
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - Media Information for Backup File : <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - Media Sharing Mode : <Multiple Concurrent Users>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - File Ordering Mode : <Sequential file access>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - Media ID : <AL6608>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - File Creation Method : <Stream>
15:08:55.170 [3352] <2> int_DumpSbtInfo: INF - leaving
15:08:55.170 [3352] <2> sbtinfo2: INF - leaving
15:08:55.756 [3355] <2> sbtinfo2: INF - entering
15:08:55.756 [3355] <2> sbtinfo2: INF - requesting image info for <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:55.756 [3355] <2> int_GetImageInfo2: INF - entering
15:08:55.756 [3355] <2> int_GetImageInfo2: INF - Image Information for file <iwhprod_20180120090005_ctl_86296_1_965940097> :
15:08:55.756 [3355] <2> xbsa_ValidateFeatureId: INF - entering
15:08:55.756 [3355] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 
15:08:55.756 [3355] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 
15:08:55.756 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:55.756 [3355] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:55.757 [3355] <2> ParseConfigExA: Option (CLIENT_NAME) redefined on line 5, discarding definition on line 4.
15:08:55.757 [3355] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle
15:08:55.757 [3355] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:55.757 [3355] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:55.757 [3355] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:55.757 [3355] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:55.758 [3355] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:55.758 [3355] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:55.758 [3355] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:55.758 [3355] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:55.758 [3355] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:55.783 [3355] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:55.783 [3355] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:55.829 [3355] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:55.830 [3355] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.52892 TO 10.10.36.33.13724 fd = 19
15:08:55.830 [3355] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.52892 TO 10.10.36.33.13724
15:08:55.830 [3355] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:55.830 [3355] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:55.830 [3355] <2> bprd_connect: no authentication required
15:08:55.830 [3355] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:55.908 [3355] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId
15:08:55.909 [3355] <2> xbsa_ValidateFeatureId: INF - leaving (0)

15:08:55.909 [3355] <2> int_FindBackupImage: INF - entering
15:08:55.909 [3355] <2> int_GetBfsDateRange: INF - entering
15:08:55.909 [3355] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097
15:08:55.909 [3355] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile
15:08:55.909 [3355] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967648135
15:08:55.909 [3355] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518293335
15:08:55.909 [3355] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37
15:08:55.909 [3355] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37
15:08:55.909 [3355] <2> int_logDateRange: INF - leaving
15:08:55.909 [3355] <2> xbsa_BeginTransaction: INF - entering
15:08:55.909 [3355] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - returning - Oracle
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:55.909 [3355] <2> xbsa_BeginTransaction: INF - leaving (0)

15:08:55.909 [3355] <2> xbsa_QueryObject: INF - entering
15:08:55.909 [3355] <4> VxBSAQueryObject: INF - entering QueryObject.
15:08:55.909 [3355] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:55.909 [3355] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:55.909 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:08:55.910 [3355] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:08:55.910 [3355] <4> VxBSAGetEnv: INF - returning - 
15:08:55.910 [3355] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName.
15:08:55.910 [3355] <4> getOwnerName: entering getOwnerName.
15:08:55.910 [3355] <4> BuildBprdRequest: entering BuildBprdRequest.
15:08:55.910 [3355] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:55.910 [3355] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:55.910 [3355] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:55.936 [3355] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:55.936 [3355] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:55.980 [3355] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:55.980 [3355] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.41231 TO 10.10.36.33.13724 fd = 19
15:08:55.980 [3355] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.41231 TO 10.10.36.33.13724
15:08:55.980 [3355] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:55.980 [3355] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:55.980 [3355] <2> bprd_connect: no authentication required
15:08:55.980 [3355] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:56.304 [3355] <4> dbc_GetMediaListByName: 	Media ID : <AL6608>
15:08:56.305 [3355] <4> dbc_GetMediaListByName: 	File Creation Date and Time : <1516498928>
15:08:56.305 [3355] <4> dbc_GetMediaListByName: 	File Expiration Date and Time: <1524534128>
15:08:56.305 [3355] <4> dbc_GetMediaListByName: 	Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:56.305 [3355] <4> dbc_GetMediaListByName: 	Keyword : <iwhprod>
15:08:56.305 [3355] <4> VxBSAQueryObject: INF - Object </iwhprod_20180120090005_ctl_86296_1_965940097> was found on media <AL6608>.
15:08:56.305 [3355] <2> xbsa_QueryObject: INF - leaving (0)

15:08:56.305 [3355] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928>
15:08:56.305 [3355] <2> xbsa_EndTransaction: INF - entering
15:08:56.305 [3355] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:08:56.305 [3355] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:08:56.305 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:08:56.305 [3355] <4> VxBSAGetEnv: INF - returning - dbclient
15:08:56.305 [3355] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:08:56.305 [3355] <4> delete_old_files: entering delete_old_files.
15:08:56.306 [3355] <2> xbsa_EndTransaction: INF - leaving (0)

15:08:56.306 [3355] <2> int_FindBackupImage: INF - leaving
15:08:56.306 [3355] <2> int_SetImageInfoArray: INF - entering
15:08:56.306 [3355] <2> int_SetImageInfoArray: INF - leaving
15:08:56.306 [3355] <2> int_GetImageInfo2: INF - leaving
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - entering
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - Media Information for Backup File : <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - Media Sharing Mode : <Multiple Concurrent Users>
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - File Ordering Mode : <Sequential file access>
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - Media ID : <AL6608>
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928>
15:08:56.306 [3355] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128>
15:08:56.307 [3355] <2> int_DumpSbtInfo: INF - Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:56.307 [3355] <2> int_DumpSbtInfo: INF - File Creation Method : <Stream>
15:08:56.307 [3355] <2> int_DumpSbtInfo: INF - leaving
15:08:56.307 [3355] <2> sbtinfo2: INF - leaving
15:08:56.909 [3358] <2> sbtinfo2: INF - entering
15:08:56.909 [3358] <2> sbtinfo2: INF - requesting image info for <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:56.909 [3358] <2> int_GetImageInfo2: INF - entering
15:08:56.909 [3358] <2> int_GetImageInfo2: INF - Image Information for file <iwhprod_20180120090005_ctl_86296_1_965940097> :
15:08:56.909 [3358] <2> xbsa_ValidateFeatureId: INF - entering
15:08:56.909 [3358] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 
15:08:56.909 [3358] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 
15:08:56.909 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:56.909 [3358] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:56.910 [3358] <2> ParseConfigExA: Option (CLIENT_NAME) redefined on line 5, discarding definition on line 4.
15:08:56.910 [3358] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028
15:08:56.910 [3358] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10
15:08:56.911 [3358] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:56.935 [3358] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:56.935 [3358] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:56.979 [3358] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:56.980 [3358] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.42220 TO 10.10.36.33.13724 fd = 19
15:08:56.980 [3358] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.42220 TO 10.10.36.33.13724
15:08:56.980 [3358] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:56.980 [3358] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:56.980 [3358] <2> bprd_connect: no authentication required
15:08:56.980 [3358] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:57.058 [3358] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId
15:08:57.058 [3358] <2> xbsa_ValidateFeatureId: INF - leaving (0)

15:08:57.058 [3358] <2> int_FindBackupImage: INF - entering
15:08:57.058 [3358] <2> int_GetBfsDateRange: INF - entering
15:08:57.058 [3358] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097
15:08:57.058 [3358] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile
15:08:57.059 [3358] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967648137
15:08:57.059 [3358] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518293337
15:08:57.059 [3358] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37
15:08:57.059 [3358] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37
15:08:57.059 [3358] <2> int_logDateRange: INF - leaving
15:08:57.059 [3358] <2> xbsa_BeginTransaction: INF - entering
15:08:57.059 [3358] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - Oracle
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:57.059 [3358] <2> xbsa_BeginTransaction: INF - leaving (0)

15:08:57.059 [3358] <2> xbsa_QueryObject: INF - entering
15:08:57.059 [3358] <4> VxBSAQueryObject: INF - entering QueryObject.
15:08:57.059 [3358] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:08:57.059 [3358] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:08:57.059 [3358] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:08:57.059 [3358] <4> VxBSAGetEnv: INF - returning - 
15:08:57.059 [3358] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName.
15:08:57.059 [3358] <4> getOwnerName: entering getOwnerName.
15:08:57.060 [3358] <4> BuildBprdRequest: entering BuildBprdRequest.
15:08:57.060 [3358] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:57.060 [3358] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:57.060 [3358] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:08:57.097 [3358] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:08:57.097 [3358] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:08:57.139 [3358] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:08:57.139 [3358] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.35681 TO 10.10.36.33.13724 fd = 19
15:08:57.139 [3358] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.35681 TO 10.10.36.33.13724
15:08:57.139 [3358] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:08:57.140 [3358] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:08:57.140 [3358] <2> bprd_connect: no authentication required
15:08:57.140 [3358] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:08:57.473 [3358] <4> dbc_GetMediaListByName: 	Media ID : <AL6608>
15:08:57.473 [3358] <4> dbc_GetMediaListByName: 	File Creation Date and Time : <1516498928>
15:08:57.473 [3358] <4> dbc_GetMediaListByName: 	File Expiration Date and Time: <1524534128>
15:08:57.473 [3358] <4> dbc_GetMediaListByName: 	Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:57.473 [3358] <4> dbc_GetMediaListByName: 	Keyword : <iwhprod>
15:08:57.473 [3358] <4> VxBSAQueryObject: INF - Object </iwhprod_20180120090005_ctl_86296_1_965940097> was found on media <AL6608>.
15:08:57.473 [3358] <2> xbsa_QueryObject: INF - leaving (0)

15:08:57.473 [3358] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928>
15:08:57.473 [3358] <2> xbsa_EndTransaction: INF - entering
15:08:57.473 [3358] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:08:57.473 [3358] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:08:57.473 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:08:57.473 [3358] <4> VxBSAGetEnv: INF - returning - dbclient
15:08:57.473 [3358] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:08:57.473 [3358] <4> delete_old_files: entering delete_old_files.
15:08:57.473 [3358] <2> xbsa_EndTransaction: INF - leaving (0)

15:08:57.473 [3358] <2> int_FindBackupImage: INF - leaving
15:08:57.473 [3358] <2> int_SetImageInfoArray: INF - entering
15:08:57.474 [3358] <2> int_SetImageInfoArray: INF - leaving
15:08:57.474 [3358] <2> int_GetImageInfo2: INF - leaving
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - entering
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - Media Information for Backup File : <iwhprod_20180120090005_ctl_86296_1_965940097>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - Media Sharing Mode : <Multiple Concurrent Users>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - File Ordering Mode : <Sequential file access>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - Media ID : <AL6608>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - Comment : <Backup ID : rome_pres-bu_1516498928>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - File Creation Method : <Stream>
15:08:57.474 [3358] <2> int_DumpSbtInfo: INF - leaving
15:08:57.474 [3358] <2> sbtinfo2: INF - leaving
15:09:01.156 [3349] <2> sbtrestore: INF - entering
15:09:01.156 [3349] <2> int_StartJob: INF - entering
15:09:01.156 [3349] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_POLICY
15:09:01.156 [3349] <2> int_IsSAPBackup: INF - entering
15:09:01.156 [3349] <2> int_IsSAPBackup: INF - leaving
15:09:01.156 [3349] <2> xbsa_ValidateFeatureId: INF - entering
15:09:01.156 [3349] <2> xbsa_ValidateFeatureId: INF - leaving (0)

15:09:01.156 [3349] <2> int_SetKeyWord: INF - entering
15:09:01.156 [3349] <2> int_FindBackupImage: INF - entering
15:09:01.156 [3349] <2> int_GetBfsDateRange: INF - entering
15:09:01.156 [3349] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097
15:09:01.156 [3349] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile
15:09:01.156 [3349] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967648141
15:09:01.156 [3349] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518293341
15:09:01.156 [3349] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37
15:09:01.156 [3349] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37
15:09:01.156 [3349] <2> int_logDateRange: INF - leaving
15:09:01.156 [3349] <2> xbsa_BeginTransaction: INF - entering
15:09:01.156 [3349] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - returning - Oracle
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:09:01.156 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.157 [3349] <2> xbsa_BeginTransaction: INF - leaving (0)

15:09:01.157 [3349] <2> xbsa_QueryObject: INF - entering
15:09:01.157 [3349] <4> VxBSAQueryObject: INF - entering QueryObject.
15:09:01.157 [3349] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:09:01.157 [3349] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:09:01.157 [3349] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - returning - NONE
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:09:01.157 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.157 [3349] <4> bsa_bplist: entering bsa_bplist
15:09:01.158 [3349] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:09:01.182 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:09:01.182 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:09:01.230 [3349] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:09:01.230 [3349] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.52202 TO 10.10.36.33.13724 fd = 19
15:09:01.230 [3349] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.52202 TO 10.10.36.33.13724
15:09:01.230 [3349] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:09:01.230 [3349] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:09:01.230 [3349] <2> bprd_connect: no authentication required
15:09:01.230 [3349] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:09:01.230 [3349] <2> bsa_bplist: start_date = Fri Jan 19 21:41:37 2018

15:09:01.230 [3349] <2> bsa_bplist: end_date = Sun Jan 21 21:41:37 2018

15:09:01.230 [3349] <2> bsa_bplist: Request = oracle dba rome_pres-bu romepres-sol10-bu romepres-sol10-bu NONE 0  3 999 1516416097 1516588897 4 4 1 1 1 0 4 8001 4004 4 0 C C C C C 0 2 0 0 0
15:09:01.230 [3349] <4> bsa_bplist: Filepath = /iwhprod_20180120090005_ctl_86296_1_965940097
15:09:01.563 [3349] <2> dbc_get_string: Output =    16777216           0           0           0    16777216           1                    0          -1 -rw-rw---- oracle    dba          16777216 1516498928 1516498907   45  125 /iwhprod_20180120090005_ctl_86296_1_965940097 33200 oracle dba 16777216 1516498907 1516498907 1516498907 1 0 0 15 13 0 0 3 1516498907 1 4   Oracle Database Oracle Backup  
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectOwner.bsa_ObjectOwner: 
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectOwner.app_ObjectOwner: 
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectName.objectSpaceName: Oracle Database
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectName.pathName: /iwhprod_20180120090005_ctl_86296_1_965940097
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - copyType: 3
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectType: 4
15:09:01.564 [3349] <4> bsa_printQueryDescriptor: INF - objectStatus: 2
15:09:01.564 [3349] <4> bsa_GetNextObject: INF - entering bsa_GetNextObject
15:09:01.564 [3349] <4> bsa_GetNextObject: INF - entry_line:<   16777216           0           0           0    16777216           1                    0          -1 -rw-rw---- oracle    dba          16777216 1516498928 1516498907   45  125 /iwhprod_20180120090005_ctl_86296_1_965940097 33200 oracle dba 16777216 1516498907 1516498907 1516498907 1 0 0 15 13 0 0 3 1516498907 1 4   Oracle Database Oracle Backup  >
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - entering bsa_AssignObjDesc.
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - compressed_size: 16777216
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - compressed_size_gig: 0
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - owner: oracle
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - group: dba
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - file_size: 16777216
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - fileNamelen: 45
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - image_time: 1516498928
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - fileName: /iwhprod_20180120090005_ctl_86296_1_965940097
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - bsa_objectOwner: 
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - app_objectOwner: 
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - objectSpaceName: Oracle Database
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - copyType: 3
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - objectType: 4
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - objectStatus: 1
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - resourceType: Oracle Backup
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - objectDescription: 
15:09:01.564 [3349] <4> bsa_AssignObjDesc: INF - objectInfo: 
15:09:01.564 [3349] <4> bsa_AssignObjDesc: n_tuple: 16777216 0 0 0 16777216 1 0 -1 45 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097
15:09:01.564 [3349] <4> bsa_GetNextObject: INF - Object Matched, Checking objectStatus
15:09:01.565 [3349] <4> bsa_SetObjectStatus: INF - entering bsa_SetObjectStatus
15:09:01.565 [3349] <4> bsa_SetObjectStatus: INF - file /iwhprod_20180120090005_ctl_86296_1_965940097 found status: 0
15:09:01.565 [3349] <4> bsa_GetNextObject: INF - Object Matched ALL query criteria
15:09:01.565 [3349] <2> xbsa_QueryObject: INF - leaving (0)

15:09:01.565 [3349] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928>
15:09:01.565 [3349] <2> xbsa_EndTransaction: INF - entering
15:09:01.565 [3349] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:09:01.565 [3349] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - returning - dbclient
15:09:01.565 [3349] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:09:01.565 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.565 [3349] <2> xbsa_EndTransaction: INF - leaving (0)

15:09:01.565 [3349] <2> int_FindBackupImage: INF - leaving
15:09:01.565 [3349] <2> xbsa_BeginTransaction: INF - entering
15:09:01.565 [3349] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - returning - Oracle
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
15:09:01.565 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.565 [3349] <2> xbsa_BeginTransaction: INF - leaving (0)

15:09:01.565 [3349] <2> xbsa_ProgressLogSetup: INF - entering
15:09:01.565 [3349] <2> xbsa_ProgressLogSetup: INF - No progress log: Job will proceed without progress logging
15:09:01.566 [3349] <2> xbsa_ProgressLogSetup: INF - leaving
15:09:01.566 [3349] <2> xbsa_GetObject: INF - entering
15:09:01.566 [3349] <4> VxBSAGetObject: INF - entering GetObject 
15:09:01.566 [3349] <4> VxBSAGetObject: INF - Using restore priority <DEFAULT>
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - NONE
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_PROGRESS_LOG_FILE
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - 
15:09:01.566 [3349] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
15:09:01.566 [3349] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
15:09:01.566 [3349] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
15:09:01.566 [3349] <8> VxBSAGetObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
15:09:01.566 [3349] <4> bsa_RestoreFileObject: INF - entering bsa_RestoreFileObject.
15:09:01.566 [3349] <4> RestoreFileObjects: INF - entering RestoreFileObjects.
15:09:01.566 [3349] <4> initOpen: entering initOpen.
15:09:01.566 [3349] <4> openProgressFile: entering openProgressFile.
15:09:01.566 [3349] <4> openProgressFile: commFull = </usr/openv/netbackup/logs/user_ops/dbext/logs/3349.0.1518206941>
15:09:01.566 [3349] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/logs>
15:09:01.566 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.567 [3349] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/oracle>
15:09:01.567 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.567 [3349] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/oracle does not exist
15:09:01.567 [3349] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbtemplates/oracle>
15:09:01.567 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.567 [3349] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/oracle does not exist
15:09:01.567 [3349] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbtemplates/db2>
15:09:01.567 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.567 [3349] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/db2 does not exist
15:09:01.567 [3349] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/jobs>
15:09:01.567 [3349] <4> delete_old_files: entering delete_old_files.
15:09:01.567 [3349] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/jobs does not exist
15:09:01.567 [3349] <4> openProgressFile: progressFD = <19>
15:09:01.567 [3349] <4> openProgressFile: progressFD_en = <-1>
15:09:01.567 [3349] <4> RestoreFileObjects: INF - restoring File:</iwhprod_20180120090005_ctl_86296_1_965940097>
15:09:01.567 [3349] <4> sendRequest: entering sendRequest.
15:09:01.567 [3349] <4> sendRequest: Progress log not for monitoring=</usr/openv/netbackup/logs/user_ops/dbext/logs/3349.0.1518206941>
15:09:01.567 [3349] <4> getOwnerName: entering getOwnerName.
15:09:01.567 [3349] <4> sendRequest: sending RESTORE request to bprd
15:09:01.568 [3349] <4> sendRequest:    request: <oracle dba rome_pres-bu romepres-sol10-bu romepres-sol10-bu romepres-sol10-bu /usr/openv/netbackup/logs/user_ops/dbext/logs/3349.0.1518206941 NONE NONE 0  1518206941 1516498928 1516498928 1518206941 4 0 0 0 0 12 0 4 0 8001 4004 0 0 0 C C C C C 0 1 0 1 0 0 0 0 0  0 0  0 0 0 0 0 0 0 0 5 0 NONE -1 0 0 0 0 NONE NONE>
15:09:01.568 [3349] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
15:09:01.596 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
15:09:01.596 [3349] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
15:09:01.640 [3349] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
15:09:01.640 [3349] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.59571 TO 10.10.36.33.13724 fd = 20
15:09:01.640 [3349] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.59571 TO 10.10.36.33.13724
15:09:01.640 [3349] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
15:09:01.640 [3349] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
15:09:01.640 [3349] <2> bprd_connect: no authentication required
15:09:01.640 [3349] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
15:09:02.328 [3349] <4> sendRequest: sending buf = 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097
15:09:02.328 [3349] <4> sendRequest: Date range: <-s 01/20/18 20:42:08>, <-e 01/20/18 20:42:08>
15:09:02.328 [3349] <4> serverResponse: entering serverResponse.
15:09:02.328 [3349] <4> serverResponse: initial client_read_timeout = <30000>
15:09:02.328 [3349] <4> readCommMessages: Entering readCommMessages
15:09:12.328 [3349] <4> serverResponse: read comm file:<15:09:04 (3782199.xxx) Restore job id 3782199 will require 1 image.>
15:09:12.329 [3349] <4> serverResponse: read comm file:<15:09:04 (3782199.xxx) Media id AL6608 is needed for the restore.>
15:09:12.329 [3349] <4> readCommMessages: Entering readCommMessages
15:09:17.329 [3349] <4> serverResponse: read comm file:<15:09:08 (3782199.001) Restoring from image created Sat Jan 20 20:42:08 2018>
15:09:17.329 [3349] <4> serverResponse: read comm file:<15:09:10 (3782199.001) INF - If Media id AL6608 is not in a robotic library administrative interaction may be required to satisfy this mount request.>
15:09:17.329 [3349] <4> serverResponse: read comm file:<15:09:10 (3782199.001) INF - No drives available or requesting resources>
15:09:17.329 [3349] <4> serverResponse: INF - Waiting for a resource to become available, resetting timeout
15:09:17.329 [3349] <4> serverResponse: INF - comm file read timeout set to 360000
15:09:17.329 [3349] <4> readCommMessages: Entering readCommMessages
15:09:22.329 [3349] <4> serverResponse: read comm file:<15:09:12 (3782199.001) INF - Waiting for mount of media id AL6608 on server virt-hooey-bu for reading.>
15:09:22.330 [3349] <4> serverResponse: INF - media mount timeout set to 360000
15:09:22.330 [3349] <4> readCommMessages: Entering readCommMessages
15:09:57.331 [3349] <4> serverResponse: read comm file:<15:09:48 (3782199.001) INF - Waiting for positioning of media id AL6608 on server virt-hooey-bu for reading.>
15:09:57.331 [3349] <4> serverResponse: INF - media positioning timeout set to 30000
15:09:57.332 [3349] <4> readCommMessages: Entering readCommMessages
15:10:52.334 [3349] <4> serverResponse: read comm file:<15:10:44 (3782199.001) INF - Beginning restore from server virt-hooey-bu to client romepres-sol10-bu.>
15:10:52.334 [3349] <4> serverResponse: INF - comm file read timeout set to 30000
15:10:52.334 [3349] <4> readCommMessages: Entering readCommMessages
15:15:37.345 [3349] <4> serverResponse: read comm file:<15:15:28 INF - Server status = 150>
15:15:37.345 [3349] <16> serverResponse: ERR - server exited with status 150: termination requested by administrator
15:15:37.345 [3349] <16> RestoreFileObjects: ERR - serverResponse() failed
15:15:37.345 [3349] <4> closeApi: entering closeApi.
15:15:37.345 [3349] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files

15:15:37.345 [3349] <16> VxBSAGetObject: ERR - System error occurred trying to retrieve object in RestoreFileObject.  Status: 3
15:15:37.345 [3349] <2> xbsa_ProcessError: INF - entering
15:15:37.345 [3349] <2> xbsa_ProcessError: INF - leaving
15:15:37.345 [3349] <16> xbsa_GetObject: ERR - VxBSAGetObject: Failed with error:
   Server Status:  termination requested by administrator
15:15:37.346 [3349] <2> xbsa_GetObject: INF - leaving (3)

15:15:37.346 [3349] <16> int_StartJob: ERR - Failed to open backup file for restore.

15:15:37.346 [3349] <2> int_StartJob: INF - leaving
15:15:37.346 [3349] <2> sbtrestore: INF - leaving
15:15:37.346 [3349] <2> sbterror: INF - entering
15:15:37.346 [3349] <2> sbterror: INF - Error=7501: Failed to open backup file for restore.
.
15:15:37.346 [3349] <2> sbterror: INF - leaving
15:15:46.347 [3349] <2> sbtend: INF - entering
15:15:46.347 [3349] <2> int_FreeDuplexStrings: INF - entering
15:15:46.347 [3349] <2> int_FreeDuplexStrings: INF - leaving
15:15:46.347 [3349] <4> sbtend: INF - --- END of SESSION ---
15:15:46.347 [3349] <2> sbtend: INF - leaving
15:15:46.348 [3349] <2> xbsa_EndTransaction: INF - entering
15:15:46.348 [3349] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn.
15:15:46.348 [3349] <4> VxBSAEndTxn: INF - Transaction being COMMITED.
15:15:46.348 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY
15:15:46.348 [3349] <4> VxBSAGetEnv: INF - returning - dbclient
15:15:46.348 [3349] <4> VxBSAEndTxn: INF - Cleaning directory: </usr/openv/netbackup/logs/dbclient>
15:15:46.348 [3349] <4> delete_old_files: entering delete_old_files.
15:15:46.348 [3349] <8> close_image: Session being terminated abnormally, cleaning up
15:15:46.348 [3349] <4> closeApi: entering closeApi.
15:15:46.348 [3349] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files

15:15:46.348 [3349] <4> close_image: INF - restore FAILED
15:15:46.348 [3349] <4> close_image: INF ---- end of Restore ---

15:15:46.348 [3349] <16> VxBSAEndTxn: ERR - Transaction ended with active Backup/Restore.
15:15:46.348 [3349] <2> xbsa_ProcessError: INF - entering
15:15:46.348 [3349] <2> xbsa_ProcessError: INF - leaving
15:15:46.348 [3349] <16> xbsa_EndTransaction: ERR - VxBSAEndTxn: Failed with error:
   The transaction was aborted.
15:15:46.348 [3349] <2> xbsa_EndTransaction: INF - leaving (32)

15:15:46.348 [3349] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:15:46.348 [3349] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:15:46.348 [3349] <4> VxBSAGetEnv: INF - returning - 
15:15:47.203 [3352] <2> sbtend: INF - entering
15:15:47.203 [3352] <2> int_FreeDuplexStrings: INF - entering
15:15:47.203 [3352] <2> int_FreeDuplexStrings: INF - leaving
15:15:47.203 [3352] <2> int_CleanupInfo2: INF - entering
15:15:47.203 [3352] <2> int_CleanupInfo2: INF - leaving
15:15:47.203 [3352] <4> sbtend: INF - --- END of SESSION ---
15:15:47.203 [3352] <2> sbtend: INF - leaving
15:15:47.204 [3352] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:15:47.204 [3352] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:15:47.204 [3352] <4> VxBSAGetEnv: INF - returning - 
15:15:47.336 [3355] <2> sbtend: INF - entering
15:15:47.336 [3355] <2> int_FreeDuplexStrings: INF - entering
15:15:47.336 [3355] <2> int_FreeDuplexStrings: INF - leaving
15:15:47.336 [3355] <2> int_CleanupInfo2: INF - entering
15:15:47.337 [3355] <2> int_CleanupInfo2: INF - leaving
15:15:47.337 [3355] <4> sbtend: INF - --- END of SESSION ---
15:15:47.337 [3355] <2> sbtend: INF - leaving
15:15:47.337 [3355] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:15:47.337 [3355] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:15:47.337 [3355] <4> VxBSAGetEnv: INF - returning - 
15:15:47.513 [3358] <2> sbtend: INF - entering
15:15:47.513 [3358] <2> int_FreeDuplexStrings: INF - entering
15:15:47.514 [3358] <2> int_FreeDuplexStrings: INF - leaving
15:15:47.514 [3358] <2> int_CleanupInfo2: INF - entering
15:15:47.514 [3358] <2> int_CleanupInfo2: INF - leaving
15:15:47.514 [3358] <4> sbtend: INF - --- END of SESSION ---
15:15:47.514 [3358] <2> sbtend: INF - leaving
15:15:47.514 [3358] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:15:47.514 [3358] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:15:47.514 [3358] <4> VxBSAGetEnv: INF - returning -