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

14:56:30.138 [3108] <2> xbsa_GetEnv: INF - entering
14:56:30.138 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
14:56:30.138 [3108] <4> VxBSAGetEnv: INF - returning - 
14:56:30.138 [3108] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

14:56:30.138 [3108] <2> xbsa_GetEnv: INF - leaving (80)

14:56:30.138 [3108] <2> int_GetOracleVersion: INF - entering
14:56:30.138 [3108] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
14:56:30.138 [3108] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
14:56:30.138 [3108] <2> int_GetOracleVersion: INF - leaving
14:56:30.138 [3108] <2> int_LogSystemInfo: INF - entering
14:56:30.138 [3108] <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

14:56:30.138 [3108] <2> int_LogSystemInfo: INF - leaving
14:56:30.138 [3108] <2> int_GetMMInfo: INF - entering
14:56:30.138 [3108] <2> int_GetMMInfo: INF - Initialized Signal
14:56:30.138 [3108] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
14:56:30.138 [3108] <2> int_GetMMInfo: INF - leaving
14:56:30.138 [3108] <2> int_InitializeSbtFunctionPointers: INF - entering
14:56:30.139 [3108] <2> int_InitializeSbtFunctionPointers: INF - leaving
14:56:30.139 [3108] <2> sbtinit: INF - leaving
14:56:30.139 [3108] <2> sbtinit2: INF - entering
14:56:30.139 [3108] <2> sbtinit2: INF - leaving
14:56:35.139 [3108] <2> sbtcommand: INF - entering
14:56:35.139 [3108] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:35.140 [3108] <2> int_ProcessCommandString: INF - entering
14:56:35.140 [3108] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:35.140 [3108] <2> int_ProcessCommand: INF - entering
14:56:35.140 [3108] <2> xbsa_SetEnv: INF - entering
14:56:35.140 [3108] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:35.140 [3108] <2> xbsa_SetEnv: INF - leaving (0)

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

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

14:56:35.300 [3111] <2> xbsa_GetEnv: INF - entering
14:56:35.300 [3111] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
14:56:35.300 [3111] <4> VxBSAGetEnv: INF - returning - 
14:56:35.300 [3111] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

14:56:35.300 [3111] <2> xbsa_GetEnv: INF - leaving (80)

14:56:35.300 [3111] <2> int_GetOracleVersion: INF - entering
14:56:35.300 [3111] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
14:56:35.300 [3111] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
14:56:35.300 [3111] <2> int_GetOracleVersion: INF - leaving
14:56:35.300 [3111] <2> int_LogSystemInfo: INF - entering
14:56:35.300 [3111] <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

14:56:35.300 [3111] <2> int_LogSystemInfo: INF - leaving
14:56:35.300 [3111] <2> int_GetMMInfo: INF - entering
14:56:35.300 [3111] <2> int_GetMMInfo: INF - Initialized Signal
14:56:35.300 [3111] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
14:56:35.300 [3111] <2> int_GetMMInfo: INF - leaving
14:56:35.300 [3111] <2> int_InitializeSbtFunctionPointers: INF - entering
14:56:35.300 [3111] <2> int_InitializeSbtFunctionPointers: INF - leaving
14:56:35.300 [3111] <2> sbtinit: INF - leaving
14:56:35.300 [3111] <2> sbtinit2: INF - entering
14:56:35.300 [3111] <2> sbtinit2: INF - leaving
14:56:40.141 [3108] <2> sbtcommand: INF - entering
14:56:40.141 [3108] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:40.141 [3108] <2> int_ProcessCommandString: INF - entering
14:56:40.141 [3108] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:40.141 [3108] <2> int_ProcessCommand: INF - entering
14:56:40.141 [3108] <2> xbsa_SetEnv: INF - entering
14:56:40.141 [3108] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:40.141 [3108] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:40.141 [3108] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:40.141 [3108] <2> int_ProcessCommand: INF - leaving
14:56:40.141 [3108] <2> int_ProcessCommandString: INF - leaving
14:56:40.141 [3108] <2> sbtcommand: INF - leaving
14:56:40.301 [3111] <2> sbtcommand: INF - entering
14:56:40.301 [3111] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:40.301 [3111] <2> int_ProcessCommandString: INF - entering
14:56:40.301 [3111] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:40.301 [3111] <2> int_ProcessCommand: INF - entering
14:56:40.301 [3111] <2> xbsa_SetEnv: INF - entering
14:56:40.301 [3111] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:40.301 [3111] <2> xbsa_SetEnv: INF - leaving (0)

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

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

14:56:40.461 [3114] <2> xbsa_GetEnv: INF - entering
14:56:40.461 [3114] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
14:56:40.461 [3114] <4> VxBSAGetEnv: INF - returning - 
14:56:40.461 [3114] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

14:56:40.461 [3114] <2> xbsa_GetEnv: INF - leaving (80)

14:56:40.461 [3114] <2> int_GetOracleVersion: INF - entering
14:56:40.461 [3114] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
14:56:40.461 [3114] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
14:56:40.461 [3114] <2> int_GetOracleVersion: INF - leaving
14:56:40.461 [3114] <2> int_LogSystemInfo: INF - entering
14:56:40.461 [3114] <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

14:56:40.461 [3114] <2> int_LogSystemInfo: INF - leaving
14:56:40.462 [3114] <2> int_GetMMInfo: INF - entering
14:56:40.462 [3114] <2> int_GetMMInfo: INF - Initialized Signal
14:56:40.462 [3114] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
14:56:40.462 [3114] <2> int_GetMMInfo: INF - leaving
14:56:40.462 [3114] <2> int_InitializeSbtFunctionPointers: INF - entering
14:56:40.462 [3114] <2> int_InitializeSbtFunctionPointers: INF - leaving
14:56:40.462 [3114] <2> sbtinit: INF - leaving
14:56:40.462 [3114] <2> sbtinit2: INF - entering
14:56:40.462 [3114] <2> sbtinit2: INF - leaving
14:56:45.142 [3108] <2> sbtcommand: INF - entering
14:56:45.142 [3108] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:45.142 [3108] <2> int_ProcessCommandString: INF - entering
14:56:45.142 [3108] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:45.142 [3108] <2> int_ProcessCommand: INF - entering
14:56:45.142 [3108] <2> xbsa_SetEnv: INF - entering
14:56:45.142 [3108] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:45.142 [3108] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:45.143 [3108] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:45.143 [3108] <2> int_ProcessCommand: INF - leaving
14:56:45.143 [3108] <2> int_ProcessCommandString: INF - leaving
14:56:45.143 [3108] <2> sbtcommand: INF - leaving
14:56:45.302 [3111] <2> sbtcommand: INF - entering
14:56:45.303 [3111] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:45.303 [3111] <2> int_ProcessCommandString: INF - entering
14:56:45.303 [3111] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:45.303 [3111] <2> int_ProcessCommand: INF - entering
14:56:45.303 [3111] <2> xbsa_SetEnv: INF - entering
14:56:45.303 [3111] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:45.303 [3111] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:45.303 [3111] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:45.303 [3111] <2> int_ProcessCommand: INF - leaving
14:56:45.303 [3111] <2> int_ProcessCommandString: INF - leaving
14:56:45.303 [3111] <2> sbtcommand: INF - leaving
14:56:45.463 [3114] <2> sbtcommand: INF - entering
14:56:45.463 [3114] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:45.463 [3114] <2> int_ProcessCommandString: INF - entering
14:56:45.463 [3114] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:45.463 [3114] <2> int_ProcessCommand: INF - entering
14:56:45.463 [3114] <2> xbsa_SetEnv: INF - entering
14:56:45.463 [3114] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:45.463 [3114] <2> xbsa_SetEnv: INF - leaving (0)

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

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

14:56:45.613 [3117] <2> xbsa_GetEnv: INF - entering
14:56:45.613 [3117] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
14:56:45.613 [3117] <4> VxBSAGetEnv: INF - returning - 
14:56:45.613 [3117] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block

14:56:45.613 [3117] <2> xbsa_GetEnv: INF - leaving (80)

14:56:45.613 [3117] <2> int_GetOracleVersion: INF - entering
14:56:45.613 [3117] <2> int_GetOracleVersion: INF - ORACLE_HOME= </u01/app/oracle/product/10.2.0.5.0/db_1>
14:56:45.613 [3117] <2> int_GetOracleVersion: INF - Found </u01/app/oracle/product/10.2.0.5.0/db_1/bin/rman>
14:56:45.613 [3117] <2> int_GetOracleVersion: INF - leaving
14:56:45.613 [3117] <2> int_LogSystemInfo: INF - entering
14:56:45.613 [3117] <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

14:56:45.613 [3117] <2> int_LogSystemInfo: INF - leaving
14:56:45.613 [3117] <2> int_GetMMInfo: INF - entering
14:56:45.613 [3117] <2> int_GetMMInfo: INF - Initialized Signal
14:56:45.613 [3117] <2> int_GetMMInfo: INF - support for Proxy Copy enabled
14:56:45.613 [3117] <2> int_GetMMInfo: INF - leaving
14:56:45.613 [3117] <2> int_InitializeSbtFunctionPointers: INF - entering
14:56:45.613 [3117] <2> int_InitializeSbtFunctionPointers: INF - leaving
14:56:45.613 [3117] <2> sbtinit: INF - leaving
14:56:45.614 [3117] <2> sbtinit2: INF - entering
14:56:45.614 [3117] <2> sbtinit2: INF - leaving
14:56:50.143 [3108] <2> sbtcommand: INF - entering
14:56:50.143 [3108] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:50.143 [3108] <2> int_ProcessCommandString: INF - entering
14:56:50.143 [3108] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:50.143 [3108] <2> int_ProcessCommand: INF - entering
14:56:50.143 [3108] <2> xbsa_SetEnv: INF - entering
14:56:50.143 [3108] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:50.144 [3108] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:50.144 [3108] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:50.144 [3108] <2> int_ProcessCommand: INF - leaving
14:56:50.144 [3108] <2> int_ProcessCommandString: INF - leaving
14:56:50.144 [3108] <2> sbtcommand: INF - leaving
14:56:50.304 [3111] <2> sbtcommand: INF - entering
14:56:50.304 [3111] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:50.304 [3111] <2> int_ProcessCommandString: INF - entering
14:56:50.304 [3111] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:50.304 [3111] <2> int_ProcessCommand: INF - entering
14:56:50.304 [3111] <2> xbsa_SetEnv: INF - entering
14:56:50.304 [3111] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:50.304 [3111] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:50.304 [3111] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:50.304 [3111] <2> int_ProcessCommand: INF - leaving
14:56:50.304 [3111] <2> int_ProcessCommandString: INF - leaving
14:56:50.304 [3111] <2> sbtcommand: INF - leaving
14:56:50.464 [3114] <2> sbtcommand: INF - entering
14:56:50.464 [3114] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:50.464 [3114] <2> int_ProcessCommandString: INF - entering
14:56:50.464 [3114] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:50.464 [3114] <2> int_ProcessCommand: INF - entering
14:56:50.464 [3114] <2> xbsa_SetEnv: INF - entering
14:56:50.464 [3114] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:50.464 [3114] <2> xbsa_SetEnv: INF - leaving (0)

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

14:56:50.464 [3114] <2> int_ProcessCommand: INF - Client <rome_pres-bu> will be used for this API session.
14:56:50.464 [3114] <2> int_ProcessCommand: INF - leaving
14:56:50.464 [3114] <2> int_ProcessCommandString: INF - leaving
14:56:50.464 [3114] <2> sbtcommand: INF - leaving
14:56:50.614 [3117] <2> sbtcommand: INF - entering
14:56:50.614 [3117] <2> sbtcommand: INF - command string=<NB_ORA_SERV=atlbackupmaster, NB_ORA_CLIENT=rome_pres-bu>
14:56:50.614 [3117] <2> int_ProcessCommandString: INF - entering
14:56:50.614 [3117] <4> int_ProcessCommandString: INF - cmd_key=<NB_ORA_SERV> cmd_val=<atlbackupmaster>
14:56:50.614 [3117] <2> int_ProcessCommand: INF - entering
14:56:50.614 [3117] <2> xbsa_SetEnv: INF - entering
14:56:50.614 [3117] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST
14:56:50.614 [3117] <2> xbsa_SetEnv: INF - leaving (0)

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

14:56:55.160 [3117] <2> int_FindBackupImage: INF - leaving
14:56:55.160 [3117] <2> int_SetImageInfoArray: INF - entering
14:56:55.160 [3117] <2> int_SetImageInfoArray: INF - leaving
14:56:55.160 [3117] <2> int_GetImageInfo2: INF - leaving
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - entering
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - Media Information for Backup File : <iwhprod_20180120090005_ctl_86296_1_965940097>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - Media Sharing Mode : <Multiple Concurrent Users>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - File Ordering Mode : <Sequential file access>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - Media ID : <AL6608>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - Comment : <Backup ID : rome_pres-bu_1516498928>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - File Creation Method : <Stream>
14:56:55.160 [3117] <2> int_DumpSbtInfo: INF - leaving
14:56:55.160 [3117] <2> sbtinfo2: INF - leaving
14:56:59.810 [3108] <2> sbtrestore: INF - entering
14:56:59.810 [3108] <2> int_StartJob: INF - entering
14:56:59.810 [3108] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_POLICY
14:56:59.810 [3108] <2> int_IsSAPBackup: INF - entering
14:56:59.810 [3108] <2> int_IsSAPBackup: INF - leaving
14:56:59.810 [3108] <2> xbsa_ValidateFeatureId: INF - entering
14:56:59.810 [3108] <2> xbsa_ValidateFeatureId: INF - leaving (0)

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

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

14:56:59.881 [3108] <2> bsa_bplist: end_date = Sun Jan 21 21:41:37 2018

14:56:59.881 [3108] <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
14:56:59.881 [3108] <4> bsa_bplist: Filepath = /iwhprod_20180120090005_ctl_86296_1_965940097
14:57:00.208 [3108] <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  
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectOwner.bsa_ObjectOwner: 
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectOwner.app_ObjectOwner: 
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectName.objectSpaceName: Oracle Database
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectName.pathName: /iwhprod_20180120090005_ctl_86296_1_965940097
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - copyType: 3
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectType: 4
14:57:00.208 [3108] <4> bsa_printQueryDescriptor: INF - objectStatus: 2
14:57:00.208 [3108] <4> bsa_GetNextObject: INF - entering bsa_GetNextObject
14:57:00.208 [3108] <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  >
14:57:00.208 [3108] <4> bsa_AssignObjDesc: INF - entering bsa_AssignObjDesc.
14:57:00.208 [3108] <4> bsa_AssignObjDesc: INF - compressed_size: 16777216
14:57:00.208 [3108] <4> bsa_AssignObjDesc: INF - compressed_size_gig: 0
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - owner: oracle
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - group: dba
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - file_size: 16777216
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - fileNamelen: 45
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - image_time: 1516498928
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - fileName: /iwhprod_20180120090005_ctl_86296_1_965940097
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - bsa_objectOwner: 
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - app_objectOwner: 
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - objectSpaceName: Oracle Database
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - copyType: 3
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - objectType: 4
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - objectStatus: 1
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - resourceType: Oracle Backup
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - objectDescription: 
14:57:00.209 [3108] <4> bsa_AssignObjDesc: INF - objectInfo: 
14:57:00.209 [3108] <4> bsa_AssignObjDesc: n_tuple: 16777216 0 0 0 16777216 1 0 -1 45 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097
14:57:00.209 [3108] <4> bsa_GetNextObject: INF - Object Matched, Checking objectStatus
14:57:00.209 [3108] <4> bsa_SetObjectStatus: INF - entering bsa_SetObjectStatus
14:57:00.209 [3108] <4> bsa_SetObjectStatus: INF - file /iwhprod_20180120090005_ctl_86296_1_965940097 found status: 0
14:57:00.209 [3108] <4> bsa_GetNextObject: INF - Object Matched ALL query criteria
14:57:00.209 [3108] <2> xbsa_QueryObject: INF - leaving (0)

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

14:57:00.210 [3108] <2> int_FindBackupImage: INF - leaving
14:57:00.210 [3108] <2> xbsa_BeginTransaction: INF - entering
14:57:00.210 [3108] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn.
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - Oracle
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <2> xbsa_BeginTransaction: INF - leaving (0)

14:57:00.210 [3108] <2> xbsa_ProgressLogSetup: INF - entering
14:57:00.210 [3108] <2> xbsa_ProgressLogSetup: INF - No progress log: Job will proceed without progress logging
14:57:00.210 [3108] <2> xbsa_ProgressLogSetup: INF - leaving
14:57:00.210 [3108] <2> xbsa_GetObject: INF - entering
14:57:00.210 [3108] <4> VxBSAGetObject: INF - entering GetObject 
14:57:00.210 [3108] <4> VxBSAGetObject: INF - Using restore priority <DEFAULT>
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - NONE
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_PROGRESS_LOG_FILE
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - 
14:57:00.210 [3108] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig.
14:57:00.210 [3108] <4> dbc_GetServerClientConfig: ServerName: <atlbackupmaster>, ClientName: <romepres-sol10-bu>
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - returning - atlbackupmaster
14:57:00.210 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST
14:57:00.211 [3108] <4> VxBSAGetEnv: INF - returning - rome_pres-bu
14:57:00.211 [3108] <8> VxBSAGetObject: WARNING - NBBSA_CLIENT_HOST name= <rome_pres-bu> differs from NBU config name= <romepres-sol10-bu>
14:57:00.211 [3108] <4> bsa_RestoreFileObject: INF - entering bsa_RestoreFileObject.
14:57:00.211 [3108] <4> RestoreFileObjects: INF - entering RestoreFileObjects.
14:57:00.211 [3108] <4> initOpen: entering initOpen.
14:57:00.211 [3108] <4> openProgressFile: entering openProgressFile.
14:57:00.211 [3108] <4> openProgressFile: commFull = </usr/openv/netbackup/logs/user_ops/dbext/logs/3108.0.1518206220>
14:57:00.211 [3108] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/logs>
14:57:00.211 [3108] <4> delete_old_files: entering delete_old_files.
14:57:00.211 [3108] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/oracle>
14:57:00.211 [3108] <4> delete_old_files: entering delete_old_files.
14:57:00.211 [3108] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/oracle does not exist
14:57:00.211 [3108] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbtemplates/oracle>
14:57:00.211 [3108] <4> delete_old_files: entering delete_old_files.
14:57:00.211 [3108] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/oracle does not exist
14:57:00.211 [3108] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbtemplates/db2>
14:57:00.212 [3108] <4> delete_old_files: entering delete_old_files.
14:57:00.212 [3108] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/db2 does not exist
14:57:00.212 [3108] <4> openProgressFile: cleaning directory: </usr/openv/netbackup/logs/user_ops/dbext/jobs>
14:57:00.212 [3108] <4> delete_old_files: entering delete_old_files.
14:57:00.212 [3108] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/jobs does not exist
14:57:00.212 [3108] <4> openProgressFile: progressFD = <19>
14:57:00.212 [3108] <4> openProgressFile: progressFD_en = <-1>
14:57:00.212 [3108] <4> RestoreFileObjects: INF - restoring File:</iwhprod_20180120090005_ctl_86296_1_965940097>
14:57:00.212 [3108] <4> sendRequest: entering sendRequest.
14:57:00.212 [3108] <4> sendRequest: Progress log not for monitoring=</usr/openv/netbackup/logs/user_ops/dbext/logs/3108.0.1518206220>
14:57:00.212 [3108] <4> getOwnerName: entering getOwnerName.
14:57:00.212 [3108] <4> sendRequest: sending RESTORE request to bprd
14:57:00.212 [3108] <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/3108.0.1518206220 NONE NONE 0  1518206220 1516498928 1516498928 1518206220 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>
14:57:00.213 [3108] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000
14:57:00.241 [3108] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006
14:57:00.241 [3108] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd
14:57:00.291 [3108] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000
14:57:00.291 [3108] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.58940 TO 10.10.36.33.13724 fd = 20
14:57:00.291 [3108] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.58940 TO 10.10.36.33.13724
14:57:00.291 [3108] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required
14:57:00.291 [3108] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required
14:57:00.291 [3108] <2> bprd_connect: no authentication required
14:57:00.291 [3108] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000
14:57:01.596 [3108] <4> sendRequest: sending buf = 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097
14:57:01.596 [3108] <4> sendRequest: Date range: <-s 01/20/18 20:42:08>, <-e 01/20/18 20:42:08>
14:57:01.596 [3108] <4> serverResponse: entering serverResponse.
14:57:01.596 [3108] <4> serverResponse: initial client_read_timeout = <30000>
14:57:01.596 [3108] <4> readCommMessages: Entering readCommMessages
14:57:11.597 [3108] <4> serverResponse: read comm file:<14:57:03 (3782180.xxx) Restore job id 3782180 will require 1 image.>
14:57:11.597 [3108] <4> serverResponse: read comm file:<14:57:03 (3782180.xxx) Media id AL6608 is needed for the restore.>
14:57:11.597 [3108] <4> readCommMessages: Entering readCommMessages
14:57:16.597 [3108] <4> serverResponse: read comm file:<14:57:07 (3782180.001) Restoring from image created Sat Jan 20 20:42:08 2018>
14:57:16.598 [3108] <4> serverResponse: read comm file:<14:57:09 (3782180.001) INF - If Media id AL6608 is not in a robotic library administrative interaction may be required to satisfy this mount request.>
14:57:16.598 [3108] <4> serverResponse: read comm file:<14:57:09 (3782180.001) INF - No drives available or requesting resources>
14:57:16.598 [3108] <4> serverResponse: INF - Waiting for a resource to become available, resetting timeout
14:57:16.598 [3108] <4> serverResponse: INF - comm file read timeout set to 360000
14:57:16.598 [3108] <4> readCommMessages: Entering readCommMessages
14:57:21.598 [3108] <4> serverResponse: read comm file:<14:57:11 (3782180.001) INF - Waiting for mount of media id AL6608 on server virt-hooey-bu for reading.>
14:57:21.598 [3108] <4> serverResponse: INF - media mount timeout set to 360000
14:57:21.598 [3108] <4> readCommMessages: Entering readCommMessages
14:58:21.601 [3108] <4> serverResponse: read comm file:<14:58:13 (3782180.001) INF - Waiting for positioning of media id AL6608 on server virt-hooey-bu for reading.>
14:58:21.601 [3108] <4> serverResponse: INF - media positioning timeout set to 30000
14:58:21.601 [3108] <4> readCommMessages: Entering readCommMessages
14:59:01.604 [3108] <4> serverResponse: read comm file:<14:58:55 (3782180.001) INF - Beginning restore from server virt-hooey-bu to client romepres-sol10-bu.>
14:59:01.604 [3108] <4> serverResponse: INF - comm file read timeout set to 30000
14:59:01.604 [3108] <4> readCommMessages: Entering readCommMessages
15:10:01.631 [3108] <4> serverResponse: read comm file:<15:09:53 INF - Server status = 150>
15:10:01.631 [3108] <16> serverResponse: ERR - server exited with status 150: termination requested by administrator
15:10:01.631 [3108] <16> RestoreFileObjects: ERR - serverResponse() failed
15:10:01.631 [3108] <4> closeApi: entering closeApi.
15:10:01.631 [3108] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files

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

15:10:01.631 [3108] <16> int_StartJob: ERR - Failed to open backup file for restore.

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

15:10:04.633 [3108] <4> close_image: INF - restore FAILED
15:10:04.633 [3108] <4> close_image: INF ---- end of Restore ---

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

15:10:04.633 [3108] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:10:04.634 [3108] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:10:04.634 [3108] <4> VxBSAGetEnv: INF - returning - 
15:10:04.913 [3111] <2> sbtend: INF - entering
15:10:04.914 [3111] <2> int_FreeDuplexStrings: INF - entering
15:10:04.914 [3111] <2> int_FreeDuplexStrings: INF - leaving
15:10:04.914 [3111] <2> int_CleanupInfo2: INF - entering
15:10:04.914 [3111] <2> int_CleanupInfo2: INF - leaving
15:10:04.914 [3111] <4> sbtend: INF - --- END of SESSION ---
15:10:04.914 [3111] <2> sbtend: INF - leaving
15:10:04.914 [3111] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:10:04.914 [3111] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:10:04.914 [3111] <4> VxBSAGetEnv: INF - returning - 
15:10:05.065 [3114] <2> sbtend: INF - entering
15:10:05.065 [3114] <2> int_FreeDuplexStrings: INF - entering
15:10:05.065 [3114] <2> int_FreeDuplexStrings: INF - leaving
15:10:05.065 [3114] <2> int_CleanupInfo2: INF - entering
15:10:05.065 [3114] <2> int_CleanupInfo2: INF - leaving
15:10:05.065 [3114] <4> sbtend: INF - --- END of SESSION ---
15:10:05.065 [3114] <2> sbtend: INF - leaving
15:10:05.065 [3114] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:10:05.065 [3114] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:10:05.065 [3114] <4> VxBSAGetEnv: INF - returning - 
15:10:05.219 [3117] <2> sbtend: INF - entering
15:10:05.219 [3117] <2> int_FreeDuplexStrings: INF - entering
15:10:05.219 [3117] <2> int_FreeDuplexStrings: INF - leaving
15:10:05.219 [3117] <2> int_CleanupInfo2: INF - entering
15:10:05.219 [3117] <2> int_CleanupInfo2: INF - leaving
15:10:05.219 [3117] <4> sbtend: INF - --- END of SESSION ---
15:10:05.220 [3117] <2> sbtend: INF - leaving
15:10:05.220 [3117] <4> VxBSATerminate: INF - entering VxBSATerminate.
15:10:05.220 [3117] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
15:10:05.220 [3117] <4> VxBSAGetEnv: INF - returning -