09:31:31.757 [3131] <4> VxBSAInit: Veritas NetBackup XBSA Interface - 7.7.3 2016051915 Log Init Info 2 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_API 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_FEATURE_ID 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.757 [3131] <4> InitializeClientInfo: NOT doing setsockopt() to set network buffer size 09:31:31.757 [3131] <4> VxBSAGetLocaleInfo: INF - entering VxBSAGetLocaleInfo 09:31:31.757 [3131] <4> VxBSASetEnvFromConfig: INF - entering VxBSASetEnvFromConfig 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.757 [3131] <4> dbc_GetClassNameFromConfig: Default backup policy not configured. 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.757 [3131] <4> dbc_GetScheduleNameFromConfig: Default schedule not configured. 09:31:31.757 [3131] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 09:31:31.757 [3131] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - chbsux0431.eu.novartis.net 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_VERBOSE_LEVEL 09:31:31.757 [3131] <4> VxBSAGetEnv: INF - returning - 5 09:31:31.757 [3131] <2> sbtinit: INF - entering 09:31:31.757 [3131] <2> xbsa_GetEnv: INF - entering 09:31:31.758 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 09:31:31.758 [3131] <4> VxBSAGetEnv: INF - returning - PHCHBS-SP260086.eu.novartis.net 09:31:31.758 [3131] <2> xbsa_GetEnv: INF - leaving (0) 09:31:31.758 [3131] <2> xbsa_GetEnv: INF - entering 09:31:31.758 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 09:31:31.758 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:31.758 [3131] <8> xbsa_GetEnv: WRN - NBBSA_SCHEDULE not found in environment block 09:31:31.758 [3131] <2> xbsa_GetEnv: INF - leaving (80) 09:31:31.758 [3131] <2> int_LogSystemInfo: INF - entering 09:31:31.758 [3131] <2> int_LogSystemInfo: INF - Veritas NetBackup for Oracle - Release 7.7.3 (2016051915) System name: Linux Node name: PHCHBS-SP260086.eu.novartis.net Release: 2.6.32-573.12.1.el6.x86_64 Version: #1 SMP Mon Nov 23 12:55:32 EST 2015 Machine: x86_64 User name: oracle Client Host: PHCHBS-SP260086.eu.novartis.net 09:31:31.758 [3131] <2> int_LogSystemInfo: INF - leaving 09:31:31.758 [3131] <2> int_GetMMInfo: INF - entering 09:31:31.758 [3131] <2> int_GetMMInfo: INF - Initialized Signal 09:31:31.758 [3131] <2> int_GetMMInfo: INF - support for Proxy Copy enabled 09:31:31.758 [3131] <2> int_GetMMInfo: INF - leaving 09:31:31.758 [3131] <2> int_InitializeSbtFunctionPointers: INF - entering 09:31:31.758 [3131] <2> int_InitializeSbtFunctionPointers: INF - leaving 09:31:31.758 [3131] <2> sbtinit: INF - leaving 09:31:31.758 [3131] <2> sbtinit2: INF - entering 09:31:31.758 [3131] <2> sbtinit2: INF - leaving 09:31:31.906 [3131] <2> sbtcommand: INF - entering 09:31:31.906 [3131] <2> sbtcommand: INF - command string= 09:31:31.906 [3131] <2> int_ProcessCommandString: INF - entering 09:31:31.906 [3131] <4> int_ProcessCommandString: INF - cmd_key= cmd_val= 09:31:31.906 [3131] <2> int_ProcessCommand: INF - entering 09:31:31.906 [3131] <2> xbsa_SetEnv: INF - entering 09:31:31.906 [3131] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_POLICY 09:31:31.906 [3131] <2> xbsa_SetEnv: INF - leaving (0) 09:31:31.906 [3131] <2> int_ProcessCommand: INF - Policy will be used for this API session. 09:31:31.906 [3131] <2> int_ProcessCommand: INF - leaving 09:31:31.906 [3131] <2> int_ProcessCommandString: INF - leaving 09:31:31.906 [3131] <2> sbtcommand: INF - leaving 09:31:32.414 [3131] <2> sbtbackup: INF - entering 09:31:32.414 [3131] <2> int_StartJob: INF - entering 09:31:32.414 [3131] <2> int_IsSAPBackup: INF - entering 09:31:32.414 [3131] <2> int_IsSAPBackup: INF - leaving 09:31:32.414 [3131] <2> xbsa_ValidateFeatureId: INF - entering 09:31:32.414 [3131] <4> VxBSAValidateFeatureId: INF - entering VxBSAValidateFeatureId 09:31:32.414 [3131] <4> bsa_checkFeatureId: INF - entering bsa_checkFeatureId 09:31:32.414 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 09:31:32.414 [3131] <4> VxBSAGetEnv: INF - returning - chbsux0431.eu.novartis.net 09:31:32.414 [3131] <2> vnet_proxy_connect: bpconfGetKeyValPairAsInt() failed: 227, assume USE_SOCKET_HANDOFF_PROXY = NO 09:31:32.415 [3131] <2> vnet_proxy_connect: proxy not required for function_id = 3, host = chbsux0431.eu.novartis.net, service = bprd 09:31:32.418 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:936] using SVC=veritas_pbx PORT=1556 09:31:32.418 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:940] adjusted service name 1556 09:31:32.421 [3131] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1764] found via getaddrinfo NAME=chbsux0431.eu.novartis.net SVC=veritas_pbx 09:31:32.421 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.421 [3131] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1764] found via getaddrinfo NAME=147.167.134.119 SVC=NULL 09:31:32.422 [3131] <2> init_resilient_cache: [vnet_nbrntd.c:880] Initialize resilient cache. 0 0x0 09:31:32.422 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.424 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:936] using SVC=vnetd PORT=13724 09:31:32.424 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:940] adjusted service name 13724 09:31:32.426 [3131] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1764] found via getaddrinfo NAME=chbsux0431.eu.novartis.net SVC=vnetd 09:31:32.426 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.426 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.428 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:936] using SVC=bprd PORT=13720 09:31:32.428 [3131] <2> retry_getaddrinfo: [vnet_addrinfo.c:940] adjusted service name 13720 09:31:32.430 [3131] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1764] found via getaddrinfo NAME=chbsux0431.eu.novartis.net SVC=bprd 09:31:32.430 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.430 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.431 [3131] <2> async_connect: [vnet_connect.c:1645] connect in progress 1 0x1 09:31:32.433 [3131] <2> vnet_pbxConnect: pbxConnectEx Succeeded 09:31:32.433 [3131] <2> do_pbx_service: [vnet_connect.c:2310] via PBX bprd CONNECT FROM 147.167.177.9.23618 TO 147.167.134.119.1556 fd = 11 09:31:32.434 [3131] <2> async_connect: [vnet_connect.c:1837] connect async CONNECT FROM 147.167.177.9.23618 TO 147.167.134.119.1556 fd = 11 09:31:32.434 [3131] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO chbsux0431.eu.novartis.net 147.167.134.119 bprd VIA pbx 09:31:32.434 [3131] <2> logconnections: BPRD CONNECT FROM 147.167.177.9.23618 TO 147.167.134.119.1556 fd = 11 09:31:32.434 [3131] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1025] VxSS not supported 0 0x0 09:31:32.444 [3131] <4> bsa_checkFeatureId: INF - Successfully validated FeatureId 09:31:32.444 [3131] <2> xbsa_ValidateFeatureId: INF - leaving (0) 09:31:32.444 [3131] <2> int_SetKeyWord: INF - entering 09:31:32.444 [3131] <2> xbsa_SetEnv: INF - entering 09:31:32.444 [3131] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_KEYWORD 09:31:32.444 [3131] <2> xbsa_SetEnv: INF - leaving (0) 09:31:32.444 [3131] <2> int_SetKeyWord: INF - leaving 09:31:32.444 [3131] <2> xbsa_BeginTransaction: INF - entering 09:31:32.444 [3131] <4> VxBSABeginTxn: INF - entering VxBSABeginTxn. 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DB_TYPE 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - Oracle 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_ORA_SID 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - PROPASXP_1021904159 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_NTUPLE_RESTORE 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_COPY_NUMBER 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_RESTORE_PRIORITY 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_JOB_SUBTYPE 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DISK_MEDIA_SERVER 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.444 [3131] <2> xbsa_BeginTransaction: INF - leaving (0) 09:31:32.444 [3131] <2> xbsa_ProgressLogSetup: INF - entering 09:31:32.444 [3131] <2> xbsa_ProgressLogSetup: INF - No progress log: Job will proceed without progress logging 09:31:32.444 [3131] <2> xbsa_ProgressLogSetup: INF - leaving 09:31:32.444 [3131] <2> xbsa_CreateObject: INF - entering 09:31:32.444 [3131] <4> VxBSACreateObject: INF - entering CreateObject 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_OBJINFO_LEN 09:31:32.444 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectOwner.bsa_ObjectOwner: 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectOwner.app_ObjectOwner: 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectName.objectSpaceName: Oracle Database 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectName.pathName: /rrriiu3k_1_1 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - createTime: 0 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - copyType: 3 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - copyid: 0.0 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - restoreOrder: 0.0 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - estimatedSize: 0.100 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - resourceType: Oracle Backup 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectType: 4 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectStatus: 0 09:31:32.470 [3131] <4> bsa_printObjectDescriptor: INF - objectDescription: 09:31:32.470 [3131] <4> VxBSACreateObject: objectOwner: -default_user- 09:31:32.471 [3131] <4> VxBSACreateObject: objectGroup: oinstall 1 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_IMAGE_PERMISSION 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_POLICY 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - Oracle-Pharm-PROPASXP-PRD 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SCHEDULE 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> dbc_GetScheduleNameFromConfig: Default schedule not configured. 09:31:32.471 [3131] <4> dbc_GetServerClientConfig: entering dbc_GetServerClientConfig. 09:31:32.471 [3131] <4> dbc_GetServerClientConfig: ServerName: , ClientName: 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - BSA_SERVICE_HOST 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - chbsux0431.eu.novartis.net 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CLIENT_HOST 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - PHCHBS-SP260086.eu.novartis.net 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DUMP_HOST 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_CATALOG_HOST 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_KEYWORD 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - PROPASXP_1021904159 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_PROGRESS_LOG_FILE 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_PARENT_JOBID 09:31:32.471 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:32.471 [3131] <4> bsa_CreateNewImage: INF - entering bsa_CreateNewImage. 09:31:32.471 [3131] <4> CreateNewImage: INF - entering CreateNewImage. 09:31:32.471 [3131] <4> initOpen: entering initOpen. 09:31:32.471 [3131] <4> openProgressFile: entering openProgressFile. 09:31:32.471 [3131] <4> openProgressFile: commFull = 09:31:32.472 [3131] <4> openProgressFile: cleaning directory: 09:31:32.472 [3131] <4> delete_old_files: entering delete_old_files. 09:31:32.472 [3131] <4> openProgressFile: cleaning directory: 09:31:32.472 [3131] <4> delete_old_files: entering delete_old_files. 09:31:32.472 [3131] <4> openProgressFile: cleaning directory: 09:31:32.472 [3131] <4> delete_old_files: entering delete_old_files. 09:31:32.472 [3131] <4> openProgressFile: cleaning directory: 09:31:32.472 [3131] <4> delete_old_files: entering delete_old_files. 09:31:32.472 [3131] <4> openProgressFile: cleaning directory: 09:31:32.472 [3131] <4> delete_old_files: entering delete_old_files. 09:31:32.472 [3131] <4> openProgressFile: progressFD = <11> 09:31:32.472 [3131] <4> openProgressFile: progressFD_en = <-1> 09:31:32.472 [3131] <4> CreateNewImage: INF - backing up File: 09:31:32.472 [3131] <4> sendRequest: entering sendRequest. 09:31:32.472 [3131] <4> sendRequest: Progress log not for monitoring= 09:31:32.472 [3131] <4> getOwnerName: entering getOwnerName. 09:31:32.473 [3131] <4> sendRequest: sending BACKUP request to bprd 09:31:32.473 [3131] <4> sendRequest: request = oracle oinstall PHCHBS-SP260086.eu.novartis.net PHCHBS-SP260086.eu.novartis.net PHCHBS-SP260086.eu.novartis.net /usr/openv/netbackup/logs/user_ops/dbe xt/logs/3131.0.1476689492 Oracle-Pharm-PROPASXP-PRD NONE 19 PROPASXP_1021904159 4 NONE NONE 1 0 4 0 C C C C C 0 0 0 0 5 0 0 09:31:32.474 [3131] <2> bprd_connect_need_auth: Ignoring local_name PHCHBS-SP260086.eu.novartis.net. 09:31:32.474 [3131] <2> bprd_connect_need_auth: Ignoring owner_name oracle. 09:31:32.474 [3131] <2> vnet_proxy_connect: bpconfGetKeyValPairAsInt() failed: 227, assume USE_SOCKET_HANDOFF_PROXY = NO 09:31:32.474 [3131] <2> vnet_proxy_connect: proxy not required for function_id = 3, host = chbsux0431.eu.novartis.net, service = bprd 09:31:32.474 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.474 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.474 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.474 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.474 [3131] <2> vnet_sortaddrs: [vnet_addrinfo.c:3992] sorted addrs: 1 0x1 09:31:32.474 [3131] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:4822] using interface ANY 09:31:32.474 [3131] <2> async_connect: [vnet_connect.c:1645] connect in progress 1 0x1 09:31:32.478 [3131] <2> vnet_pbxConnect: pbxConnectEx Succeeded 09:31:32.478 [3131] <2> do_pbx_service: [vnet_connect.c:2310] via PBX bprd CONNECT FROM 147.167.177.9.41109 TO 147.167.134.119.1556 fd = 12 09:31:32.478 [3131] <2> async_connect: [vnet_connect.c:1837] connect async CONNECT FROM 147.167.177.9.41109 TO 147.167.134.119.1556 fd = 12 09:31:32.478 [3131] <2> connect_to_service: connect succeeded STATUS (0) SUCCESS FROM 0.0.0.0 TO chbsux0431.eu.novartis.net 147.167.134.119 bprd VIA pbx 09:31:32.478 [3131] <2> logconnections: BPRD CONNECT FROM 147.167.177.9.41109 TO 147.167.134.119.1556 fd = 12 09:31:32.478 [3131] <2> vnet_check_vxss_client_magic_with_info_ex: [vnet_vxss_helper.c:1025] VxSS not supported 0 0x0 09:31:32.478 [3131] <4> serverResponse: entering serverResponse. 09:31:32.478 [3131] <4> serverResponse: initial client_read_timeout = <3600> 09:31:32.478 [3131] <4> readCommMessages: Entering readCommMessages 09:31:33.478 [3131] <4> serverResponse: read comm file:<09:31:32 Initiating backup> 09:31:33.479 [3131] <4> serverResponse: read comm file:<09:31:33 INF - Starting bpbrm> 09:31:33.479 [3131] <4> readCommMessages: Entering readCommMessages 09:31:35.479 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Data socket = chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03172476689494080638000000080-UygxGO;788e6687f6a37222e36e 317579bb93f7;12;1800> 09:31:35.479 [3131] <4> serverResponse: INF - connecting to server on DATA socket chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03172476689494080638000000080-UygxGO;788e6687f6a37222e36e317 579bb93f7;12;1800 09:31:35.479 [3131] <4> connectSockStr: INF - port id = IPC:/usr/openv/var/tmp/vnet-03172476689494080638000000080-UygxGO;788e6687f6a37222e36e317579bb93f7;12;1800 09:31:35.479 [3131] <4> connectSockStr: INF - hostname = chbsux0431.eu.novartis.net 09:31:35.479 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1113] hash_str1 788e6687f6a37222e36e317579bb93f7 09:31:35.480 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1114] hash_str2 bbce304367250a4dfd64c168677f4483 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1649] hash_str1 788e6687f6a37222e36e317579bb93f7 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1651] hash_str2 bbce304367250a4dfd64c168677f4483 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1677] hash_str 788e6687f6a37222e36e317579bb93f7 09:31:35.480 [3131] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:819] the socket is 13 0xd 09:31:35.480 [3131] <4> serverResponse: INF - DATA sockfd: 13 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Name socket = chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03209476689494189514000000080-6ODq0O;07f0cc447749dfcff3c0 815f69e134dc;12;1800> 09:31:35.480 [3131] <4> serverResponse: INF - connecting to server on NAME socket chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03209476689494189514000000080-6ODq0O;07f0cc447749dfcff3c0815 f69e134dc;12;1800 09:31:35.480 [3131] <4> connectSockStr: INF - port id = IPC:/usr/openv/var/tmp/vnet-03209476689494189514000000080-6ODq0O;07f0cc447749dfcff3c0815f69e134dc;12;1800 09:31:35.480 [3131] <4> connectSockStr: INF - hostname = chbsux0431.eu.novartis.net 09:31:35.480 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1113] hash_str1 07f0cc447749dfcff3c0815f69e134dc 09:31:35.480 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1114] hash_str2 409940ce15eb37466d185247173c7c38 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1649] hash_str1 07f0cc447749dfcff3c0815f69e134dc 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1651] hash_str2 409940ce15eb37466d185247173c7c38 09:31:35.480 [3131] <2> verify_hashes: [vnet_vnetd.c:1677] hash_str 07f0cc447749dfcff3c0815f69e134dc 09:31:35.480 [3131] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:819] the socket is 14 0xe 09:31:35.480 [3131] <4> serverResponse: INF - NAME sockfd: 14 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Job id = 11654526> 09:31:35.480 [3131] <4> serverResponse: -jobid 11654526 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Backup id = PHCHBS-SP260086.eu.novartis.net_1476689493> 09:31:35.480 [3131] <4> serverResponse: -backupid PHCHBS-SP260086.eu.novartis.net_1476689493 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Backup time = 1476689493> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Policy name = Oracle-Pharm-PROPASXP-PRD> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Snapshot = 0> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Frozen image = 0> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Backup copy = 0> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Master server = chbsux0431.eu.novartis.net> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Media server = chbsux0431.eu.novartis.net> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Multiplexing = 0> 09:31:35.480 [3131] <4> serverResponse: read comm file:<09:31:34 INF - New data socket = chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03171476689493981505000000080-6seqoO;18c0d3de9a00a3c5 43741288e10fd98c;12;1800> 09:31:35.480 [3131] <4> serverResponse: INF - connecting to server on NEW DATA socket chbsux0431.eu.novartis.net.IPC:/usr/openv/var/tmp/vnet-03171476689493981505000000080-6seqoO;18c0d3de9a00a3c5437 41288e10fd98c;12;1800 09:31:35.480 [3131] <4> connectSockStr: INF - port id = IPC:/usr/openv/var/tmp/vnet-03171476689493981505000000080-6seqoO;18c0d3de9a00a3c543741288e10fd98c;12;1800 09:31:35.480 [3131] <4> connectSockStr: INF - hostname = chbsux0431.eu.novartis.net 09:31:35.482 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1113] hash_str1 18c0d3de9a00a3c543741288e10fd98c 09:31:35.482 [3131] <2> vnet_receive_network_socket: [vnet_vnetd.c:1114] hash_str2 9f2987519bd5e4f99c20fb7577e03e37 09:31:35.482 [3131] <2> verify_hashes: [vnet_vnetd.c:1649] hash_str1 18c0d3de9a00a3c543741288e10fd98c 09:31:35.482 [3131] <2> verify_hashes: [vnet_vnetd.c:1651] hash_str2 9f2987519bd5e4f99c20fb7577e03e37 09:31:35.482 [3131] <2> verify_hashes: [vnet_vnetd.c:1677] hash_str 18c0d3de9a00a3c543741288e10fd98c 09:31:35.482 [3131] <2> vnet_check_resilient_socket: [vnet_nbrntd.c:819] the socket is 15 0xf 09:31:35.482 [3131] <4> serverResponse: INF - NEW DATA sockfd: 15 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Encrypt = 0> 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Use shared memory = 0> 09:31:35.482 [3131] <4> serverResponse: INF - UseShM set to 0 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Compression = 0> 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Encrypt = 0> 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Keep logs = 4> 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Client read timeout = 9600> 09:31:35.482 [3131] <4> serverResponse: read comm file:<09:31:34 INF - Media mount timeout = 0> 09:31:35.482 [3131] <4> serverResponse: INF - end reading comm file 09:31:35.482 [3131] <4> CreateNewImage: INF - dSock=13, nSock=14, ndSock=15 09:31:35.482 [3131] <4> handshake: entering handShake. 09:31:35.482 [3131] <4> handshake: INF - BACKUP START sent to bpbrm 09:31:35.483 [3131] <4> readSock: entering readSock. 09:31:35.483 [3131] <4> read_from_socket: entering read_from_socket. 09:31:35.523 [3131] <4> handshake: INF - CONTINUE BACKUP message received 09:31:35.523 [3131] <4> handshake: INF - Beginning backup timeout value = 3600 09:31:35.523 [3131] <4> readCommMessages: Entering readCommMessages 09:31:37.523 [3131] <4> handshake: INF - got filename from server: 09:31:37.523 [3131] <4> handshake: INF - valid newdataSock: 15 09:31:37.523 [3131] <4> handshake: INF - nameSock = 14, dataSock = 15, commSock = 13 09:31:37.523 [3131] <4> writeTarHeader: entering writeTarHeader. 09:31:37.524 [3131] <4> getOwnerName: entering getOwnerName. 09:31:37.525 [3131] <4> finishHeader: entering finishHeader. 09:31:37.525 [3131] <4> getOwnerName: entering getOwnerName. 09:31:37.525 [3131] <4> writeTarHeader: INF - LF_TYPE = 40 09:31:37.525 [3131] <4> finishHeader: entering finishHeader. 09:31:37.525 [3131] <4> writeTarHeader: INF - writing tar header 09:31:37.525 [3131] <4> CreateNewImage: INF - returning STAT_SUCCESS 09:31:37.525 [3131] <4> VxBSACreateObject: INF - Object 1 added to NEW image 1476689493 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectOwner.bsa_ObjectOwner: 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectOwner.app_ObjectOwner: 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectName.objectSpaceName: Oracle Database 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectName.pathName: /rrriiu3k_1_1 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - createTime: 1476689493 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - copyType: 3 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - copyid: 1.1476689493 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - restoreOrder: 1476689492.1 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - estimatedSize: 0.100 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - resourceType: Oracle Backup 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectType: 4 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectStatus: 2 09:31:37.525 [3131] <4> bsa_printObjectDescriptor: INF - objectDescription: 09:31:37.525 [3131] <2> xbsa_CreateObject: INF - leaving (0) 09:31:37.525 [3131] <2> int_StartJob: INF - copyID: 1 - 1476689493 09:31:37.525 [3131] <2> xbsa_GetEnv: INF - entering 09:31:37.525 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SERVER_READ_TIMEOUT 09:31:37.525 [3131] <4> VxBSAGetEnv: INF - returning - 09:31:37.525 [3131] <8> xbsa_GetEnv: WRN - NBBSA_SERVER_READ_TIMEOUT not found in environment block 09:31:37.525 [3131] <2> xbsa_GetEnv: INF - leaving (80) 09:31:37.525 [3131] <2> xbsa_SetEnv: INF - entering 09:31:37.525 [3131] <4> VxBSASetEnv: INF - entering SetEnv - NBBSA_SERVER_READ_TIMEOUT 09:31:37.525 [3131] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_SERVER_READ_TIMEOUT 09:31:37.525 [3131] <4> VxBSAGetEnv: INF - returning - 10800 09:31:37.526 [3131] <4> dbc_SetServerClientReadTimeout: INF - sending server read timeout 09:31:37.526 [3131] <2> xbsa_SetEnv: INF - leaving (0) 09:31:37.526 [3131] <2> int_StartJob: INF - leaving 09:31:37.526 [3131] <2> sbtbackup: INF - leaving 09:35:32.404 [3131] <2> int_WriteData: INF - writing buffer # 1 of size 262144 09:51:09.289 [3131] <16> writeToServer: ERR - send() to server on socket failed: Connection timed out (110) 09:51:09.291 [3131] <16> dbc_put: ERR - failed sending data to server 09:51:09.291 [3131] <4> closeApi: entering closeApi. 09:51:09.292 [3131] <4> closeApi: INF - EXIT STATUS 6: the backup failed to back up the requested files 09:51:09.292 [3131] <4> closeApi: INF - closing commSock 13 09:51:09.292 [3131] <4> closeApi: INF - close of commSock returned <0> errno is <2> 09:51:09.292 [3131] <4> closeApi: INF - closing dataSock 15 09:51:09.292 [3131] <4> closeApi: INF - close of dataSock returned <0> errno is <2> 09:51:09.292 [3131] <4> closeApi: INF - setting linger on nameSock 14 09:51:09.292 [3131] <4> closeApi: INF - closing nameSock 14