Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Redirection failure loop #132

Closed
bbockelm opened this issue Jul 30, 2014 · 10 comments
Closed

Redirection failure loop #132

bbockelm opened this issue Jul 30, 2014 · 10 comments

Comments

@bbockelm
Copy link
Contributor

We ran into an interesting Xrootd failure. See below. My interpretation of the problem:

  1. File is not at cmsxrootd.fnal.gov, so user is redirected to cms-xrd-global.cern.ch with the appropriate 'tried' opaque data.
  2. cms-xrd-global redirects to cmsdbs.ihep.ac.cn which redirects to seadmin.ihep.ac.cn.
  3. seadmin.ihep.ac.cn has connectivity issues. Client returns to cmsxrootd.fnal.gov with no opaque data.
  4. Steps (1) - (3) repeat until max redirection limit is hit.

We have no idea why cmsdbs.ihep.ac.cn gets the redirection 100% of the time in testing; best guess is it is related to redirector load (another connected redirect, xrootd.ba.infn.it also has the file).

None of the servers require CMS auth (well, except the one that is behind a firewall causing the issue in the first place!) so a dev should be able to retry.

140730 10:54:04 1896 Xrd: main: (C) 2004-2011 by the XRootD collaboration. Version: v3.3.5
140730 10:54:04 1896 Xrd: Create: (C) 2004-2010 by the Xrootd group. XrdClient $Revision$ - Xrootd version: v3.3.5
140730 10:54:04 1896 Xrd: TakeUrl: parsing url: 
140730 10:54:04 1896 Xrd: XrdClientUrlSet: parsing: root://cmsxrootd.fnal.gov//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: protocol: root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: file: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: list of [host:port] : cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: XrdClientUrlSet: Remote file to open is '/store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root'
140730 10:54:04 1896 Xrd: TakeUrl: parsing url: cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    HostWPort:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    File:   /
140730 10:54:04 1896 Xrd: TakeUrl:    Host:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    Port:   0
140730 10:54:04 1896 Xrd: ConvertDNSAlias: resolving cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: CheckPort: TCP port not specified: trying /etc/services ...
140730 10:54:04 1896 Xrd: CheckPort: found tcp port 1094.
140730 10:54:04 1896 Xrd: ConvertDNSAlias: found host xrootd.unl.edu with addr 129.93.239.131
140730 10:54:04 1896 Xrd: ConvertDNSAlias: found host cmsxrootd1.fnal.gov with addr 131.225.204.174
140730 10:54:04 1896 Xrd: ShowUrls: The converted URLs count is 2
140730 10:54:04 1896 Xrd: ShowUrls: URL n.1: root://xrootd.unl.edu:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root.
140730 10:54:04 1896 Xrd: ShowUrls: URL n.2: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root.
140730 10:54:04 1896 Xrd: TakeUrl: parsing url: root://cmsxrootd.fnal.gov//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: TakeUrl:    Proto:   root
140730 10:54:04 1896 Xrd: TakeUrl:    HostWPort:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: TakeUrl:    Host:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    Port:   0
140730 10:54:04 1896 Xrd: XrdClientUrlSet: parsing: root://cmsxrootd.fnal.gov//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: protocol: root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: file: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: XrdClientUrlSet: list of [host:port] : cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: XrdClientUrlSet: Remote file to open is '/store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root'
140730 10:54:04 1896 Xrd: TakeUrl: parsing url: cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    HostWPort:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    File:   /
140730 10:54:04 1896 Xrd: TakeUrl:    Host:   cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    Port:   0
140730 10:54:04 1896 Xrd: ConvertDNSAlias: resolving cmsxrootd.fnal.gov
140730 10:54:04 1896 Xrd: CheckPort: TCP port not specified: trying /etc/services ...
140730 10:54:04 1896 Xrd: CheckPort: found tcp port 1094.
140730 10:54:04 1896 Xrd: ConvertDNSAlias: found host xrootd.unl.edu with addr 129.93.239.131
140730 10:54:04 1896 Xrd: ConvertDNSAlias: found host cmsxrootd1.fnal.gov with addr 131.225.204.174
140730 10:54:04 1896 Xrd: ShowUrls: The converted URLs count is 2
140730 10:54:04 1896 Xrd: ShowUrls: URL n.1: root://xrootd.unl.edu:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root.
140730 10:54:04 1896 Xrd: ShowUrls: URL n.2: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root.
140730 10:54:04 1896 Xrd: CheckHostDomain: Resolved [cmsxrootd1.fnal.gov]'s domain name into [fnal.gov]
140730 10:54:04 1896 Xrd: DomainMatcher: search for 'fnal.gov' in ''
140730 10:54:04 1896 Xrd: DomainMatcher: no domain matching 'fnal.gov' found in ''
140730 10:54:04 1896 Xrd: DomainMatcher: search for 'fnal.gov' in '*'
140730 10:54:04 1896 Xrd: DomainMatcher: domain: * matches 'fnal.gov' (matching chars: 1)
140730 10:54:04 1896 Xrd: CheckHostDomain: Access granted to the domain of [cmsxrootd1.fnal.gov].
140730 10:54:04 1896 Xrd: Open: Trying to connect to cmsxrootd1.fnal.gov:1094. Connect try 1
140730 10:54:04 1896 Xrd: XrdClientConn: Trying to connect to 131.225.204.174:1094
140730 10:54:04 1896 Xrd: Connect: Creating a logical connection...
140730 10:54:04 1896 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:54:04 1896 Xrd: Connect: Connecting to [cmsxrootd1.fnal.gov:1094]
140730 10:54:04 1896 Xrd: ClientSock::TryConnect_low: Trying to connect to cmsxrootd1.fnal.gov(131.225.204.174):1094 Windowsize=0 Timeout=120
140730 10:54:04 1896 Xrd: Connect: Connected to [cmsxrootd1.fnal.gov:1094]
140730 10:54:04 1896 Xrd: Connect: New physical connection to server cmsxrootd1.fnal.gov:1094 succesfully created.
140730 10:54:04 1896 Xrd: Connect: LogConn: size:1 count: 1PhyConn: size:1
140730 10:54:04 1896 Xrd: Connect: Connect(cmsxrootd1.fnal.gov, 1094) returned 0
140730 10:54:04 1896 Xrd: Open: The logical connection id is 0.
140730 10:54:04 1896 Xrd: Open: Working url is root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: DoHandShake: HandShake step 1: Sending handshake with a piggy-backed protocol request
140730 10:54:04 1896 Xrd: DoHandShake: HandShake step 2: Reading 4 bytes.
140730 10:54:04 1896 Xrd: DoHandShake: HandShake step 3: Reading 12 bytes.
140730 10:54:04 1896 Xrd: DoHandShake: Server protocol: 663 type: 0
140730 10:54:04 1896 Xrd: DoHandShake: Server protocol (kXR_protocol): 663 flags: 2
140730 10:54:04 1896 Xrd: DoHandShake: Setting Load Balancer Server Url = root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: TakeUrl: parsing url: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: TakeUrl:    Proto:   root
140730 10:54:04 1896 Xrd: TakeUrl:    HostWPort:   cmsxrootd1.fnal.gov:1094
140730 10:54:04 1896 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: TakeUrl:    Host:   cmsxrootd1.fnal.gov
140730 10:54:04 1896 Xrd: TakeUrl:    Port:   1094
140730 10:54:04 1896 Xrd: GetAccessToSrv: Ok: the server on [cmsxrootd1.fnal.gov:1094] is an xrootd redirector.
140730 10:54:04 1896 Xrd: StartReader: Starting reader thread...
140730 10:54:04 1898 Xrd: SocketReaderThread: Reader Thread starting.
140730 10:54:04 1896 Xrd: DoLogin: Logging into the server [cmsxrootd1.fnal.gov:1094]. pid=1896 uid=bbockelm
140730 10:54:04 1896 Xrd: SendGenCommand: Sending command XrdClientConn::DoLogin
140730 10:54:04 1896 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:04 1896 Xrd: DoLogin: Got session ID: 11ffffffbb050060450000ffffffd10000003effffffbb0500
140730 10:54:04 1896 Xrd: DoLogin: No prev session info for 131.225.204.174:1094.
140730 10:54:04 1896 Xrd: Open: Access to server granted.
140730 10:54:04 1896 Xrd: Open: Opening the remote file /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:04 1896 Xrd: Open: File open in progress.
140730 10:54:04 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:04 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:04 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:04 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:05 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:05 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:06 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:06 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:07 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:07 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:07 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:07 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:08 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:08 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:09 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:09 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:10 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:10 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:10 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:10 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:11 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:11 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:11 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:11 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:12 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:12 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:13 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:13 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:14 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:14 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:14 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:14 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:15 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:15 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:15 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:15 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:16 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:16 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:17 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:17 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:18 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:18 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:18 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:18 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:19 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:19 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:20 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:20 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:21 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:21 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:21 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:21 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:22 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:22 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:22 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:22 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:23 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:23 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:24 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:24 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:25 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:25 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:25 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:25 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:26 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:26 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:27 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:27 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:28 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:28 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:28 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:28 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:29 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:29 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:29 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:29 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:30 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:30 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:31 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:31 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:32 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:32 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:32 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:32 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:33 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:33 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:34 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 10:54:34 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 10:54:35 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:35 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:54:35 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_redirect] (4004)
140730 10:54:35 1899 Xrd: Disconnect:  LogConnID: 0 destroyed
140730 10:54:35 1899 Xrd: HandleServerError: Redir count=1
140730 10:54:35 1899 Xrd: TakeUrl: parsing url: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Proto:   root
140730 10:54:35 1899 Xrd: TakeUrl:    HostWPort:   cmsxrootd1.fnal.gov:1094
140730 10:54:35 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Host:   cmsxrootd1.fnal.gov
140730 10:54:35 1899 Xrd: TakeUrl:    Port:   1094
140730 10:54:35 1899 Xrd: HandleServerError: Received redirection to [cms-xrd-global.cern.ch:1094]. Token=[]]. Opaque=[tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov].
140730 10:54:35 1899 Xrd: CheckHostDomain: Resolved [cms-xrd-global.cern.ch]'s domain name into [cern.ch]
140730 10:54:35 1899 Xrd: DomainMatcher: search for 'cern.ch' in ''
140730 10:54:35 1899 Xrd: DomainMatcher: no domain matching 'cern.ch' found in ''
140730 10:54:35 1899 Xrd: DomainMatcher: search for 'cern.ch' in '*'
140730 10:54:35 1899 Xrd: DomainMatcher: domain: * matches 'cern.ch' (matching chars: 1)
140730 10:54:35 1899 Xrd: CheckHostDomain: Access granted to the domain of [cms-xrd-global.cern.ch].
140730 10:54:35 1899 Xrd: XrdClientConn: Trying to connect to 128.142.139.172:1094
140730 10:54:35 1899 Xrd: Connect: Creating a logical connection...
140730 10:54:35 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:54:35 1899 Xrd: Connect: Connecting to [cms-xrd-global.cern.ch:1094]
140730 10:54:35 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to cms-xrd-global.cern.ch(128.142.139.172):1094 Windowsize=0 Timeout=120
140730 10:54:35 1899 Xrd: Connect: Connected to [cms-xrd-global.cern.ch:1094]
140730 10:54:35 1899 Xrd: Connect: New physical connection to server cms-xrd-global.cern.ch:1094 succesfully created.
140730 10:54:35 1899 Xrd: Connect: LogConn: size:2 count: 1PhyConn: size:2
140730 10:54:35 1899 Xrd: Connect: Destroying connection condvar for bbockelm@cms-xrd-global.cern.ch:1094
140730 10:54:35 1899 Xrd: Connect: Connect(cms-xrd-global.cern.ch, 1094) returned 1
140730 10:54:35 1899 Xrd: DoHandShake: HandShake step 1: Sending handshake with a piggy-backed protocol request
140730 10:54:35 1899 Xrd: DoHandShake: HandShake step 2: Reading 4 bytes.
140730 10:54:35 1899 Xrd: DoHandShake: HandShake step 3: Reading 12 bytes.
140730 10:54:35 1899 Xrd: DoHandShake: Server protocol: 663 type: 0
140730 10:54:35 1899 Xrd: DoHandShake: Server protocol (kXR_protocol): 663 flags: 258
140730 10:54:35 1899 Xrd: DoHandShake: Setting Meta Manager Server Url = root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Proto:   root
140730 10:54:35 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:54:35 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:54:35 1899 Xrd: TakeUrl:    Port:   1094
140730 10:54:35 1899 Xrd: GetAccessToSrv: Ok: the server on [cms-xrd-global.cern.ch:1094] is an xrootd meta manager.
140730 10:54:35 1899 Xrd: StartReader: Starting reader thread...
140730 10:54:35 1928 Xrd: SocketReaderThread: Reader Thread starting.
140730 10:54:35 1899 Xrd: DoLogin: Logging into the server [cms-xrd-global.cern.ch:1094]. pid=1896 uid=bbockelm
140730 10:54:35 1899 Xrd: SendGenCommand: Sending command XrdClientConn::DoLogin
140730 10:54:35 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cms-xrd-global.cern.ch:1094]...
140730 10:54:35 1899 Xrd: DoLogin: Got session ID: ffffffaaffffffe1ffffffdb0bffffffb4190000ffffffe205000022fffffff4ffffffdb0b
140730 10:54:35 1899 Xrd: DoLogin: No prev session info for 128.142.139.172:1094.
140730 10:54:35 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cms-xrd-global.cern.ch:1094
140730 10:54:35 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:35 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cms-xrd-global.cern.ch:1094]...
140730 10:54:35 1899 Xrd: ReadPartialAnswer: Server [cms-xrd-global.cern.ch:1094] answered [kXR_redirect] (4004)
140730 10:54:35 1899 Xrd: Disconnect:  LogConnID: 1 destroyed
140730 10:54:35 1899 Xrd: HandleServerError: Redir count=2
140730 10:54:35 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Proto:   root
140730 10:54:35 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:54:35 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:35 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:54:35 1899 Xrd: TakeUrl:    Port:   1094
140730 10:54:35 1899 Xrd: HandleServerError: Received redirection to [cmsdbs.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:54:35 1899 Xrd: CheckHostDomain: Resolved [cmsdbs.ihep.ac.cn]'s domain name into [ihep.ac.cn]
140730 10:54:35 1899 Xrd: DomainMatcher: search for 'ihep.ac.cn' in ''
140730 10:54:35 1899 Xrd: DomainMatcher: no domain matching 'ihep.ac.cn' found in ''
140730 10:54:35 1899 Xrd: DomainMatcher: search for 'ihep.ac.cn' in '*'
140730 10:54:35 1899 Xrd: DomainMatcher: domain: * matches 'ihep.ac.cn' (matching chars: 1)
140730 10:54:35 1899 Xrd: CheckHostDomain: Access granted to the domain of [cmsdbs.ihep.ac.cn].
140730 10:54:35 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.52:1094
140730 10:54:35 1899 Xrd: Connect: Creating a logical connection...
140730 10:54:35 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:54:35 1899 Xrd: Connect: Connecting to [cmsdbs.ihep.ac.cn:1094]
140730 10:54:35 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to cmsdbs.ihep.ac.cn(202.122.33.52):1094 Windowsize=0 Timeout=120
140730 10:54:36 1899 Xrd: Connect: Connected to [cmsdbs.ihep.ac.cn:1094]
140730 10:54:36 1899 Xrd: Connect: New physical connection to server cmsdbs.ihep.ac.cn:1094 succesfully created.
140730 10:54:36 1899 Xrd: Connect: LogConn: size:3 count: 1PhyConn: size:3
140730 10:54:36 1899 Xrd: Connect: Destroying connection condvar for bbockelm@cmsdbs.ihep.ac.cn:1094
140730 10:54:36 1899 Xrd: Connect: Connect(cmsdbs.ihep.ac.cn, 1094) returned 2
140730 10:54:36 1899 Xrd: DoHandShake: HandShake step 1: Sending handshake with a piggy-backed protocol request
140730 10:54:36 1899 Xrd: DoHandShake: HandShake step 2: Reading 4 bytes.
140730 10:54:36 1899 Xrd: DoHandShake: HandShake step 3: Reading 12 bytes.
140730 10:54:36 1899 Xrd: DoHandShake: Server protocol: 657 type: 0
140730 10:54:36 1899 Xrd: DoHandShake: Server protocol (kXR_protocol): 657 flags: 0
140730 10:54:36 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsdbs.ihep.ac.cn:1094] is an xrootd redirector.
140730 10:54:36 1899 Xrd: StartReader: Starting reader thread...
140730 10:54:36 1929 Xrd: SocketReaderThread: Reader Thread starting.
140730 10:54:36 1899 Xrd: DoLogin: Logging into the server [cmsdbs.ihep.ac.cn:1094]. pid=1896 uid=bbockelm
140730 10:54:36 1899 Xrd: SendGenCommand: Sending command XrdClientConn::DoLogin
140730 10:54:36 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsdbs.ihep.ac.cn:1094]...
140730 10:54:36 1899 Xrd: DoLogin: Got session ID: ffffffbc0b00004115000014000000ffffffbd0b0000
140730 10:54:36 1899 Xrd: DoLogin: No prev session info for 202.122.33.52:1094.
140730 10:54:36 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsdbs.ihep.ac.cn:1094
140730 10:54:36 1899 Xrd: SendGenCommand: Sending command Open
140730 10:54:36 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsdbs.ihep.ac.cn:1094]...
140730 10:54:36 1899 Xrd: ReadPartialAnswer: Server [cmsdbs.ihep.ac.cn:1094] answered [kXR_redirect] (4004)
140730 10:54:36 1899 Xrd: Disconnect:  LogConnID: 2 destroyed
140730 10:54:36 1899 Xrd: HandleServerError: Redir count=3
140730 10:54:36 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:36 1899 Xrd: TakeUrl:    Proto:   root
140730 10:54:36 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 10:54:36 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:54:36 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 10:54:36 1899 Xrd: TakeUrl:    Port:   1094
140730 10:54:36 1899 Xrd: HandleServerError: Received redirection to [seadmin.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:54:36 1899 Xrd: CheckHostDomain: Resolved [seadmin.ihep.ac.cn]'s domain name into [ihep.ac.cn]
140730 10:54:36 1899 Xrd: DomainMatcher: search for 'ihep.ac.cn' in ''
140730 10:54:36 1899 Xrd: DomainMatcher: no domain matching 'ihep.ac.cn' found in ''
140730 10:54:36 1899 Xrd: DomainMatcher: search for 'ihep.ac.cn' in '*'
140730 10:54:36 1899 Xrd: DomainMatcher: domain: * matches 'ihep.ac.cn' (matching chars: 1)
140730 10:54:36 1899 Xrd: CheckHostDomain: Access granted to the domain of [seadmin.ihep.ac.cn].
140730 10:54:36 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.22:1094
140730 10:54:36 1899 Xrd: Connect: Creating a logical connection...
140730 10:54:36 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:54:36 1899 Xrd: Connect: Connecting to [seadmin.ihep.ac.cn:1094]
140730 10:54:36 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to seadmin.ihep.ac.cn(202.122.33.22):1094 Windowsize=0 Timeout=120
140730 10:56:36 1899 Xrd: ClientSock::TryConnect_low: Connection toseadmin.ihep.ac.cn:1094 failed. (-1)
140730 10:56:36 1899 Xrd: Connect: can't open connection to [seadmin.ihep.ac.cn:1094]
140730 10:56:36 1899 Xrd: PhyConnection: Disconnecting socket...
140730 10:56:36 1899 Xrd: Connect: Destroying connection condvar for bbockelm@seadmin.ihep.ac.cn:1094
140730 10:56:36 1899 Xrd: Connect: Connect(seadmin.ihep.ac.cn, 1094) returned -1
140730 10:56:36 1899 Xrd: XrdNetFile: Error creating logical connection to seadmin.ihep.ac.cn:1094
140730 10:56:36 1899 Xrd: GoToAnotherServer: Error connecting to [seadmin.ihep.ac.cn:1094
140730 10:56:41 1899 Xrd: HandleServerError: Redir count=4
140730 10:56:41 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:41 1899 Xrd: TakeUrl:    Proto:   root
140730 10:56:41 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 10:56:41 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:41 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 10:56:41 1899 Xrd: TakeUrl:    Port:   1094
140730 10:56:41 1899 Xrd: HandleServerError: Received redirection to [cmsxrootd1.fnal.gov:1094]. Token=[]]. Opaque=[].
140730 10:56:41 1899 Xrd: XrdClientConn: Trying to connect to 131.225.204.174:1094
140730 10:56:41 1899 Xrd: Connect: Creating a logical connection...
140730 10:56:41 1899 Xrd: Connect: LogConn: size:4 count: 1PhyConn: size:3
140730 10:56:41 1899 Xrd: Connect: Connect(cmsxrootd1.fnal.gov, 1094) returned 3
140730 10:56:41 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsxrootd1.fnal.gov:1094] is an xrootd redirector.
140730 10:56:41 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsxrootd1.fnal.gov:1094]).
140730 10:56:41 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsxrootd1.fnal.gov:1094
140730 10:56:41 1899 Xrd: SendGenCommand: Sending command Open
140730 10:56:41 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:56:41 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_redirect] (4004)
140730 10:56:41 1899 Xrd: Disconnect:  LogConnID: 3 destroyed
140730 10:56:41 1899 Xrd: HandleServerError: Redir count=5
140730 10:56:41 1899 Xrd: TakeUrl: parsing url: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:41 1899 Xrd: TakeUrl:    Proto:   root
140730 10:56:41 1899 Xrd: TakeUrl:    HostWPort:   cmsxrootd1.fnal.gov:1094
140730 10:56:41 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:41 1899 Xrd: TakeUrl:    Host:   cmsxrootd1.fnal.gov
140730 10:56:41 1899 Xrd: TakeUrl:    Port:   1094
140730 10:56:41 1899 Xrd: HandleServerError: Received redirection to [cms-xrd-global.cern.ch:1094]. Token=[]]. Opaque=[tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov].
140730 10:56:42 1899 Xrd: XrdClientConn: Trying to connect to 128.142.138.171:1094
140730 10:56:42 1899 Xrd: Connect: Creating a logical connection...
140730 10:56:42 1899 Xrd: Connect: LogConn: size:5 count: 1PhyConn: size:3
140730 10:56:42 1899 Xrd: Connect: Connect(cms-xrd-global.cern.ch, 1094) returned 4
140730 10:56:42 1899 Xrd: DoHandShake: Setting Meta Manager Server Url = root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Proto:   root
140730 10:56:42 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:56:42 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:56:42 1899 Xrd: TakeUrl:    Port:   1094
140730 10:56:42 1899 Xrd: GetAccessToSrv: Ok: the server on [cms-xrd-global.cern.ch:1094] is an xrootd meta manager.
140730 10:56:42 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cms-xrd-global.cern.ch:1094]).
140730 10:56:42 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cms-xrd-global.cern.ch:1094
140730 10:56:42 1899 Xrd: SendGenCommand: Sending command Open
140730 10:56:42 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cms-xrd-global.cern.ch:1094]...
140730 10:56:42 1899 Xrd: ReadPartialAnswer: Server [cms-xrd-global.cern.ch:1094] answered [kXR_redirect] (4004)
140730 10:56:42 1899 Xrd: Disconnect:  LogConnID: 4 destroyed
140730 10:56:42 1899 Xrd: HandleServerError: Redir count=6
140730 10:56:42 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Proto:   root
140730 10:56:42 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:56:42 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:56:42 1899 Xrd: TakeUrl:    Port:   1094
140730 10:56:42 1899 Xrd: HandleServerError: Received redirection to [cmsdbs.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:56:42 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.52:1094
140730 10:56:42 1899 Xrd: Connect: Creating a logical connection...
140730 10:56:42 1899 Xrd: Connect: LogConn: size:6 count: 1PhyConn: size:3
140730 10:56:42 1899 Xrd: Connect: Connect(cmsdbs.ihep.ac.cn, 1094) returned 5
140730 10:56:42 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsdbs.ihep.ac.cn:1094] is an xrootd redirector.
140730 10:56:42 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsdbs.ihep.ac.cn:1094]).
140730 10:56:42 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsdbs.ihep.ac.cn:1094
140730 10:56:42 1899 Xrd: SendGenCommand: Sending command Open
140730 10:56:42 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsdbs.ihep.ac.cn:1094]...
140730 10:56:42 1899 Xrd: ReadPartialAnswer: Server [cmsdbs.ihep.ac.cn:1094] answered [kXR_redirect] (4004)
140730 10:56:42 1899 Xrd: Disconnect:  LogConnID: 5 destroyed
140730 10:56:42 1899 Xrd: HandleServerError: Redir count=7
140730 10:56:42 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Proto:   root
140730 10:56:42 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 10:56:42 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:56:42 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 10:56:42 1899 Xrd: TakeUrl:    Port:   1094
140730 10:56:42 1899 Xrd: HandleServerError: Received redirection to [seadmin.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:56:42 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.22:1094
140730 10:56:42 1899 Xrd: Connect: Creating a logical connection...
140730 10:56:42 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:56:42 1899 Xrd: Connect: Connecting to [seadmin.ihep.ac.cn:1094]
140730 10:56:42 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to seadmin.ihep.ac.cn(202.122.33.22):1094 Windowsize=0 Timeout=120
140730 10:57:34 1897 Xrd: XrdClientPhyConnection: Destroying. [:-1]
140730 10:57:34 1897 Xrd: PhyConnection: Disconnecting socket...
140730 10:58:42 1899 Xrd: ClientSock::TryConnect_low: Connection toseadmin.ihep.ac.cn:1094 failed. (-1)
140730 10:58:42 1899 Xrd: Connect: can't open connection to [seadmin.ihep.ac.cn:1094]
140730 10:58:42 1899 Xrd: PhyConnection: Disconnecting socket...
140730 10:58:42 1899 Xrd: Connect: Destroying connection condvar for bbockelm@seadmin.ihep.ac.cn:1094
140730 10:58:42 1899 Xrd: Connect: Connect(seadmin.ihep.ac.cn, 1094) returned -1
140730 10:58:42 1899 Xrd: XrdNetFile: Error creating logical connection to seadmin.ihep.ac.cn:1094
140730 10:58:42 1899 Xrd: GoToAnotherServer: Error connecting to [seadmin.ihep.ac.cn:1094
140730 10:58:47 1899 Xrd: HandleServerError: Redir count=8
140730 10:58:47 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Proto:   root
140730 10:58:47 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 10:58:47 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 10:58:47 1899 Xrd: TakeUrl:    Port:   1094
140730 10:58:47 1899 Xrd: HandleServerError: Received redirection to [cmsxrootd1.fnal.gov:1094]. Token=[]]. Opaque=[].
140730 10:58:47 1899 Xrd: XrdClientConn: Trying to connect to 131.225.204.174:1094
140730 10:58:47 1899 Xrd: Connect: Creating a logical connection...
140730 10:58:47 1899 Xrd: Connect: LogConn: size:7 count: 1PhyConn: size:3
140730 10:58:47 1899 Xrd: Connect: Connect(cmsxrootd1.fnal.gov, 1094) returned 6
140730 10:58:47 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsxrootd1.fnal.gov:1094] is an xrootd redirector.
140730 10:58:47 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsxrootd1.fnal.gov:1094]).
140730 10:58:47 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsxrootd1.fnal.gov:1094
140730 10:58:47 1899 Xrd: SendGenCommand: Sending command Open
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_redirect] (4004)
140730 10:58:47 1899 Xrd: Disconnect:  LogConnID: 6 destroyed
140730 10:58:47 1899 Xrd: HandleServerError: Redir count=9
140730 10:58:47 1899 Xrd: TakeUrl: parsing url: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Proto:   root
140730 10:58:47 1899 Xrd: TakeUrl:    HostWPort:   cmsxrootd1.fnal.gov:1094
140730 10:58:47 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Host:   cmsxrootd1.fnal.gov
140730 10:58:47 1899 Xrd: TakeUrl:    Port:   1094
140730 10:58:47 1899 Xrd: HandleServerError: Received redirection to [cms-xrd-global.cern.ch:1094]. Token=[]]. Opaque=[tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov].
140730 10:58:47 1899 Xrd: XrdClientConn: Trying to connect to 128.142.139.172:1094
140730 10:58:47 1899 Xrd: Connect: Creating a logical connection...
140730 10:58:47 1899 Xrd: Connect: LogConn: size:8 count: 1PhyConn: size:3
140730 10:58:47 1899 Xrd: Connect: Connect(cms-xrd-global.cern.ch, 1094) returned 7
140730 10:58:47 1899 Xrd: DoHandShake: Setting Meta Manager Server Url = root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Proto:   root
140730 10:58:47 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:58:47 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:58:47 1899 Xrd: TakeUrl:    Port:   1094
140730 10:58:47 1899 Xrd: GetAccessToSrv: Ok: the server on [cms-xrd-global.cern.ch:1094] is an xrootd meta manager.
140730 10:58:47 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cms-xrd-global.cern.ch:1094]).
140730 10:58:47 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cms-xrd-global.cern.ch:1094
140730 10:58:47 1899 Xrd: SendGenCommand: Sending command Open
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cms-xrd-global.cern.ch:1094]...
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Server [cms-xrd-global.cern.ch:1094] answered [kXR_redirect] (4004)
140730 10:58:47 1899 Xrd: Disconnect:  LogConnID: 7 destroyed
140730 10:58:47 1899 Xrd: HandleServerError: Redir count=10
140730 10:58:47 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Proto:   root
140730 10:58:47 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 10:58:47 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 10:58:47 1899 Xrd: TakeUrl:    Port:   1094
140730 10:58:47 1899 Xrd: HandleServerError: Received redirection to [cmsdbs.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:58:47 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.52:1094
140730 10:58:47 1899 Xrd: Connect: Creating a logical connection...
140730 10:58:47 1899 Xrd: Connect: LogConn: size:9 count: 1PhyConn: size:3
140730 10:58:47 1899 Xrd: Connect: Connect(cmsdbs.ihep.ac.cn, 1094) returned 8
140730 10:58:47 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsdbs.ihep.ac.cn:1094] is an xrootd redirector.
140730 10:58:47 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsdbs.ihep.ac.cn:1094]).
140730 10:58:47 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsdbs.ihep.ac.cn:1094
140730 10:58:47 1899 Xrd: SendGenCommand: Sending command Open
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsdbs.ihep.ac.cn:1094]...
140730 10:58:47 1899 Xrd: ReadPartialAnswer: Server [cmsdbs.ihep.ac.cn:1094] answered [kXR_redirect] (4004)
140730 10:58:47 1899 Xrd: Disconnect:  LogConnID: 8 destroyed
140730 10:58:47 1899 Xrd: HandleServerError: Redir count=11
140730 10:58:47 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Proto:   root
140730 10:58:47 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 10:58:47 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 10:58:47 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 10:58:47 1899 Xrd: TakeUrl:    Port:   1094
140730 10:58:47 1899 Xrd: HandleServerError: Received redirection to [seadmin.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 10:58:47 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.22:1094
140730 10:58:47 1899 Xrd: Connect: Creating a logical connection...
140730 10:58:47 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 10:58:47 1899 Xrd: Connect: Connecting to [seadmin.ihep.ac.cn:1094]
140730 10:58:47 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to seadmin.ihep.ac.cn(202.122.33.22):1094 Windowsize=0 Timeout=120
140730 10:59:34 1897 Xrd: XrdClientPhyConnection: Destroying. [:-1]
140730 10:59:34 1897 Xrd: PhyConnection: Disconnecting socket...
140730 11:00:47 1899 Xrd: ClientSock::TryConnect_low: Connection toseadmin.ihep.ac.cn:1094 failed. (-1)
140730 11:00:47 1899 Xrd: Connect: can't open connection to [seadmin.ihep.ac.cn:1094]
140730 11:00:47 1899 Xrd: PhyConnection: Disconnecting socket...
140730 11:00:47 1899 Xrd: Connect: Destroying connection condvar for bbockelm@seadmin.ihep.ac.cn:1094
140730 11:00:47 1899 Xrd: Connect: Connect(seadmin.ihep.ac.cn, 1094) returned -1
140730 11:00:47 1899 Xrd: XrdNetFile: Error creating logical connection to seadmin.ihep.ac.cn:1094
140730 11:00:47 1899 Xrd: GoToAnotherServer: Error connecting to [seadmin.ihep.ac.cn:1094
140730 11:00:52 1899 Xrd: HandleServerError: Redir count=12
140730 11:00:52 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:00:52 1899 Xrd: TakeUrl:    Proto:   root
140730 11:00:52 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 11:00:52 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:00:52 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 11:00:52 1899 Xrd: TakeUrl:    Port:   1094
140730 11:00:52 1899 Xrd: HandleServerError: Received redirection to [cmsxrootd1.fnal.gov:1094]. Token=[]]. Opaque=[].
140730 11:00:52 1899 Xrd: XrdClientConn: Trying to connect to 131.225.204.174:1094
140730 11:00:52 1899 Xrd: Connect: Creating a logical connection...
140730 11:00:52 1899 Xrd: Connect: LogConn: size:10 count: 1PhyConn: size:3
140730 11:00:52 1899 Xrd: Connect: Connect(cmsxrootd1.fnal.gov, 1094) returned 9
140730 11:00:52 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsxrootd1.fnal.gov:1094] is an xrootd redirector.
140730 11:00:52 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsxrootd1.fnal.gov:1094]).
140730 11:00:52 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsxrootd1.fnal.gov:1094
140730 11:00:52 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:52 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:00:53 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:00:53 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:00:54 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:54 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:00:54 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:00:54 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:00:55 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:55 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:00:56 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:00:56 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:00:57 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:57 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:00:57 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:00:57 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:00:58 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:58 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:00:58 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:00:58 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:00:59 1899 Xrd: SendGenCommand: Sending command Open
140730 11:00:59 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:00 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:00 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:01 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:01 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:01 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:01 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:02 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:02 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:03 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:03 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:04 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:04 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:04 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:04 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:05 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:05 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:05 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:05 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:06 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:06 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:07 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:07 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:08 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:08 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:08 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:08 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:09 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:09 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:10 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:10 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:11 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:11 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:11 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:11 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:12 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:12 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:12 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:12 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:13 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:13 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:14 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:14 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:15 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:15 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:15 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:15 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:16 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:16 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:17 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:17 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:18 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:18 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:18 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:18 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:19 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:19 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:19 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:19 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:20 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:20 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:21 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:21 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:22 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:22 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:22 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_wait] (4005)
140730 11:01:22 1899 Xrd: CheckErrorStatus: Server [cmsxrootd1.fnal.gov:1094] requested 1 seconds of wait
140730 11:01:23 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:23 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsxrootd1.fnal.gov:1094]...
140730 11:01:23 1899 Xrd: ReadPartialAnswer: Server [cmsxrootd1.fnal.gov:1094] answered [kXR_redirect] (4004)
140730 11:01:23 1899 Xrd: Disconnect:  LogConnID: 9 destroyed
140730 11:01:23 1899 Xrd: HandleServerError: Redir count=13
140730 11:01:23 1899 Xrd: TakeUrl: parsing url: root://cmsxrootd1.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Proto:   root
140730 11:01:23 1899 Xrd: TakeUrl:    HostWPort:   cmsxrootd1.fnal.gov:1094
140730 11:01:23 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Host:   cmsxrootd1.fnal.gov
140730 11:01:23 1899 Xrd: TakeUrl:    Port:   1094
140730 11:01:23 1899 Xrd: HandleServerError: Received redirection to [cms-xrd-global.cern.ch:1094]. Token=[]]. Opaque=[tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov].
140730 11:01:23 1899 Xrd: XrdClientConn: Trying to connect to 128.142.138.171:1094
140730 11:01:23 1899 Xrd: Connect: Creating a logical connection...
140730 11:01:23 1899 Xrd: XrdClientPhyConnection: Destroying. [:-1]
140730 11:01:23 1899 Xrd: PhyConnection: Disconnecting socket...
140730 11:01:23 1899 Xrd: Connect: LogConn: size:11 count: 1PhyConn: size:3
140730 11:01:23 1899 Xrd: Connect: Connect(cms-xrd-global.cern.ch, 1094) returned 10
140730 11:01:23 1899 Xrd: DoHandShake: Setting Meta Manager Server Url = root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Proto:   root
140730 11:01:23 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 11:01:23 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 11:01:23 1899 Xrd: TakeUrl:    Port:   1094
140730 11:01:23 1899 Xrd: GetAccessToSrv: Ok: the server on [cms-xrd-global.cern.ch:1094] is an xrootd meta manager.
140730 11:01:23 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cms-xrd-global.cern.ch:1094]).
140730 11:01:23 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cms-xrd-global.cern.ch:1094
140730 11:01:23 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:23 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cms-xrd-global.cern.ch:1094]...
140730 11:01:23 1899 Xrd: ReadPartialAnswer: Server [cms-xrd-global.cern.ch:1094] answered [kXR_redirect] (4004)
140730 11:01:23 1899 Xrd: Disconnect:  LogConnID: 10 destroyed
140730 11:01:23 1899 Xrd: HandleServerError: Redir count=14
140730 11:01:23 1899 Xrd: TakeUrl: parsing url: root://cms-xrd-global.cern.ch:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Proto:   root
140730 11:01:23 1899 Xrd: TakeUrl:    HostWPort:   cms-xrd-global.cern.ch:1094
140730 11:01:23 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:23 1899 Xrd: TakeUrl:    Host:   cms-xrd-global.cern.ch
140730 11:01:23 1899 Xrd: TakeUrl:    Port:   1094
140730 11:01:23 1899 Xrd: HandleServerError: Received redirection to [cmsdbs.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 11:01:23 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.52:1094
140730 11:01:23 1899 Xrd: Connect: Creating a logical connection...
140730 11:01:23 1899 Xrd: Connect: LogConn: size:12 count: 1PhyConn: size:3
140730 11:01:23 1899 Xrd: Connect: Connect(cmsdbs.ihep.ac.cn, 1094) returned 11
140730 11:01:23 1899 Xrd: GetAccessToSrv: Ok: the server on [cmsdbs.ihep.ac.cn:1094] is an xrootd redirector.
140730 11:01:23 1899 Xrd: GetAccessToSrv: Reusing physical connection to server [cmsdbs.ihep.ac.cn:1094]).
140730 11:01:23 1899 Xrd: SendGenCommand: Got (and maybe recovered) an error from cmsdbs.ihep.ac.cn:1094
140730 11:01:23 1899 Xrd: SendGenCommand: Sending command Open
140730 11:01:23 1899 Xrd: ReadPartialAnswer: Reading a XrdClientMessage from the server [cmsdbs.ihep.ac.cn:1094]...
140730 11:01:24 1899 Xrd: ReadPartialAnswer: Server [cmsdbs.ihep.ac.cn:1094] answered [kXR_redirect] (4004)
140730 11:01:24 1899 Xrd: Disconnect:  LogConnID: 11 destroyed
140730 11:01:24 1899 Xrd: HandleServerError: Redir count=15
140730 11:01:24 1899 Xrd: TakeUrl: parsing url: root://cmsdbs.ihep.ac.cn:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:24 1899 Xrd: TakeUrl:    Proto:   root
140730 11:01:24 1899 Xrd: TakeUrl:    HostWPort:   cmsdbs.ihep.ac.cn:1094
140730 11:01:24 1899 Xrd: TakeUrl:    File:   /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root
140730 11:01:24 1899 Xrd: TakeUrl:    Host:   cmsdbs.ihep.ac.cn
140730 11:01:24 1899 Xrd: TakeUrl:    Port:   1094
140730 11:01:24 1899 Xrd: HandleServerError: Received redirection to [seadmin.ihep.ac.cn:1094]. Token=[]]. Opaque=[].
140730 11:01:24 1899 Xrd: XrdClientConn: Trying to connect to 202.122.33.22:1094
140730 11:01:24 1899 Xrd: Connect: Creating a logical connection...
140730 11:01:24 1899 Xrd: Connect: Physical connection not found. Creating a new one...
140730 11:01:24 1899 Xrd: Connect: Connecting to [seadmin.ihep.ac.cn:1094]
140730 11:01:24 1899 Xrd: ClientSock::TryConnect_low: Trying to connect to seadmin.ihep.ac.cn(202.122.33.22):1094 Windowsize=0 Timeout=120
140730 11:03:24 1899 Xrd: ClientSock::TryConnect_low: Connection toseadmin.ihep.ac.cn:1094 failed. (-1)
140730 11:03:24 1899 Xrd: Connect: can't open connection to [seadmin.ihep.ac.cn:1094]
140730 11:03:24 1899 Xrd: PhyConnection: Disconnecting socket...
140730 11:03:24 1899 Xrd: Connect: Destroying connection condvar for bbockelm@seadmin.ihep.ac.cn:1094
140730 11:03:24 1899 Xrd: Connect: Connect(seadmin.ihep.ac.cn, 1094) returned -1
140730 11:03:24 1899 Xrd: XrdNetFile: Error creating logical connection to seadmin.ihep.ac.cn:1094
140730 11:03:24 1899 Xrd: GoToAnotherServer: Error connecting to [seadmin.ihep.ac.cn:1094
140730 11:03:29 1899 Xrd: HandleServerError: Redir count=16
140730 11:03:29 1899 Xrd: SendGenCommand: Too many redirections for request  kXR_open. Aborting command.
@ljanyst
Copy link
Contributor

ljanyst commented Jul 30, 2014

Hi Brian,

did you observe the same issue with xrdcopy?

Cheers,
Lukasz

@bbockelm
Copy link
Contributor Author

Hi,

I copy the xrdcopy behavior below. It didn't even get this far - it was unable to connect to the data server and immediately gave up.

If it asks cms-xrd-global.cern.ch for a different replica, there is another one available.

Brian

[bbockelm@hcc-briantest ~]$ XRD_CONNECTIONWINDOW=10 XRD_NETWORKSTACK=IPv4 xrdcopy -d 2 -f  'root://cmsxrootd.fnal.gov//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root' /dev/null
[2014-07-31 09:53:57.590053 -0500][Debug  ][Poller            ] Available pollers: built-in
[2014-07-31 09:53:57.590138 -0500][Debug  ][Poller            ] Attempting to create a poller according to preference: built-in,libevent
[2014-07-31 09:53:57.590157 -0500][Debug  ][Poller            ] Creating poller: built-in
[2014-07-31 09:53:57.590172 -0500][Debug  ][Poller            ] Creating and starting the built-in poller...
[2014-07-31 09:53:57.590478 -0500][Debug  ][TaskMgr           ] Starting the task manager...
[2014-07-31 09:53:57.590603 -0500][Debug  ][TaskMgr           ] Task manager started
[2014-07-31 09:53:57.590778 -0500][Debug  ][JobMgr            ] Starting the job manager...
[2014-07-31 09:53:57.590925 -0500][Debug  ][JobMgr            ] Job manager started, 3 workers
[2014-07-31 09:53:57.590975 -0500][Debug  ][TaskMgr           ] Registering task: "FileTimer task" to be run at: [2014-07-31 09:53:57 -0500]
[2014-07-31 09:53:57.590975 -0500][Debug  ][PostMaster        ] Creating new channel to: cmsxrootd.fnal.gov:1094 1 stream(s)
[2014-07-31 09:53:57.590975 -0500][Debug  ][PostMaster        ] [cmsxrootd.fnal.gov:1094 #0] Stream parameters: Network Stack: IPv4, Connection Window: 10, ConnectionRetry: 5, Stream Error Widnow: 1800
[2014-07-31 09:53:57.591661 -0500][Debug  ][TaskMgr           ] Registering task: "TickGeneratorTask for: cmsxrootd.fnal.gov:1094" to be run at: [2014-07-31 09:54:12 -0500]
[2014-07-31 09:53:57.592294 -0500][Debug  ][PostMaster        ] [cmsxrootd.fnal.gov:1094] Found 2 address(es): [::ffff:131.225.204.174]:1094, [::ffff:129.93.239.131]:1094
[2014-07-31 09:53:57.592348 -0500][Debug  ][AsyncSock         ] [cmsxrootd.fnal.gov:1094 #0.0] Attempting connection to [::ffff:131.225.204.174]:1094
[2014-07-31 09:53:57.592630 -0500][Debug  ][Poller            ] Adding socket 0x17bc5c0 to the poller
[2014-07-31 09:53:57.610637 -0500][Debug  ][AsyncSock         ] [cmsxrootd.fnal.gov:1094 #0.0] Async connection call returned
[2014-07-31 09:53:57.610689 -0500][Debug  ][XRootDTransport   ] [cmsxrootd.fnal.gov:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2014-07-31 09:53:57.628762 -0500][Debug  ][XRootDTransport   ] [cmsxrootd.fnal.gov:1094 #0.0] Got the server hand shake response (type: manager [], protocol version 297)
[2014-07-31 09:53:57.628811 -0500][Debug  ][XRootDTransport   ] [cmsxrootd.fnal.gov:1094 #0.0] kXR_protocol successful (type: manager [], protocol version 297)
[2014-07-31 09:53:57.630250 -0500][Debug  ][XRootDTransport   ] [cmsxrootd.fnal.gov:1094 #0.0] Sending out kXR_login request, username: bbockelm, cgi: ?xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp
[2014-07-31 09:53:57.648046 -0500][Debug  ][XRootDTransport   ] [cmsxrootd.fnal.gov:1094 #0.0] Logged in
[2014-07-31 09:53:57.648102 -0500][Debug  ][PostMaster        ] [cmsxrootd.fnal.gov:1094 #0] Stream 0 connected.
[2014-07-31 09:53:57.648119 -0500][Debug  ][Utility           ] Monitor library name not set. No monitoring
[2014-07-31 09:53:57.666362 -0500][Error  ][XRootD            ] [cmsxrootd.fnal.gov:1094] Handling error while processing kXR_stat (path: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root, flags: none): [ERROR] Error response.
[2014-07-31 09:53:57.666413 -0500][Debug  ][Utility           ] CopyProcess: 1 jobs to prepare
[2014-07-31 09:53:57.666657 -0500][Debug  ][Utility           ] Creating a classic copy job, from root://cmsxrootd.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root to file:///dev/null
[2014-07-31 09:53:57.666665 -0500][Debug  ][Utility           ] Opening root://cmsxrootd.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root for reading
[2014-07-31 09:53:57.666665 -0500][Debug  ][File              ] [0x17bd4c0@root://cmsxrootd.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root] Sending an open command
[2014-07-31 09:53:57.703137 -0500][Debug  ][PostMaster        ] Creating new channel to: cms-xrd-global.cern.ch:1094 1 stream(s)
[2014-07-31 09:53:57.703181 -0500][Debug  ][PostMaster        ] [cms-xrd-global.cern.ch:1094 #0] Stream parameters: Network Stack: IPv4, Connection Window: 10, ConnectionRetry: 5, Stream Error Widnow: 1800
[2014-07-31 09:53:57.703922 -0500][Debug  ][TaskMgr           ] Registering task: "TickGeneratorTask for: cms-xrd-global.cern.ch:1094" to be run at: [2014-07-31 09:54:12 -0500]
[2014-07-31 09:53:57.704764 -0500][Debug  ][PostMaster        ] [cms-xrd-global.cern.ch:1094] Found 2 address(es): [::ffff:128.142.138.171]:1094, [::ffff:128.142.139.172]:1094
[2014-07-31 09:53:57.704764 -0500][Debug  ][AsyncSock         ] [cms-xrd-global.cern.ch:1094 #0.0] Attempting connection to [::ffff:128.142.138.171]:1094
[2014-07-31 09:53:57.704843 -0500][Debug  ][Poller            ] Adding socket 0x600014a0 to the poller
[2014-07-31 09:53:57.840069 -0500][Debug  ][AsyncSock         ] [cms-xrd-global.cern.ch:1094 #0.0] Async connection call returned
[2014-07-31 09:53:57.840161 -0500][Debug  ][XRootDTransport   ] [cms-xrd-global.cern.ch:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2014-07-31 09:53:57.978066 -0500][Debug  ][XRootDTransport   ] [cms-xrd-global.cern.ch:1094 #0.0] Got the server hand shake response (type: manager [], protocol version 297)
[2014-07-31 09:53:57.978131 -0500][Debug  ][XRootDTransport   ] [cms-xrd-global.cern.ch:1094 #0.0] kXR_protocol successful (type: manager [meta], protocol version 297)
[2014-07-31 09:53:57.979646 -0500][Debug  ][XRootDTransport   ] [cms-xrd-global.cern.ch:1094 #0.0] Sending out kXR_login request, username: bbockelm, cgi: ?xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp
[2014-07-31 09:53:58.115605 -0500][Debug  ][XRootDTransport   ] [cms-xrd-global.cern.ch:1094 #0.0] Logged in
[2014-07-31 09:53:58.115660 -0500][Debug  ][PostMaster        ] [cms-xrd-global.cern.ch:1094 #0] Stream 0 connected.
[2014-07-31 09:53:58.251794 -0500][Debug  ][TaskMgr           ] Registering task: "WaitTask for: 0x0x17bcbf0" to be run at: [2014-07-31 09:54:03 -0500]
[2014-07-31 09:54:03.591660 -0500][Debug  ][TaskMgr           ] Done with task: "WaitTask for: 0x0x17bcbf0"
[2014-07-31 09:54:03.727778 -0500][Debug  ][PostMaster        ] Creating new channel to: cmsdbs.ihep.ac.cn:1094 1 stream(s)
[2014-07-31 09:54:03.727822 -0500][Debug  ][PostMaster        ] [cmsdbs.ihep.ac.cn:1094 #0] Stream parameters: Network Stack: IPv4, Connection Window: 10, ConnectionRetry: 5, Stream Error Widnow: 1800
[2014-07-31 09:54:03.728751 -0500][Debug  ][TaskMgr           ] Registering task: "TickGeneratorTask for: cmsdbs.ihep.ac.cn:1094" to be run at: [2014-07-31 09:54:18 -0500]
[2014-07-31 09:54:03.729339 -0500][Debug  ][PostMaster        ] [cmsdbs.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.52]:1094
[2014-07-31 09:54:03.729384 -0500][Debug  ][AsyncSock         ] [cmsdbs.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.52]:1094
[2014-07-31 09:54:03.729450 -0500][Debug  ][Poller            ] Adding socket 0x5c001390 to the poller
[2014-07-31 09:54:03.940392 -0500][Debug  ][AsyncSock         ] [cmsdbs.ihep.ac.cn:1094 #0.0] Async connection call returned
[2014-07-31 09:54:03.940461 -0500][Debug  ][XRootDTransport   ] [cmsdbs.ihep.ac.cn:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2014-07-31 09:54:04.150457 -0500][Debug  ][XRootDTransport   ] [cmsdbs.ihep.ac.cn:1094 #0.0] Got the server hand shake response (type: manager [], protocol version 291)
[2014-07-31 09:54:04.150735 -0500][Debug  ][XRootDTransport   ] [cmsdbs.ihep.ac.cn:1094 #0.0] kXR_protocol successful (type: manager [], protocol version 291)
[2014-07-31 09:54:04.152103 -0500][Debug  ][XRootDTransport   ] [cmsdbs.ihep.ac.cn:1094 #0.0] Sending out kXR_login request, username: bbockelm, cgi: ?xrd.cc=us&xrd.tz=-6&xrd.appname=xrdcp
[2014-07-31 09:54:04.361933 -0500][Debug  ][XRootDTransport   ] [cmsdbs.ihep.ac.cn:1094 #0.0] Logged in
[2014-07-31 09:54:04.361988 -0500][Debug  ][PostMaster        ] [cmsdbs.ihep.ac.cn:1094 #0] Stream 0 connected.
[2014-07-31 09:54:04.579622 -0500][Debug  ][PostMaster        ] Creating new channel to: seadmin.ihep.ac.cn:1094 1 stream(s)
[2014-07-31 09:54:04.579666 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094 #0] Stream parameters: Network Stack: IPv4, Connection Window: 10, ConnectionRetry: 5, Stream Error Widnow: 1800
[2014-07-31 09:54:04.580462 -0500][Debug  ][TaskMgr           ] Registering task: "TickGeneratorTask for: seadmin.ihep.ac.cn:1094" to be run at: [2014-07-31 09:54:19 -0500]
[2014-07-31 09:54:04.581075 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:04.581106 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:04.581316 -0500][Debug  ][Poller            ] Adding socket 0x600020c0 to the poller
[2014-07-31 09:54:19.595002 -0500][Error  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Socket error while handshaking: [ERROR] Socket timeout
[2014-07-31 09:54:19.595034 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:54:19.595044 -0500][Debug  ][Poller            ] <x><--><x> Removing socket from the poller
[2014-07-31 09:54:19.595623 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:19.602204 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:19.602278 -0500][Debug  ][Poller            ] Adding socket 0x600020c0 to the poller
[2014-07-31 09:54:34.617613 -0500][Error  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Socket error while handshaking: [ERROR] Socket timeout
[2014-07-31 09:54:34.617654 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:54:34.617664 -0500][Debug  ][Poller            ] <x><--><x> Removing socket from the poller
[2014-07-31 09:54:34.618361 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:34.618408 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:34.618685 -0500][Debug  ][Poller            ] Adding socket 0x600020c0 to the poller
[2014-07-31 09:54:49.633035 -0500][Error  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Socket error while handshaking: [ERROR] Socket timeout
[2014-07-31 09:54:49.633071 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:54:49.633082 -0500][Debug  ][Poller            ] <x><--><x> Removing socket from the poller
[2014-07-31 09:54:49.633741 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:49.633761 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.22]:1094
[2014-07-31 09:54:49.634002 -0500][Debug  ][Poller            ] Adding socket 0x600020c0 to the poller
[2014-07-31 09:55:04.647680 -0500][Error  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Socket error while handshaking: [ERROR] Socket timeout
[2014-07-31 09:55:04.647719 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:55:04.647729 -0500][Debug  ][Poller            ] <x><--><x> Removing socket from the poller
[2014-07-31 09:55:04.648565 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094] Found 1 address(es): [::ffff:202.122.33.22]:1094
[2014-07-31 09:55:04.648602 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Attempting connection to [::ffff:202.122.33.22]:1094
[2014-07-31 09:55:04.648781 -0500][Debug  ][Poller            ] Adding socket 0x600020c0 to the poller
[2014-07-31 09:55:19.663584 -0500][Error  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Socket error while handshaking: [ERROR] Socket timeout
[2014-07-31 09:55:19.663614 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:55:19.663624 -0500][Debug  ][Poller            ] <x><--><x> Removing socket from the poller
[2014-07-31 09:55:19.663666 -0500][Error  ][PostMaster        ] [seadmin.ihep.ac.cn:1094 #0] Unable to recover: [ERROR] Socket timeout.
[2014-07-31 09:55:19.663678 -0500][Error  ][XRootD            ] [seadmin.ihep.ac.cn:1094] Impossible to send message kXR_open (file: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root?tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ). Trying to recover.
[2014-07-31 09:55:19.663693 -0500][Error  ][XRootD            ] [seadmin.ihep.ac.cn:1094] Handling error while processing kXR_open (file: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root?tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [FATAL] Socket timeout.
[2014-07-31 09:55:20.013534 -0500][Error  ][XRootD            ] [cmsdbs.ihep.ac.cn:1094] Handling error while processing kXR_open (file: /store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root?tried=+1213xrootd.unl.edu1213cmsxrootd1.fnal.gov,seadmin.ihep.ac.cn, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [ERROR] Error response.
[2014-07-31 09:55:20.013610 -0500][Debug  ][File              ] [0x17bd4c0@root://cmsxrootd.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root] Open has returned with status [ERROR] Server responded with an error: [3011] No servers have read access to the file
[2014-07-31 09:55:20.013624 -0500][Debug  ][File              ] [0x17bd4c0@root://cmsxrootd.fnal.gov:1094//store/mc/Spring14dr/WplusToTauNu_CT10_13TeV-powheg-pythia8-tauola/AODSIM/PU_S14_POSTLS170_V6-v1/00000/024BD287-7E08-E411-93B6-00266CFFBE68.root] Error while opening at cmsdbs.ihep.ac.cn:1094: [ERROR] Server responded with an error: [3011] No servers have read access to the file
[0B/0B][100%][==================================================][0B/s]  
Run: [ERROR] Server responded with an error: [3011] No servers have read access to the file

[2014-07-31 09:55:20.013750 -0500][Debug  ][JobMgr            ] Stopping the job manager...
[2014-07-31 09:55:20.014126 -0500][Debug  ][JobMgr            ] Job manager stopped
[2014-07-31 09:55:20.014126 -0500][Debug  ][TaskMgr           ] Stopping the task manager...
[2014-07-31 09:55:20.014294 -0500][Debug  ][TaskMgr           ] Task manager stopped
[2014-07-31 09:55:20.014294 -0500][Debug  ][Poller            ] Stopping the poller...
[2014-07-31 09:55:20.016467 -0500][Debug  ][TaskMgr           ] Requesting unregistration of: "TickGeneratorTask for: cms-xrd-global.cern.ch:1094"
[2014-07-31 09:55:20.016467 -0500][Debug  ][AsyncSock         ] [cms-xrd-global.cern.ch:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016467 -0500][Debug  ][Poller            ] <[::ffff:129.93.244.208]:49622><--><[::ffff:128.142.138.171]:1094> Removing socket from the poller
[2014-07-31 09:55:20.016543 -0500][Debug  ][PostMaster        ] [cms-xrd-global.cern.ch:1094 #0] Destroying stream
[2014-07-31 09:55:20.016574 -0500][Debug  ][AsyncSock         ] [cms-xrd-global.cern.ch:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016605 -0500][Debug  ][TaskMgr           ] Requesting unregistration of: "TickGeneratorTask for: cmsdbs.ihep.ac.cn:1094"
[2014-07-31 09:55:20.016610 -0500][Debug  ][AsyncSock         ] [cmsdbs.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016610 -0500][Debug  ][Poller            ] <[::ffff:129.93.244.208]:52796><--><[::ffff:202.122.33.52]:1094> Removing socket from the poller
[2014-07-31 09:55:20.016610 -0500][Debug  ][PostMaster        ] [cmsdbs.ihep.ac.cn:1094 #0] Destroying stream
[2014-07-31 09:55:20.016610 -0500][Debug  ][AsyncSock         ] [cmsdbs.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016610 -0500][Debug  ][TaskMgr           ] Requesting unregistration of: "TickGeneratorTask for: cmsxrootd.fnal.gov:1094"
[2014-07-31 09:55:20.016610 -0500][Debug  ][AsyncSock         ] [cmsxrootd.fnal.gov:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016610 -0500][Debug  ][Poller            ] <[::ffff:129.93.244.208]:35108><--><[::ffff:131.225.204.174]:1094> Removing socket from the poller
[2014-07-31 09:55:20.016614 -0500][Debug  ][PostMaster        ] [cmsxrootd.fnal.gov:1094 #0] Destroying stream
[2014-07-31 09:55:20.016624 -0500][Debug  ][AsyncSock         ] [cmsxrootd.fnal.gov:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016634 -0500][Debug  ][TaskMgr           ] Requesting unregistration of: "TickGeneratorTask for: seadmin.ihep.ac.cn:1094"
[2014-07-31 09:55:20.016642 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket
[2014-07-31 09:55:20.016650 -0500][Debug  ][PostMaster        ] [seadmin.ihep.ac.cn:1094 #0] Destroying stream
[2014-07-31 09:55:20.016657 -0500][Debug  ][AsyncSock         ] [seadmin.ihep.ac.cn:1094 #0.0] Closing the socket

@ljanyst
Copy link
Contributor

ljanyst commented Jul 31, 2014

Thanks, I will have a look at both.

@ljanyst
Copy link
Contributor

ljanyst commented Aug 1, 2014

I cannot reproduce, I get redirected to Spain starting from both CERN and SLAC. I will recreate these conditions artificially on Monday and see what's going wrong.

@bbockelm
Copy link
Contributor Author

bbockelm commented Aug 6, 2014

Shoot - sorry, I think we booted Beijing from the federation the following day and I forgot to update the ticket.

@ljanyst
Copy link
Contributor

ljanyst commented Aug 6, 2014

That's OK, I have enough info to reproduce with the imposters.

@ljanyst
Copy link
Contributor

ljanyst commented Aug 8, 2014

I have reproduced with the imposters. This is what happens:

The new client works as expected, but that's probably not really what we want. It goes from FNAL to global to IHEP redirector to IHEP disk server. It fails at the disk server, it goes back to global adding the disk server to tired CGI, it gets back to IHEP redirector where it gets: "No servers have read access to the file" since the disk server that has the file is excluded by "tried". It should probably add the whole chain from load balancer to failing server to the tried CGI, or should it? I am not quite sure. I would probably opt for marking everything from the disk server up to the first encountered manager? Any opinions?

When using the old client the whole CGI gets lost. It's not hugely complicated to fix, but not trivial either because, to properly do it, I would have to add functionality to track whole redirection chains. Currently the old client tracks only the last connection. It's probably not worth it.

@bbockelm
Copy link
Contributor Author

bbockelm commented Aug 8, 2014

Hi Lukasz,

It would seem that we should also exclude the first encountered manager. Honestly, I don't know the whole history behind the behavior of the old client in this regards - it might be nice to hear about @abh3 as he has a long memory (and perhaps there's a downside we're forgetting).

For the old client - I think it might be sufficient to just fix the fact it doesn't blacklist the disk server on a TCP connection failure.

Brian

@ffurano
Copy link
Contributor

ffurano commented Aug 8, 2014

Hi Brian,
nothing so special for the history. The "tried" mechanism was just born around 2004 with local clusters in mind, and then a bit stretched when the ideas of multiple redirection levels were implemented, with the versions up to roughly 2008. With the ideas of metamanagers, roughly 2009, it became clear that "just jump back to the top of the tree" started being insufficient, and no choice was really good if it was based on remembering only one failed node. A full-blown implementation would probably have to remember and avoid all the already erroneously visited nodes.
Fabrizio

@ljanyst
Copy link
Contributor

ljanyst commented Aug 12, 2014

Hi Brian,

I can do it, no problem, but I would also like to hear Andy's opinion on the matter. @abh3?

Cheers,
Lukasz

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

3 participants