14:24:07.303 [2440] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5 2010042404 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.303 [2440] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size 14:24:07.303 [2440] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 14:24:07.303 [2440] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:07.303 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.303 [2440] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:07.303 [2440] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - returning - 5 14:24:07.304 [2440] <2> sbtinit: INF - entering 14:24:07.304 [2440] <2> xbsa_GetEnv: INF - entering 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu 14:24:07.304 [2440] <2> xbsa_GetEnv: INF - leaving (0) 14:24:07.304 [2440] <2> xbsa_GetEnv: INF - entering 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:07.304 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:07.304 [2440] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block 14:24:07.304 [2440] <2> xbsa_GetEnv: INF - leaving (80) 14:24:07.304 [2440] <2> int_GetOracleVersion: INF - entering 14:24:07.304 [2440] <2> int_GetOracleVersion: INF - ORACLE_HOME= 14:24:07.304 [2440] <2> int_GetOracleVersion: INF - Found 14:24:07.304 [2440] <2> int_GetOracleVersion: INF - leaving 14:24:07.304 [2440] <2> int_LogSystemInfo: INF - entering 14:24:07.304 [2440] <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:24:07.304 [2440] <2> int_LogSystemInfo: INF - leaving 14:24:07.304 [2440] <2> int_GetMMInfo: INF - entering 14:24:07.304 [2440] <2> int_GetMMInfo: INF - Initialized Signal 14:24:07.304 [2440] <2> int_GetMMInfo: INF - support for Proxy Copy enabled 14:24:07.304 [2440] <2> int_GetMMInfo: INF - leaving 14:24:07.304 [2440] <2> int_InitializeSbtFunctionPointers: INF - entering 14:24:07.304 [2440] <2> int_InitializeSbtFunctionPointers: INF - leaving 14:24:07.304 [2440] <2> sbtinit: INF - leaving 14:24:07.305 [2440] <2> sbtinit2: INF - entering 14:24:07.305 [2440] <2> sbtinit2: INF - leaving 14:24:12.305 [2440] <2> sbtcommand: INF - entering 14:24:12.305 [2440] <2> sbtcommand: INF - command string= 14:24:12.305 [2440] <2> int_ProcessCommandString: INF - entering 14:24:12.305 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:12.305 [2440] <2> int_ProcessCommand: INF - entering 14:24:12.306 [2440] <2> xbsa_SetEnv: INF - entering 14:24:12.306 [2440] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:12.306 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:12.306 [2440] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:12.306 [2440] <2> int_ProcessCommand: INF - leaving 14:24:12.306 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:12.306 [2440] <2> int_ProcessCommand: INF - entering 14:24:12.306 [2440] <2> xbsa_SetEnv: INF - entering 14:24:12.306 [2440] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:12.306 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:12.306 [2440] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:12.306 [2440] <2> int_ProcessCommand: INF - leaving 14:24:12.306 [2440] <2> int_ProcessCommandString: INF - leaving 14:24:12.306 [2440] <2> sbtcommand: INF - leaving 14:24:12.450 [2443] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5 2010042404 14:24:12.450 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API 14:24:12.450 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.450 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID 14:24:12.450 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.450 [2443] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size 14:24:12.450 [2443] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 14:24:12.450 [2443] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.451 [2443] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:12.451 [2443] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - 5 14:24:12.451 [2443] <2> sbtinit: INF - entering 14:24:12.451 [2443] <2> xbsa_GetEnv: INF - entering 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu 14:24:12.451 [2443] <2> xbsa_GetEnv: INF - leaving (0) 14:24:12.451 [2443] <2> xbsa_GetEnv: INF - entering 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:12.451 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:12.451 [2443] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block 14:24:12.451 [2443] <2> xbsa_GetEnv: INF - leaving (80) 14:24:12.451 [2443] <2> int_GetOracleVersion: INF - entering 14:24:12.451 [2443] <2> int_GetOracleVersion: INF - ORACLE_HOME= 14:24:12.451 [2443] <2> int_GetOracleVersion: INF - Found 14:24:12.451 [2443] <2> int_GetOracleVersion: INF - leaving 14:24:12.451 [2443] <2> int_LogSystemInfo: INF - entering 14:24:12.451 [2443] <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:24:12.451 [2443] <2> int_LogSystemInfo: INF - leaving 14:24:12.451 [2443] <2> int_GetMMInfo: INF - entering 14:24:12.451 [2443] <2> int_GetMMInfo: INF - Initialized Signal 14:24:12.451 [2443] <2> int_GetMMInfo: INF - support for Proxy Copy enabled 14:24:12.451 [2443] <2> int_GetMMInfo: INF - leaving 14:24:12.452 [2443] <2> int_InitializeSbtFunctionPointers: INF - entering 14:24:12.452 [2443] <2> int_InitializeSbtFunctionPointers: INF - leaving 14:24:12.452 [2443] <2> sbtinit: INF - leaving 14:24:12.452 [2443] <2> sbtinit2: INF - entering 14:24:12.452 [2443] <2> sbtinit2: INF - leaving 14:24:17.307 [2440] <2> sbtcommand: INF - entering 14:24:17.307 [2440] <2> sbtcommand: INF - command string= 14:24:17.307 [2440] <2> int_ProcessCommandString: INF - entering 14:24:17.307 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:17.307 [2440] <2> int_ProcessCommand: INF - entering 14:24:17.307 [2440] <2> xbsa_SetEnv: INF - entering 14:24:17.307 [2440] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:17.307 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:17.307 [2440] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:17.307 [2440] <2> int_ProcessCommand: INF - leaving 14:24:17.307 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:17.307 [2440] <2> int_ProcessCommand: INF - entering 14:24:17.307 [2440] <2> xbsa_SetEnv: INF - entering 14:24:17.307 [2440] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:17.307 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:17.307 [2440] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:17.307 [2440] <2> int_ProcessCommand: INF - leaving 14:24:17.308 [2440] <2> int_ProcessCommandString: INF - leaving 14:24:17.308 [2440] <2> sbtcommand: INF - leaving 14:24:17.452 [2443] <2> sbtcommand: INF - entering 14:24:17.453 [2443] <2> sbtcommand: INF - command string= 14:24:17.453 [2443] <2> int_ProcessCommandString: INF - entering 14:24:17.453 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:17.453 [2443] <2> int_ProcessCommand: INF - entering 14:24:17.453 [2443] <2> xbsa_SetEnv: INF - entering 14:24:17.453 [2443] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:17.453 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:17.453 [2443] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:17.453 [2443] <2> int_ProcessCommand: INF - leaving 14:24:17.453 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:17.453 [2443] <2> int_ProcessCommand: INF - entering 14:24:17.453 [2443] <2> xbsa_SetEnv: INF - entering 14:24:17.453 [2443] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:17.453 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:17.453 [2443] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:17.453 [2443] <2> int_ProcessCommand: INF - leaving 14:24:17.453 [2443] <2> int_ProcessCommandString: INF - leaving 14:24:17.453 [2443] <2> sbtcommand: INF - leaving 14:24:17.612 [2447] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5 2010042404 14:24:17.612 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API 14:24:17.612 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.612 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID 14:24:17.612 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.612 [2447] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size 14:24:17.612 [2447] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 14:24:17.613 [2447] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.613 [2447] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:17.613 [2447] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - 5 14:24:17.613 [2447] <2> sbtinit: INF - entering 14:24:17.613 [2447] <2> xbsa_GetEnv: INF - entering 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu 14:24:17.613 [2447] <2> xbsa_GetEnv: INF - leaving (0) 14:24:17.613 [2447] <2> xbsa_GetEnv: INF - entering 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:17.613 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:17.613 [2447] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block 14:24:17.614 [2447] <2> xbsa_GetEnv: INF - leaving (80) 14:24:17.614 [2447] <2> int_GetOracleVersion: INF - entering 14:24:17.614 [2447] <2> int_GetOracleVersion: INF - ORACLE_HOME= 14:24:17.614 [2447] <2> int_GetOracleVersion: INF - Found 14:24:17.614 [2447] <2> int_GetOracleVersion: INF - leaving 14:24:17.614 [2447] <2> int_LogSystemInfo: INF - entering 14:24:17.614 [2447] <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:24:17.614 [2447] <2> int_LogSystemInfo: INF - leaving 14:24:17.614 [2447] <2> int_GetMMInfo: INF - entering 14:24:17.614 [2447] <2> int_GetMMInfo: INF - Initialized Signal 14:24:17.614 [2447] <2> int_GetMMInfo: INF - support for Proxy Copy enabled 14:24:17.614 [2447] <2> int_GetMMInfo: INF - leaving 14:24:17.614 [2447] <2> int_InitializeSbtFunctionPointers: INF - entering 14:24:17.614 [2447] <2> int_InitializeSbtFunctionPointers: INF - leaving 14:24:17.614 [2447] <2> sbtinit: INF - leaving 14:24:17.614 [2447] <2> sbtinit2: INF - entering 14:24:17.614 [2447] <2> sbtinit2: INF - leaving 14:24:22.308 [2440] <2> sbtcommand: INF - entering 14:24:22.308 [2440] <2> sbtcommand: INF - command string= 14:24:22.308 [2440] <2> int_ProcessCommandString: INF - entering 14:24:22.309 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.309 [2440] <2> int_ProcessCommand: INF - entering 14:24:22.309 [2440] <2> xbsa_SetEnv: INF - entering 14:24:22.309 [2440] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:22.309 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.309 [2440] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:22.309 [2440] <2> int_ProcessCommand: INF - leaving 14:24:22.309 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.309 [2440] <2> int_ProcessCommand: INF - entering 14:24:22.309 [2440] <2> xbsa_SetEnv: INF - entering 14:24:22.309 [2440] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:22.309 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.309 [2440] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:22.309 [2440] <2> int_ProcessCommand: INF - leaving 14:24:22.309 [2440] <2> int_ProcessCommandString: INF - leaving 14:24:22.309 [2440] <2> sbtcommand: INF - leaving 14:24:22.454 [2443] <2> sbtcommand: INF - entering 14:24:22.454 [2443] <2> sbtcommand: INF - command string= 14:24:22.454 [2443] <2> int_ProcessCommandString: INF - entering 14:24:22.454 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.454 [2443] <2> int_ProcessCommand: INF - entering 14:24:22.454 [2443] <2> xbsa_SetEnv: INF - entering 14:24:22.454 [2443] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:22.454 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.454 [2443] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:22.454 [2443] <2> int_ProcessCommand: INF - leaving 14:24:22.454 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.454 [2443] <2> int_ProcessCommand: INF - entering 14:24:22.454 [2443] <2> xbsa_SetEnv: INF - entering 14:24:22.454 [2443] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:22.454 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.454 [2443] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:22.454 [2443] <2> int_ProcessCommand: INF - leaving 14:24:22.454 [2443] <2> int_ProcessCommandString: INF - leaving 14:24:22.454 [2443] <2> sbtcommand: INF - leaving 14:24:22.615 [2447] <2> sbtcommand: INF - entering 14:24:22.615 [2447] <2> sbtcommand: INF - command string= 14:24:22.615 [2447] <2> int_ProcessCommandString: INF - entering 14:24:22.615 [2447] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.615 [2447] <2> int_ProcessCommand: INF - entering 14:24:22.615 [2447] <2> xbsa_SetEnv: INF - entering 14:24:22.615 [2447] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:22.615 [2447] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.615 [2447] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:22.615 [2447] <2> int_ProcessCommand: INF - leaving 14:24:22.615 [2447] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:22.615 [2447] <2> int_ProcessCommand: INF - entering 14:24:22.615 [2447] <2> xbsa_SetEnv: INF - entering 14:24:22.615 [2447] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:22.615 [2447] <2> xbsa_SetEnv: INF - leaving (0) 14:24:22.615 [2447] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:22.615 [2447] <2> int_ProcessCommand: INF - leaving 14:24:22.615 [2447] <2> int_ProcessCommandString: INF - leaving 14:24:22.615 [2447] <2> sbtcommand: INF - leaving 14:24:22.772 [2451] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 6.5 2010042404 14:24:22.772 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API 14:24:22.772 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.772 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID 14:24:22.772 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.772 [2451] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size 14:24:22.772 [2451] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 14:24:22.773 [2451] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.773 [2451] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:22.773 [2451] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - 5 14:24:22.773 [2451] <2> sbtinit: INF - entering 14:24:22.773 [2451] <2> xbsa_GetEnv: INF - entering 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - romepres-sol10-bu 14:24:22.773 [2451] <2> xbsa_GetEnv: INF - leaving (0) 14:24:22.773 [2451] <2> xbsa_GetEnv: INF - entering 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:22.773 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:22.773 [2451] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block 14:24:22.773 [2451] <2> xbsa_GetEnv: INF - leaving (80) 14:24:22.773 [2451] <2> int_GetOracleVersion: INF - entering 14:24:22.773 [2451] <2> int_GetOracleVersion: INF - ORACLE_HOME= 14:24:22.773 [2451] <2> int_GetOracleVersion: INF - Found 14:24:22.774 [2451] <2> int_GetOracleVersion: INF - leaving 14:24:22.774 [2451] <2> int_LogSystemInfo: INF - entering 14:24:22.774 [2451] <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:24:22.774 [2451] <2> int_LogSystemInfo: INF - leaving 14:24:22.774 [2451] <2> int_GetMMInfo: INF - entering 14:24:22.774 [2451] <2> int_GetMMInfo: INF - Initialized Signal 14:24:22.774 [2451] <2> int_GetMMInfo: INF - support for Proxy Copy enabled 14:24:22.774 [2451] <2> int_GetMMInfo: INF - leaving 14:24:22.774 [2451] <2> int_InitializeSbtFunctionPointers: INF - entering 14:24:22.774 [2451] <2> int_InitializeSbtFunctionPointers: INF - leaving 14:24:22.774 [2451] <2> sbtinit: INF - leaving 14:24:22.774 [2451] <2> sbtinit2: INF - entering 14:24:22.774 [2451] <2> sbtinit2: INF - leaving 14:24:27.310 [2440] <2> sbtcommand: INF - entering 14:24:27.310 [2440] <2> sbtcommand: INF - command string= 14:24:27.310 [2440] <2> int_ProcessCommandString: INF - entering 14:24:27.310 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.310 [2440] <2> int_ProcessCommand: INF - entering 14:24:27.310 [2440] <2> xbsa_SetEnv: INF - entering 14:24:27.310 [2440] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:27.310 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.310 [2440] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:27.310 [2440] <2> int_ProcessCommand: INF - leaving 14:24:27.310 [2440] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.310 [2440] <2> int_ProcessCommand: INF - entering 14:24:27.310 [2440] <2> xbsa_SetEnv: INF - entering 14:24:27.310 [2440] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:27.310 [2440] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.310 [2440] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:27.310 [2440] <2> int_ProcessCommand: INF - leaving 14:24:27.310 [2440] <2> int_ProcessCommandString: INF - leaving 14:24:27.310 [2440] <2> sbtcommand: INF - leaving 14:24:27.455 [2443] <2> sbtcommand: INF - entering 14:24:27.455 [2443] <2> sbtcommand: INF - command string= 14:24:27.455 [2443] <2> int_ProcessCommandString: INF - entering 14:24:27.455 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.455 [2443] <2> int_ProcessCommand: INF - entering 14:24:27.455 [2443] <2> xbsa_SetEnv: INF - entering 14:24:27.455 [2443] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:27.455 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.455 [2443] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:27.455 [2443] <2> int_ProcessCommand: INF - leaving 14:24:27.455 [2443] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.455 [2443] <2> int_ProcessCommand: INF - entering 14:24:27.455 [2443] <2> xbsa_SetEnv: INF - entering 14:24:27.455 [2443] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:27.455 [2443] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.455 [2443] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:27.455 [2443] <2> int_ProcessCommand: INF - leaving 14:24:27.455 [2443] <2> int_ProcessCommandString: INF - leaving 14:24:27.455 [2443] <2> sbtcommand: INF - leaving 14:24:27.616 [2447] <2> sbtcommand: INF - entering 14:24:27.616 [2447] <2> sbtcommand: INF - command string= 14:24:27.616 [2447] <2> int_ProcessCommandString: INF - entering 14:24:27.616 [2447] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.616 [2447] <2> int_ProcessCommand: INF - entering 14:24:27.616 [2447] <2> xbsa_SetEnv: INF - entering 14:24:27.616 [2447] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:27.616 [2447] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.616 [2447] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:27.616 [2447] <2> int_ProcessCommand: INF - leaving 14:24:27.616 [2447] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.616 [2447] <2> int_ProcessCommand: INF - entering 14:24:27.616 [2447] <2> xbsa_SetEnv: INF - entering 14:24:27.616 [2447] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:27.616 [2447] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.616 [2447] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:27.616 [2447] <2> int_ProcessCommand: INF - leaving 14:24:27.616 [2447] <2> int_ProcessCommandString: INF - leaving 14:24:27.616 [2447] <2> sbtcommand: INF - leaving 14:24:27.775 [2451] <2> sbtcommand: INF - entering 14:24:27.775 [2451] <2> sbtcommand: INF - command string= 14:24:27.775 [2451] <2> int_ProcessCommandString: INF - entering 14:24:27.775 [2451] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.775 [2451] <2> int_ProcessCommand: INF - entering 14:24:27.775 [2451] <2> xbsa_SetEnv: INF - entering 14:24:27.775 [2451] <4> VxBSASetEnv: INF - entering SetEnv - BSA_SERVICE_HOST 14:24:27.775 [2451] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.775 [2451] <2> int_ProcessCommand: INF - Server will be used for this API session. 14:24:27.775 [2451] <2> int_ProcessCommand: INF - leaving 14:24:27.775 [2451] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 14:24:27.775 [2451] <2> int_ProcessCommand: INF - entering 14:24:27.775 [2451] <2> xbsa_SetEnv: INF - entering 14:24:27.775 [2451] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_CLIENT_HOST 14:24:27.775 [2451] <2> xbsa_SetEnv: INF - leaving (0) 14:24:27.775 [2451] <2> int_ProcessCommand: INF - Client will be used for this API session. 14:24:27.775 [2451] <2> int_ProcessCommand: INF - leaving 14:24:27.775 [2451] <2> int_ProcessCommandString: INF - leaving 14:24:27.775 [2451] <2> sbtcommand: INF - leaving 14:24:28.311 [2440] <2> sbtinfo2: INF - entering 14:24:28.311 [2440] <2> sbtinfo2: INF - requesting image info for 14:24:28.311 [2440] <2> int_GetImageInfo2: INF - entering 14:24:28.311 [2440] <2> int_GetImageInfo2: INF - Image Information for file : 14:24:28.311 [2440] <2> xbsa_ValidateFeatureId: INF - entering 14:24:28.311 [2440] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 14:24:28.311 [2440] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 14:24:28.311 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:28.311 [2440] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:28.312 [2440] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle 14:24:28.312 [2440] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:28.312 [2440] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:28.312 [2440] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:28.312 [2440] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:28.313 [2440] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:28.313 [2440] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:28.313 [2440] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:28.313 [2440] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:28.313 [2440] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:28.338 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:28.338 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:28.387 [2440] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:28.387 [2440] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.50598 TO 10.10.36.33.13724 fd = 19 14:24:28.387 [2440] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.50598 TO 10.10.36.33.13724 14:24:28.388 [2440] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:28.388 [2440] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:28.388 [2440] <2> bprd_connect: no authentication required 14:24:28.388 [2440] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:28.466 [2440] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId 14:24:28.466 [2440] <2> xbsa_ValidateFeatureId: INF - leaving (0) 14:24:28.466 [2440] <2> int_FindBackupImage: INF - entering 14:24:28.466 [2440] <2> int_GetBfsDateRange: INF - entering 14:24:28.467 [2440] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097 14:24:28.467 [2440] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile 14:24:28.467 [2440] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967645468 14:24:28.467 [2440] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518290668 14:24:28.467 [2440] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37 14:24:28.467 [2440] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37 14:24:28.467 [2440] <2> int_logDateRange: INF - leaving 14:24:28.467 [2440] <2> xbsa_BeginTransaction: INF - entering 14:24:28.467 [2440] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:28.467 [2440] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:28.467 [2440] <2> xbsa_QueryObject: INF - entering 14:24:28.467 [2440] <4> VxBSAQueryObject: INF - entering QueryObject. 14:24:28.467 [2440] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:28.467 [2440] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:28.467 [2440] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:28.468 [2440] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:28.468 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:28.468 [2440] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName. 14:24:28.468 [2440] <4> getOwnerName: entering getOwnerName. 14:24:28.468 [2440] <4> BuildBprdRequest: entering BuildBprdRequest. 14:24:28.468 [2440] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:28.468 [2440] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:28.468 [2440] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:28.494 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:28.494 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:28.537 [2440] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:28.537 [2440] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.37041 TO 10.10.36.33.13724 fd = 19 14:24:28.537 [2440] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.37041 TO 10.10.36.33.13724 14:24:28.537 [2440] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:28.537 [2440] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:28.537 [2440] <2> bprd_connect: no authentication required 14:24:28.537 [2440] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:28.905 [2440] <4> dbc_GetMediaListByName: Media ID : 14:24:28.905 [2440] <4> dbc_GetMediaListByName: File Creation Date and Time : <1516498928> 14:24:28.905 [2440] <4> dbc_GetMediaListByName: File Expiration Date and Time: <1524534128> 14:24:28.905 [2440] <4> dbc_GetMediaListByName: Comment : 14:24:28.905 [2440] <4> dbc_GetMediaListByName: Keyword : 14:24:28.905 [2440] <4> VxBSAQueryObject: INF - Object was found on media . 14:24:28.905 [2440] <2> xbsa_QueryObject: INF - leaving (0) 14:24:28.905 [2440] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928> 14:24:28.905 [2440] <2> xbsa_EndTransaction: INF - entering 14:24:28.905 [2440] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:24:28.905 [2440] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:24:28.905 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:24:28.905 [2440] <4> VxBSAGetEnv: INF - returning - dbclient 14:24:28.905 [2440] <4> VxBSAEndTxn: INF - Cleaning directory: 14:24:28.905 [2440] <4> delete_old_files: entering delete_old_files. 14:24:28.906 [2440] <2> xbsa_EndTransaction: INF - leaving (0) 14:24:28.906 [2440] <2> int_FindBackupImage: INF - leaving 14:24:28.906 [2440] <2> int_SetImageInfoArray: INF - entering 14:24:28.906 [2440] <2> int_SetImageInfoArray: INF - leaving 14:24:28.906 [2440] <2> int_GetImageInfo2: INF - leaving 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - entering 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - Media Information for Backup File : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - Media Sharing Mode : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - File Ordering Mode : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - Media ID : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928> 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128> 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - Comment : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - File Creation Method : 14:24:28.906 [2440] <2> int_DumpSbtInfo: INF - leaving 14:24:28.906 [2440] <2> sbtinfo2: INF - leaving 14:24:29.456 [2443] <2> sbtinfo2: INF - entering 14:24:29.456 [2443] <2> sbtinfo2: INF - requesting image info for 14:24:29.456 [2443] <2> int_GetImageInfo2: INF - entering 14:24:29.456 [2443] <2> int_GetImageInfo2: INF - Image Information for file : 14:24:29.456 [2443] <2> xbsa_ValidateFeatureId: INF - entering 14:24:29.456 [2443] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 14:24:29.456 [2443] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 14:24:29.456 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:29.456 [2443] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:29.457 [2443] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:29.457 [2443] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:29.458 [2443] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:29.482 [2443] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:29.482 [2443] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:29.527 [2443] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:29.527 [2443] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.39733 TO 10.10.36.33.13724 fd = 19 14:24:29.527 [2443] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.39733 TO 10.10.36.33.13724 14:24:29.527 [2443] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:29.527 [2443] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:29.527 [2443] <2> bprd_connect: no authentication required 14:24:29.528 [2443] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:29.606 [2443] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId 14:24:29.606 [2443] <2> xbsa_ValidateFeatureId: INF - leaving (0) 14:24:29.606 [2443] <2> int_FindBackupImage: INF - entering 14:24:29.606 [2443] <2> int_GetBfsDateRange: INF - entering 14:24:29.606 [2443] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097 14:24:29.606 [2443] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile 14:24:29.606 [2443] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967645469 14:24:29.606 [2443] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518290669 14:24:29.606 [2443] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37 14:24:29.606 [2443] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37 14:24:29.606 [2443] <2> int_logDateRange: INF - leaving 14:24:29.606 [2443] <2> xbsa_BeginTransaction: INF - entering 14:24:29.606 [2443] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:29.606 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:29.606 [2443] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:29.607 [2443] <2> xbsa_QueryObject: INF - entering 14:24:29.607 [2443] <4> VxBSAQueryObject: INF - entering QueryObject. 14:24:29.607 [2443] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:29.607 [2443] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:29.607 [2443] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:29.607 [2443] <4> VxBSAGetEnv: INF - returning - 14:24:29.607 [2443] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName. 14:24:29.607 [2443] <4> getOwnerName: entering getOwnerName. 14:24:29.607 [2443] <4> BuildBprdRequest: entering BuildBprdRequest. 14:24:29.607 [2443] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:29.607 [2443] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:29.607 [2443] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:29.633 [2443] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:29.633 [2443] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:29.677 [2443] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:29.677 [2443] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.37823 TO 10.10.36.33.13724 fd = 19 14:24:29.677 [2443] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.37823 TO 10.10.36.33.13724 14:24:29.677 [2443] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:29.677 [2443] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:29.677 [2443] <2> bprd_connect: no authentication required 14:24:29.677 [2443] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:29.993 [2443] <4> dbc_GetMediaListByName: Media ID : 14:24:29.994 [2443] <4> dbc_GetMediaListByName: File Creation Date and Time : <1516498928> 14:24:29.994 [2443] <4> dbc_GetMediaListByName: File Expiration Date and Time: <1524534128> 14:24:29.994 [2443] <4> dbc_GetMediaListByName: Comment : 14:24:29.994 [2443] <4> dbc_GetMediaListByName: Keyword : 14:24:29.994 [2443] <4> VxBSAQueryObject: INF - Object was found on media . 14:24:29.994 [2443] <2> xbsa_QueryObject: INF - leaving (0) 14:24:29.994 [2443] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928> 14:24:29.994 [2443] <2> xbsa_EndTransaction: INF - entering 14:24:29.994 [2443] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:24:29.994 [2443] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:24:29.994 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:24:29.994 [2443] <4> VxBSAGetEnv: INF - returning - dbclient 14:24:29.994 [2443] <4> VxBSAEndTxn: INF - Cleaning directory: 14:24:29.994 [2443] <4> delete_old_files: entering delete_old_files. 14:24:29.994 [2443] <2> xbsa_EndTransaction: INF - leaving (0) 14:24:29.994 [2443] <2> int_FindBackupImage: INF - leaving 14:24:29.994 [2443] <2> int_SetImageInfoArray: INF - entering 14:24:29.994 [2443] <2> int_SetImageInfoArray: INF - leaving 14:24:29.994 [2443] <2> int_GetImageInfo2: INF - leaving 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - entering 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - Media Information for Backup File : 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - Media Sharing Mode : 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - File Ordering Mode : 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - Media ID : 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928> 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128> 14:24:29.994 [2443] <2> int_DumpSbtInfo: INF - Comment : 14:24:29.995 [2443] <2> int_DumpSbtInfo: INF - File Creation Method : 14:24:29.995 [2443] <2> int_DumpSbtInfo: INF - leaving 14:24:29.995 [2443] <2> sbtinfo2: INF - leaving 14:24:30.617 [2447] <2> sbtinfo2: INF - entering 14:24:30.617 [2447] <2> sbtinfo2: INF - requesting image info for 14:24:30.617 [2447] <2> int_GetImageInfo2: INF - entering 14:24:30.617 [2447] <2> int_GetImageInfo2: INF - Image Information for file : 14:24:30.617 [2447] <2> xbsa_ValidateFeatureId: INF - entering 14:24:30.617 [2447] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 14:24:30.617 [2447] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 14:24:30.617 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:30.617 [2447] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:30.618 [2447] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:30.618 [2447] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:30.619 [2447] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:30.642 [2447] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:30.642 [2447] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:30.687 [2447] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:30.687 [2447] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.39783 TO 10.10.36.33.13724 fd = 19 14:24:30.687 [2447] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.39783 TO 10.10.36.33.13724 14:24:30.687 [2447] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:30.687 [2447] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:30.687 [2447] <2> bprd_connect: no authentication required 14:24:30.687 [2447] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:30.766 [2447] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId 14:24:30.766 [2447] <2> xbsa_ValidateFeatureId: INF - leaving (0) 14:24:30.766 [2447] <2> int_FindBackupImage: INF - entering 14:24:30.766 [2447] <2> int_GetBfsDateRange: INF - entering 14:24:30.766 [2447] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097 14:24:30.766 [2447] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile 14:24:30.766 [2447] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967645470 14:24:30.766 [2447] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518290670 14:24:30.766 [2447] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37 14:24:30.766 [2447] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37 14:24:30.766 [2447] <2> int_logDateRange: INF - leaving 14:24:30.766 [2447] <2> xbsa_BeginTransaction: INF - entering 14:24:30.766 [2447] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:30.766 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:30.766 [2447] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:30.766 [2447] <2> xbsa_QueryObject: INF - entering 14:24:30.767 [2447] <4> VxBSAQueryObject: INF - entering QueryObject. 14:24:30.767 [2447] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:30.767 [2447] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:30.767 [2447] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:30.767 [2447] <4> VxBSAGetEnv: INF - returning - 14:24:30.767 [2447] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName. 14:24:30.767 [2447] <4> getOwnerName: entering getOwnerName. 14:24:30.767 [2447] <4> BuildBprdRequest: entering BuildBprdRequest. 14:24:30.767 [2447] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:30.767 [2447] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:30.767 [2447] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:30.792 [2447] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:30.792 [2447] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:30.837 [2447] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:30.837 [2447] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.62100 TO 10.10.36.33.13724 fd = 19 14:24:30.837 [2447] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.62100 TO 10.10.36.33.13724 14:24:30.837 [2447] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:30.837 [2447] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:30.837 [2447] <2> bprd_connect: no authentication required 14:24:30.838 [2447] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:31.155 [2447] <4> dbc_GetMediaListByName: Media ID : 14:24:31.155 [2447] <4> dbc_GetMediaListByName: File Creation Date and Time : <1516498928> 14:24:31.155 [2447] <4> dbc_GetMediaListByName: File Expiration Date and Time: <1524534128> 14:24:31.155 [2447] <4> dbc_GetMediaListByName: Comment : 14:24:31.155 [2447] <4> dbc_GetMediaListByName: Keyword : 14:24:31.155 [2447] <4> VxBSAQueryObject: INF - Object was found on media . 14:24:31.155 [2447] <2> xbsa_QueryObject: INF - leaving (0) 14:24:31.155 [2447] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928> 14:24:31.155 [2447] <2> xbsa_EndTransaction: INF - entering 14:24:31.155 [2447] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:24:31.155 [2447] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:24:31.155 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:24:31.155 [2447] <4> VxBSAGetEnv: INF - returning - dbclient 14:24:31.155 [2447] <4> VxBSAEndTxn: INF - Cleaning directory: 14:24:31.155 [2447] <4> delete_old_files: entering delete_old_files. 14:24:31.155 [2447] <2> xbsa_EndTransaction: INF - leaving (0) 14:24:31.155 [2447] <2> int_FindBackupImage: INF - leaving 14:24:31.155 [2447] <2> int_SetImageInfoArray: INF - entering 14:24:31.155 [2447] <2> int_SetImageInfoArray: INF - leaving 14:24:31.155 [2447] <2> int_GetImageInfo2: INF - leaving 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - entering 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - Media Information for Backup File : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - Media Sharing Mode : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - File Ordering Mode : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - Media ID : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928> 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128> 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - Comment : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - File Creation Method : 14:24:31.156 [2447] <2> int_DumpSbtInfo: INF - leaving 14:24:31.156 [2447] <2> sbtinfo2: INF - leaving 14:24:31.776 [2451] <2> sbtinfo2: INF - entering 14:24:31.776 [2451] <2> sbtinfo2: INF - requesting image info for 14:24:31.776 [2451] <2> int_GetImageInfo2: INF - entering 14:24:31.776 [2451] <2> int_GetImageInfo2: INF - Image Information for file : 14:24:31.776 [2451] <2> xbsa_ValidateFeatureId: INF - entering 14:24:31.776 [2451] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 14:24:31.776 [2451] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 14:24:31.776 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:31.776 [2451] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:31.777 [2451] <2> vauth_get_user_name: vauth_comm.c.695: user_name: oracle 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1045: host_cache_size: 200 0x000000c8 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1046: cache_time: 3600 0x00000e10 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1060: host_failed_cache_size: 40 0x00000028 14:24:31.777 [2451] <2> init_cache: vnet_hosts.c.1061: cache_time: 3600 0x00000e10 14:24:31.778 [2451] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:31.801 [2451] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:31.801 [2451] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:31.847 [2451] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:31.847 [2451] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.58630 TO 10.10.36.33.13724 fd = 19 14:24:31.847 [2451] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.58630 TO 10.10.36.33.13724 14:24:31.847 [2451] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:31.847 [2451] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:31.847 [2451] <2> bprd_connect: no authentication required 14:24:31.847 [2451] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:31.926 [2451] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId 14:24:31.926 [2451] <2> xbsa_ValidateFeatureId: INF - leaving (0) 14:24:31.926 [2451] <2> int_FindBackupImage: INF - entering 14:24:31.926 [2451] <2> int_GetBfsDateRange: INF - entering 14:24:31.926 [2451] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097 14:24:31.926 [2451] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile 14:24:31.926 [2451] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967645471 14:24:31.926 [2451] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518290671 14:24:31.926 [2451] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37 14:24:31.926 [2451] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37 14:24:31.926 [2451] <2> int_logDateRange: INF - leaving 14:24:31.926 [2451] <2> xbsa_BeginTransaction: INF - entering 14:24:31.926 [2451] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:31.927 [2451] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:31.927 [2451] <2> xbsa_QueryObject: INF - entering 14:24:31.927 [2451] <4> VxBSAQueryObject: INF - entering QueryObject. 14:24:31.927 [2451] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:31.927 [2451] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:31.927 [2451] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:31.927 [2451] <4> VxBSAGetEnv: INF - returning - 14:24:31.927 [2451] <4> dbc_GetMediaListByName: entering dbc_GetMediaListByName. 14:24:31.927 [2451] <4> getOwnerName: entering getOwnerName. 14:24:31.927 [2451] <4> BuildBprdRequest: entering BuildBprdRequest. 14:24:31.927 [2451] <4> BuildBprdRequest: request_string=<6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:31.927 [2451] <4> dbc_GetMediaListByName: Request String = <6.5 rome_pres-bu romepres-sol10-bu *NULL* 4 1516416097 1516588897 /iwhprod_20180120090005_ctl_86296_1_965940097> 14:24:31.928 [2451] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:31.954 [2451] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:31.954 [2451] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:31.955 [2451] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:31.955 [2451] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.52662 TO 10.10.36.33.13724 fd = 19 14:24:31.955 [2451] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.52662 TO 10.10.36.33.13724 14:24:31.955 [2451] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:31.955 [2451] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:31.955 [2451] <2> bprd_connect: no authentication required 14:24:31.955 [2451] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:32.272 [2451] <4> dbc_GetMediaListByName: Media ID : 14:24:32.273 [2451] <4> dbc_GetMediaListByName: File Creation Date and Time : <1516498928> 14:24:32.273 [2451] <4> dbc_GetMediaListByName: File Expiration Date and Time: <1524534128> 14:24:32.273 [2451] <4> dbc_GetMediaListByName: Comment : 14:24:32.273 [2451] <4> dbc_GetMediaListByName: Keyword : 14:24:32.273 [2451] <4> VxBSAQueryObject: INF - Object was found on media . 14:24:32.273 [2451] <2> xbsa_QueryObject: INF - leaving (0) 14:24:32.273 [2451] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928> 14:24:32.273 [2451] <2> xbsa_EndTransaction: INF - entering 14:24:32.273 [2451] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:24:32.273 [2451] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:24:32.273 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:24:32.273 [2451] <4> VxBSAGetEnv: INF - returning - dbclient 14:24:32.273 [2451] <4> VxBSAEndTxn: INF - Cleaning directory: 14:24:32.273 [2451] <4> delete_old_files: entering delete_old_files. 14:24:32.273 [2451] <2> xbsa_EndTransaction: INF - leaving (0) 14:24:32.273 [2451] <2> int_FindBackupImage: INF - leaving 14:24:32.273 [2451] <2> int_SetImageInfoArray: INF - entering 14:24:32.273 [2451] <2> int_SetImageInfoArray: INF - leaving 14:24:32.273 [2451] <2> int_GetImageInfo2: INF - leaving 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - entering 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - Media Information for Backup File : 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - Media Sharing Mode : 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - File Ordering Mode : 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - Media ID : 14:24:32.273 [2451] <2> int_DumpSbtInfo: INF - File Creation Date and Time : <1516498928> 14:24:32.274 [2451] <2> int_DumpSbtInfo: INF - File Expiration Date and Time : <1524534128> 14:24:32.274 [2451] <2> int_DumpSbtInfo: INF - Comment : 14:24:32.274 [2451] <2> int_DumpSbtInfo: INF - File Creation Method : 14:24:32.274 [2451] <2> int_DumpSbtInfo: INF - leaving 14:24:32.274 [2451] <2> sbtinfo2: INF - leaving 14:24:36.907 [2440] <2> sbtrestore: INF - entering 14:24:36.907 [2440] <2> int_StartJob: INF - entering 14:24:36.907 [2440] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_POLICY 14:24:36.907 [2440] <2> int_IsSAPBackup: INF - entering 14:24:36.907 [2440] <2> int_IsSAPBackup: INF - leaving 14:24:36.907 [2440] <2> xbsa_ValidateFeatureId: INF - entering 14:24:36.907 [2440] <2> xbsa_ValidateFeatureId: INF - leaving (0) 14:24:36.907 [2440] <2> int_SetKeyWord: INF - entering 14:24:36.907 [2440] <2> int_FindBackupImage: INF - entering 14:24:36.907 [2440] <2> int_GetBfsDateRange: INF - entering 14:24:36.907 [2440] <2> int_GetBfsDateRange: INF - RMAN file name = iwhprod_20180120090005_ctl_86296_1_965940097 14:24:36.908 [2440] <2> int_GetBfsDateRange: INF - not an auto controlfile, check for datafile 14:24:36.908 [2440] <2> int_GetBfsDateRange: INF - bfs_time=965940097,start_time=257126400,end_time=967645476 14:24:36.908 [2440] <2> int_GetBfsDateRange: INF - Start range check: bfs_time=1516502497, start=819936000, end=1518290676 14:24:36.908 [2440] <2> int_logDateRange: INF - Start Time = 01/19/18 21:41:37 14:24:36.908 [2440] <2> int_logDateRange: INF - End Time = 01/21/18 21:41:37 14:24:36.908 [2440] <2> int_logDateRange: INF - leaving 14:24:36.908 [2440] <2> xbsa_BeginTransaction: INF - entering 14:24:36.908 [2440] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:36.908 [2440] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:36.908 [2440] <2> xbsa_QueryObject: INF - entering 14:24:36.908 [2440] <4> VxBSAQueryObject: INF - entering QueryObject. 14:24:36.908 [2440] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:36.908 [2440] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:36.908 [2440] <8> VxBSAQueryObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - NONE 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:36.908 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:36.908 [2440] <4> bsa_bplist: entering bsa_bplist 14:24:36.909 [2440] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:36.935 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:36.935 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:36.977 [2440] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:36.977 [2440] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.34907 TO 10.10.36.33.13724 fd = 19 14:24:36.977 [2440] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.34907 TO 10.10.36.33.13724 14:24:36.977 [2440] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:36.977 [2440] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:36.977 [2440] <2> bprd_connect: no authentication required 14:24:36.977 [2440] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:36.977 [2440] <2> bsa_bplist: start_date = Fri Jan 19 21:41:37 2018 14:24:36.977 [2440] <2> bsa_bplist: end_date = Sun Jan 21 21:41:37 2018 14:24:36.977 [2440] <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:24:36.977 [2440] <4> bsa_bplist: Filepath = /iwhprod_20180120090005_ctl_86296_1_965940097 14:24:37.304 [2440] <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:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectOwner.bsa_ObjectOwner: 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectOwner.app_ObjectOwner: 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectName.objectSpaceName: Oracle Database 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectName.pathName: /iwhprod_20180120090005_ctl_86296_1_965940097 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - copyType: 3 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectType: 4 14:24:37.305 [2440] <4> bsa_printQueryDescriptor: INF - objectStatus: 2 14:24:37.305 [2440] <4> bsa_GetNextObject: INF - entering bsa_GetNextObject 14:24:37.305 [2440] <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:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - entering bsa_AssignObjDesc. 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - compressed_size: 16777216 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - compressed_size_gig: 0 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - owner: oracle 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - group: dba 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - file_size: 16777216 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - fileNamelen: 45 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - image_time: 1516498928 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - fileName: /iwhprod_20180120090005_ctl_86296_1_965940097 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - bsa_objectOwner: 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - app_objectOwner: 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - objectSpaceName: Oracle Database 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - copyType: 3 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - objectType: 4 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - objectStatus: 1 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - resourceType: Oracle Backup 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - objectDescription: 14:24:37.305 [2440] <4> bsa_AssignObjDesc: INF - objectInfo: 14:24:37.305 [2440] <4> bsa_AssignObjDesc: n_tuple: 16777216 0 0 0 16777216 1 0 -1 45 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097 14:24:37.305 [2440] <4> bsa_GetNextObject: INF - Object Matched, Checking objectStatus 14:24:37.305 [2440] <4> bsa_SetObjectStatus: INF - entering bsa_SetObjectStatus 14:24:37.305 [2440] <4> bsa_SetObjectStatus: INF - file /iwhprod_20180120090005_ctl_86296_1_965940097 found status: 0 14:24:37.306 [2440] <4> bsa_GetNextObject: INF - Object Matched ALL query criteria 14:24:37.306 [2440] <2> xbsa_QueryObject: INF - leaving (0) 14:24:37.306 [2440] <2> int_FindBackupImage: INF - XBSA Creation Date and Time : <1516498928> 14:24:37.306 [2440] <2> xbsa_EndTransaction: INF - entering 14:24:37.306 [2440] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:24:37.306 [2440] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - returning - dbclient 14:24:37.306 [2440] <4> VxBSAEndTxn: INF - Cleaning directory: 14:24:37.306 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.306 [2440] <2> xbsa_EndTransaction: INF - leaving (0) 14:24:37.306 [2440] <2> int_FindBackupImage: INF - leaving 14:24:37.306 [2440] <2> xbsa_BeginTransaction: INF - entering 14:24:37.306 [2440] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - returning - Oracle 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 14:24:37.306 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.306 [2440] <2> xbsa_BeginTransaction: INF - leaving (0) 14:24:37.306 [2440] <2> xbsa_ProgressLogSetup: INF - entering 14:24:37.306 [2440] <2> xbsa_ProgressLogSetup: INF - No progress log: Job will proceed without progress logging 14:24:37.306 [2440] <2> xbsa_ProgressLogSetup: INF - leaving 14:24:37.306 [2440] <2> xbsa_GetObject: INF - entering 14:24:37.306 [2440] <4> VxBSAGetObject: INF - entering GetObject 14:24:37.307 [2440] <4> VxBSAGetObject: INF - Using restore priority 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - NONE 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_PROGRESS_LOG_FILE 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - 14:24:37.307 [2440] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 14:24:37.307 [2440] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - atlbackupmaster 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 14:24:37.307 [2440] <4> VxBSAGetEnv: INF - returning - rome_pres-bu 14:24:37.307 [2440] <8> VxBSAGetObject: WARNING - NBBSA_CLIENT_HOST name= differs from NBU config name= 14:24:37.307 [2440] <4> bsa_RestoreFileObject: INF - entering bsa_RestoreFileObject. 14:24:37.307 [2440] <4> RestoreFileObjects: INF - entering RestoreFileObjects. 14:24:37.307 [2440] <4> initOpen: entering initOpen. 14:24:37.307 [2440] <4> openProgressFile: entering openProgressFile. 14:24:37.307 [2440] <4> openProgressFile: commFull = 14:24:37.308 [2440] <4> openProgressFile: cleaning directory: 14:24:37.308 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.308 [2440] <4> openProgressFile: cleaning directory: 14:24:37.308 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.308 [2440] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/oracle does not exist 14:24:37.308 [2440] <4> openProgressFile: cleaning directory: 14:24:37.308 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.308 [2440] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/oracle does not exist 14:24:37.308 [2440] <4> openProgressFile: cleaning directory: 14:24:37.308 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.308 [2440] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbtemplates/db2 does not exist 14:24:37.308 [2440] <4> openProgressFile: cleaning directory: 14:24:37.308 [2440] <4> delete_old_files: entering delete_old_files. 14:24:37.308 [2440] <8> delete_old_files: WRN - Directory /usr/openv/netbackup/logs/user_ops/dbext/jobs does not exist 14:24:37.308 [2440] <4> openProgressFile: progressFD = <19> 14:24:37.308 [2440] <4> openProgressFile: progressFD_en = <-1> 14:24:37.308 [2440] <4> RestoreFileObjects: INF - restoring File: 14:24:37.308 [2440] <4> sendRequest: entering sendRequest. 14:24:37.308 [2440] <4> sendRequest: Progress log not for monitoring= 14:24:37.308 [2440] <4> getOwnerName: entering getOwnerName. 14:24:37.309 [2440] <4> sendRequest: sending RESTORE request to bprd 14:24:37.309 [2440] <4> sendRequest: request: 14:24:37.309 [2440] <2> vnet_async_connect: vnet_vnetd.c.4039: connect in progress: 0 0x00000000 14:24:37.366 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2048: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 14:24:37.366 [2440] <2> vnet_vnetd_service_socket: vnet_vnetd.c.2062: service: bprd 14:24:37.417 [2440] <2> vnet_async_connect: vnet_vnetd.c.4225: in progress connect: 0 0x00000000 14:24:37.417 [2440] <2> vnet_async_connect: vnet_vnetd.c.4228: connect: async CONNECT FROM 10.10.36.24.63064 TO 10.10.36.33.13724 fd = 20 14:24:37.417 [2440] <2> logconnections: BPRD CONNECT FROM 10.10.36.24.63064 TO 10.10.36.33.13724 14:24:37.417 [2440] <2> vauth_authentication_required: vauth_comm.c.749: no methods for address: no authentication required 14:24:37.417 [2440] <2> vauth_connector: vauth_comm.c.182: no methods for address: no authentication required 14:24:37.418 [2440] <2> bprd_connect: no authentication required 14:24:37.418 [2440] <2> vnet_dlopen_vxss_client_magic: vnet_vxss.c.1766: Assuming no VxSS for DB Agents: 0 0x00000000 14:24:38.019 [2440] <4> sendRequest: sending buf = 1516498928 1516498928 /iwhprod_20180120090005_ctl_86296_1_965940097 14:24:38.019 [2440] <4> sendRequest: Date range: <-s 01/20/18 20:42:08>, <-e 01/20/18 20:42:08> 14:24:38.019 [2440] <4> serverResponse: entering serverResponse. 14:24:38.019 [2440] <4> serverResponse: initial client_read_timeout = <30000> 14:24:38.020 [2440] <4> readCommMessages: Entering readCommMessages 14:24:48.020 [2440] <4> serverResponse: read comm file:<14:24:39 (3782174.xxx) Restore job id 3782174 will require 1 image.> 14:24:48.020 [2440] <4> serverResponse: read comm file:<14:24:40 (3782174.xxx) Media id AL6608 is needed for the restore.> 14:24:48.020 [2440] <4> readCommMessages: Entering readCommMessages 14:24:53.020 [2440] <4> serverResponse: read comm file:<14:24:44 (3782174.001) Restoring from image created Sat Jan 20 20:42:08 2018> 14:24:53.021 [2440] <4> serverResponse: read comm file:<14:24:45 (3782174.001) INF - If Media id AL6608 is not in a robotic library administrative interaction may be required to satisfy this mount request.> 14:24:53.021 [2440] <4> serverResponse: read comm file:<14:24:45 (3782174.001) INF - No drives available or requesting resources> 14:24:53.021 [2440] <4> serverResponse: INF - Waiting for a resource to become available, resetting timeout 14:24:53.021 [2440] <4> serverResponse: INF - comm file read timeout set to 360000 14:24:53.021 [2440] <4> readCommMessages: Entering readCommMessages 14:24:58.021 [2440] <4> serverResponse: read comm file:<14:24:48 (3782174.001) INF - Waiting for mount of media id AL6608 on server virt-hooey-bu for reading.> 14:24:58.021 [2440] <4> serverResponse: INF - media mount timeout set to 360000 14:24:58.021 [2440] <4> readCommMessages: Entering readCommMessages 14:25:43.023 [2440] <4> serverResponse: read comm file:<14:25:34 (3782174.001) INF - Waiting for positioning of media id AL6608 on server virt-hooey-bu for reading.> 14:25:43.023 [2440] <4> serverResponse: INF - media positioning timeout set to 30000 14:25:43.023 [2440] <4> readCommMessages: Entering readCommMessages 14:26:38.026 [2440] <4> serverResponse: read comm file:<14:26:29 (3782174.001) INF - Beginning restore from server virt-hooey-bu to client romepres-sol10-bu.> 14:26:38.026 [2440] <4> serverResponse: INF - comm file read timeout set to 30000 14:26:38.026 [2440] <4> readCommMessages: Entering readCommMessages 14:28:28.030 [2440] <4> serverResponse: read comm file:<14:28:20 INF - Server status = 150> 14:28:28.030 [2440] <16> serverResponse: ERR - server exited with status 150: termination requested by administrator 14:28:28.030 [2440] <16> RestoreFileObjects: ERR - serverResponse() failed 14:28:28.030 [2440] <4> closeApi: entering closeApi. 14:28:28.030 [2440] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files 14:28:28.031 [2440] <16> VxBSAGetObject: ERR - System error occurred trying to retrieve object in RestoreFileObject. Status: 3 14:28:28.031 [2440] <2> xbsa_ProcessError: INF - entering 14:28:28.031 [2440] <2> xbsa_ProcessError: INF - leaving 14:28:28.031 [2440] <16> xbsa_GetObject: ERR - VxBSAGetObject: Failed with error: Server Status: termination requested by administrator 14:28:28.031 [2440] <2> xbsa_GetObject: INF - leaving (3) 14:28:28.031 [2440] <16> int_StartJob: ERR - Failed to open backup file for restore. 14:28:28.031 [2440] <2> int_StartJob: INF - leaving 14:28:28.031 [2440] <2> sbtrestore: INF - leaving 14:28:28.031 [2440] <2> sbterror: INF - entering 14:28:28.031 [2440] <2> sbterror: INF - Error=7501: Failed to open backup file for restore. . 14:28:28.031 [2440] <2> sbterror: INF - leaving 14:28:32.033 [2440] <2> sbtend: INF - entering 14:28:32.033 [2440] <2> int_FreeDuplexStrings: INF - entering 14:28:32.033 [2440] <2> int_FreeDuplexStrings: INF - leaving 14:28:32.033 [2440] <4> sbtend: INF - --- END of SESSION --- 14:28:32.033 [2440] <2> sbtend: INF - leaving 14:28:32.033 [2440] <2> xbsa_EndTransaction: INF - entering 14:28:32.033 [2440] <4> VxBSAEndTxn: INF - entering VxBSAEndTxn. 14:28:32.033 [2440] <4> VxBSAEndTxn: INF - Transaction being COMMITED. 14:28:32.034 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_LOG_DIRECTORY 14:28:32.034 [2440] <4> VxBSAGetEnv: INF - returning - dbclient 14:28:32.034 [2440] <4> VxBSAEndTxn: INF - Cleaning directory: 14:28:32.034 [2440] <4> delete_old_files: entering delete_old_files. 14:28:32.034 [2440] <8> close_image: Session being terminated abnormally, cleaning up 14:28:32.034 [2440] <4> closeApi: entering closeApi. 14:28:32.034 [2440] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files 14:28:32.034 [2440] <4> close_image: INF - restore FAILED 14:28:32.034 [2440] <4> close_image: INF ---- end of Restore --- 14:28:32.034 [2440] <16> VxBSAEndTxn: ERR - Transaction ended with active Backup/Restore. 14:28:32.034 [2440] <2> xbsa_ProcessError: INF - entering 14:28:32.034 [2440] <2> xbsa_ProcessError: INF - leaving 14:28:32.034 [2440] <16> xbsa_EndTransaction: ERR - VxBSAEndTxn: Failed with error: The transaction was aborted. 14:28:32.034 [2440] <2> xbsa_EndTransaction: INF - leaving (32) 14:28:32.034 [2440] <4> VxBSATerminate: INF - entering VxBSATerminate. 14:28:32.034 [2440] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD 14:28:32.034 [2440] <4> VxBSAGetEnv: INF - returning - 14:28:33.014 [2443] <2> sbtend: INF - entering 14:28:33.014 [2443] <2> int_FreeDuplexStrings: INF - entering 14:28:33.014 [2443] <2> int_FreeDuplexStrings: INF - leaving 14:28:33.014 [2443] <2> int_CleanupInfo2: INF - entering 14:28:33.014 [2443] <2> int_CleanupInfo2: INF - leaving 14:28:33.014 [2443] <4> sbtend: INF - --- END of SESSION --- 14:28:33.014 [2443] <2> sbtend: INF - leaving 14:28:33.014 [2443] <4> VxBSATerminate: INF - entering VxBSATerminate. 14:28:33.014 [2443] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD 14:28:33.014 [2443] <4> VxBSAGetEnv: INF - returning - 14:28:33.173 [2447] <2> sbtend: INF - entering 14:28:33.173 [2447] <2> int_FreeDuplexStrings: INF - entering 14:28:33.173 [2447] <2> int_FreeDuplexStrings: INF - leaving 14:28:33.174 [2447] <2> int_CleanupInfo2: INF - entering 14:28:33.174 [2447] <2> int_CleanupInfo2: INF - leaving 14:28:33.174 [2447] <4> sbtend: INF - --- END of SESSION --- 14:28:33.174 [2447] <2> sbtend: INF - leaving 14:28:33.174 [2447] <4> VxBSATerminate: INF - entering VxBSATerminate. 14:28:33.174 [2447] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD 14:28:33.174 [2447] <4> VxBSAGetEnv: INF - returning - 14:28:33.292 [2451] <2> sbtend: INF - entering 14:28:33.292 [2451] <2> int_FreeDuplexStrings: INF - entering 14:28:33.292 [2451] <2> int_FreeDuplexStrings: INF - leaving 14:28:33.292 [2451] <2> int_CleanupInfo2: INF - entering 14:28:33.292 [2451] <2> int_CleanupInfo2: INF - leaving 14:28:33.292 [2451] <4> sbtend: INF - --- END of SESSION --- 14:28:33.292 [2451] <2> sbtend: INF - leaving 14:28:33.292 [2451] <4> VxBSATerminate: INF - entering VxBSATerminate. 14:28:33.292 [2451] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD 14:28:33.292 [2451] <4> VxBSAGetEnv: INF - returning -