Print

Print


Hi Andy, Lukasz,

The login id issue is fixed. The XrdSecPROTOCOL issue is still there. Here is way I got by setting XRD_LOGLEVEL to Dump. Note the “IP address mismatch” message below. If I doesn’t set XrdSecPROTOCOL, the client will try “sss” first (and will get the same error message) and then switch to unix.

sh-4.1$ export XRD_LOGLEVEL=Dump
sh-4.1$ export XrdSecPROTOCOL=sss
sh-4.1$ export XrdSecDEBUG=1
sh-4.1$ /afs/slac/package/xrootd/githead/amd64_rhel60/src/XrdCl/xrdcopy -f root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk /dev/null
[2014-05-13 13:47:12.426755 -0700][Warning][Utility           ] Unable to process global config file: [ERROR] OS Error: No such file or directory
[2014-05-13 13:47:12.427093 -0700][Debug  ][Utility           ] Unable to process user config file: [ERROR] OS Error: No such file or directory
[2014-05-13 13:47:12.427178 -0700][Debug  ][PlugInMgr         ] Initializing plug-in manager...
[2014-05-13 13:47:12.427191 -0700][Debug  ][PlugInMgr         ] No default plug-in, loading plug-in configs...
[2014-05-13 13:47:12.427199 -0700][Debug  ][PlugInMgr         ] Processing plug-in definitions in /etc/xrootd/client.plugins.d...
[2014-05-13 13:47:12.427214 -0700][Debug  ][PlugInMgr         ] Unable to process directory /etc/xrootd/client.plugins.d: [ERROR] OS Error: No such file or directory
[2014-05-13 13:47:12.427223 -0700][Debug  ][PlugInMgr         ] Processing plug-in definitions in /u/sf/yangw/.xrootd/client.plugins.d...
[2014-05-13 13:47:12.427254 -0700][Debug  ][PlugInMgr         ] Unable to process directory /u/sf/yangw/.xrootd/client.plugins.d: [ERROR] OS Error: No such file or directory
[2014-05-13 13:47:12.427324 -0700][Dump   ][App               ] Chunk size: 16777216, parallel chunks 4, streams: 1
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] URL: root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.427375 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] URL: root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.427451 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] URL: root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.427481 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.427519 -0700][Dump   ][FileSystem        ] [0x966990@atlxrd001:1094] Sending kXR_stat (path: /atlas/xrootd/usr/y/yangw/z2.junk, flags: none)
[2014-05-13 13:47:12.427542 -0700][Debug  ][Poller            ] Available pollers: built-in
[2014-05-13 13:47:12.427549 -0700][Debug  ][Poller            ] Attempting to create a poller according to preference: built-in,libevent
[2014-05-13 13:47:12.427556 -0700][Debug  ][Poller            ] Creating poller: built-in
[2014-05-13 13:47:12.427565 -0700][Debug  ][Poller            ] Creating and starting the built-in poller...
[2014-05-13 13:47:12.427994 -0700][Debug  ][TaskMgr           ] Starting the task manager...
[2014-05-13 13:47:12.428135 -0700][Debug  ][TaskMgr           ] Task manager started
[2014-05-13 13:47:12.428157 -0700][Debug  ][JobMgr            ] Starting the job manager...
[2014-05-13 13:47:12.428636 -0700][Debug  ][JobMgr            ] Job manager started, 3 workers
[2014-05-13 13:47:12.428655 -0700][Debug  ][TaskMgr           ] Registering task: "FileTimer task" to be run at: [2014-05-13 13:47:12 -0700]
[2014-05-13 13:47:12.428663 -0700][Dump   ][XRootD            ] [atlxrd001:1094] Sending message kXR_stat (path: /atlas/xrootd/usr/y/yangw/z2.junk, flags: none)
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] URL: atlxrd001:1094
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.428693 -0700][Dump   ][Utility           ] Path:
[2014-05-13 13:47:12.428712 -0700][Debug  ][PostMaster        ] Creating new channel to: atlxrd001:1094 1 stream(s)
[2014-05-13 13:47:12.428736 -0700][Debug  ][PostMaster        ] [atlxrd001:1094 #0] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Widnow: 1800
[2014-05-13 13:47:12.429400 -0700][Debug  ][TaskMgr           ] Registering task: "TickGeneratorTask for: atlxrd001:1094" to be run at: [2014-05-13 13:47:27 -0700]
[2014-05-13 13:47:12.429435 -0700][Dump   ][PostMaster        ] [atlxrd001:1094 #0] Sending message kXR_stat (path: /atlas/xrootd/usr/y/yangw/z2.junk, flags: none) (0x967070) through substream 0 expecting answer at 0
[2014-05-13 13:47:12.430109 -0700][Debug  ][PostMaster        ] [atlxrd001:1094] Found 1 address(es): [::ffff:172.23.44.137]:1094
[2014-05-13 13:47:12.430142 -0700][Debug  ][AsyncSock         ] [atlxrd001:1094 #0.0] Attempting connection to [::ffff:172.23.44.137]:1094
[2014-05-13 13:47:12.430226 -0700][Debug  ][Poller            ] Adding socket 0x96b310 to the poller
[2014-05-13 13:47:12.430674 -0700][Debug  ][AsyncSock         ] [atlxrd001:1094 #0.0] Async connection call returned
[2014-05-13 13:47:12.430707 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2014-05-13 13:47:12.430799 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Wrote a message:  (0x14000950), 44 bytes
[2014-05-13 13:47:12.431118 -0700][Dump   ][XRootDTransport   ] [msg: 0x14000950] Expecting 8 bytes of message body
[2014-05-13 13:47:12.431133 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received message header, size: 8
[2014-05-13 13:47:12.431144 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received a message of 16 bytes
[2014-05-13 13:47:12.431154 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Got the server hand shake response (type: server [], protocol version 297)
[2014-05-13 13:47:12.431165 -0700][Dump   ][XRootDTransport   ] [msg: 0x14000950] Expecting 8 bytes of message body
[2014-05-13 13:47:12.431171 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received message header, size: 8
[2014-05-13 13:47:12.431178 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received a message of 16 bytes
[2014-05-13 13:47:12.431186 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] kXR_protocol successful (type: server [], protocol version 297)
[2014-05-13 13:47:12.432690 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Sending out kXR_login request, username: yangw, cgi: ?xrd.cc=us&xrd.tz=-8&xrd.appname=xrdcopy
[2014-05-13 13:47:12.432779 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Wrote a message:  (0x14000b70), 64 bytes
[2014-05-13 13:47:12.433195 -0700][Dump   ][XRootDTransport   ] [msg: 0x14000b70] Expecting 35 bytes of message body
[2014-05-13 13:47:12.433214 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received message header, size: 8
[2014-05-13 13:47:12.433227 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received a message of 43 bytes
[2014-05-13 13:47:12.433246 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Logged in
[2014-05-13 13:47:12.433264 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Authentication is required: &P=sss,0.13:&P=unix
[2014-05-13 13:47:12.433283 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Sending authentication data
sec_Client: protocol request for host atlxrd001 token='&P=sss,0.13:&P=unix'
sec_PM: Loading sss protocol object from libXrdSecsss.so
sec_sss: Client keytab='/u/sf/yangw/.xrd/sss.keytab'
sec_PM: Using sss protocol, args='0.13:'
[2014-05-13 13:47:12.436506 -0700][Debug  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Trying to authenticate using sss
sec_sss: Ret 151 bytes of credentials; k=4
[2014-05-13 13:47:12.436801 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Wrote a message:  (0x14000e40), 175 bytes
[2014-05-13 13:47:12.437142 -0700][Dump   ][XRootDTransport   ] [msg: 0x14000b70] Expecting 25 bytes of message body
[2014-05-13 13:47:12.437162 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received message header, size: 8
[2014-05-13 13:47:12.437175 -0700][Dump   ][AsyncSock         ] [atlxrd001:1094 #0.0] Received a message of 33 bytes
[2014-05-13 13:47:12.437189 -0700][Error  ][XRootDTransport   ] [atlxrd001:1094 #0.0] Authentication with sss failed: IP address mismatch.
sec_Client: protocol request for host atlxrd001 token='&P=unix'
sec_PM: Skipping unix only want sss
XrdSec: No authentication protocols are available.
[2014-05-13 13:47:12.437222 -0700][Error  ][XRootDTransport   ] [atlxrd001:1094 #0.0] No protocols left to try
[2014-05-13 13:47:12.437237 -0700][Error  ][AsyncSock         ] [atlxrd001:1094 #0.0] Socket error while handshaking: [FATAL] Auth failed
[2014-05-13 13:47:12.437250 -0700][Debug  ][AsyncSock         ] [atlxrd001:1094 #0.0] Closing the socket
[2014-05-13 13:47:12.437267 -0700][Debug  ][Poller            ] <[::ffff:134.79.198.178]:39772><--><[::ffff:172.23.44.137]:1094> Removing socket from the poller
[2014-05-13 13:47:12.437398 -0700][Error  ][PostMaster        ] [atlxrd001:1094 #0] Unable to recover: [FATAL] Auth failed.
[2014-05-13 13:47:12.437419 -0700][Error  ][XRootD            ] [atlxrd001:1094] Impossible to send message kXR_stat (path: /atlas/xrootd/usr/y/yangw/z2.junk, flags: none). Trying to recover.
[2014-05-13 13:47:12.437436 -0700][Error  ][XRootD            ] [atlxrd001:1094] Handling error while processing kXR_stat (path: /atlas/xrootd/usr/y/yangw/z2.junk, flags: none): [FATAL] Auth failed.
[2014-05-13 13:47:12.437481 -0700][Dump   ][App               ] Processing source entry: root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk, type xroot, target file: /dev/null
[2014-05-13 13:47:12.437756 -0700][Dump   ][Utility           ] Adding job with properties: 'checkSumMode' = 'none', 'checkSumPreset' = '', 'checkSumType' = '', 'chunkSize' = '16777216', 'coerce' = '0', 'dynamicSource' = '0', 'force' = '1', 'initTimeout' = '600', 'makeDir' = '0', 'parallelChunks' = '4', 'posc' = '0', 'source' = 'root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk', 'target' = '/dev/null', 'thirdParty' = 'none', 'tpcTimeout' = '1800'
[2014-05-13 13:47:12.437781 -0700][Debug  ][Utility           ] CopyProcess: 1 jobs to prepare
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] URL: root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.437800 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] URL: /dev/null
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] Protocol:  file
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] Host Name:
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.437824 -0700][Dump   ][Utility           ] Path:      /dev/null
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] URL: root://atlxrd001//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.437850 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] URL: /dev/null
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] Protocol:  file
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] Host Name:
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.437870 -0700][Dump   ][Utility           ] Path:      /dev/null
[2014-05-13 13:47:12.437887 -0700][Debug  ][Utility           ] Creating a classic copy job, from root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk to file:///dev/null
[2014-05-13 13:47:12.437904 -0700][Debug  ][Utility           ] Monitor library name not set. No monitoring
[2014-05-13 13:47:12.437959 -0700][Debug  ][Utility           ] Opening root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk for reading
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] URL: root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.437979 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] URL: root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] Protocol:  root
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] User Name:
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] Password:
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] Host Name: atlxrd001
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] Port:      1094
[2014-05-13 13:47:12.438008 -0700][Dump   ][Utility           ] Path:      /atlas/xrootd/usr/y/yangw/z2.junk
[2014-05-13 13:47:12.438543 -0700][Debug  ][File              ] [0x96c550@root://atlxrd001:1094//atlas/xrootd/usr/y/yangw/z2.junk] Sending an open command
[2014-05-13 13:47:12.438563 -0700][Dump   ][XRootD            ] [atlxrd001:1094] Sending message kXR_open (file: /atlas/xrootd/usr/y/yangw/z2.junk, mode: 00, flags: kXR_open_read kXR_async kXR_retstat )
[2014-05-13 13:47:12.438576 -0700][Dump   ][PostMaster        ] [atlxrd001:1094 #0] Sending message kXR_open (file: /atlas/xrootd/usr/y/yangw/z2.junk, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ) (0x96b980) through substream 0 expecting answer at 0
[2014-05-13 13:47:12.438588 -0700][Error  ][XRootD            ] [atlxrd001:1094] Unable to send the message kXR_open (file: /atlas/xrootd/usr/y/yangw/z2.junk, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ): [FATAL] Auth failed
[0B/0B][100%][==================================================][0B/s]
Run: [FATAL] Auth failed
[2014-05-13 13:47:12.438761 -0700][Debug  ][JobMgr            ] Stopping the job manager...
[2014-05-13 13:47:12.438778 -0700][Dump   ][JobMgr            ] Stopping worker #0...
[2014-05-13 13:47:12.439115 -0700][Dump   ][JobMgr            ] Worker #0 stopped
[2014-05-13 13:47:12.439130 -0700][Dump   ][JobMgr            ] Stopping worker #1...
[2014-05-13 13:47:12.439277 -0700][Dump   ][JobMgr            ] Worker #1 stopped
[2014-05-13 13:47:12.439290 -0700][Dump   ][JobMgr            ] Stopping worker #2...
[2014-05-13 13:47:12.439548 -0700][Dump   ][JobMgr            ] Worker #2 stopped
[2014-05-13 13:47:12.439563 -0700][Debug  ][JobMgr            ] Job manager stopped
[2014-05-13 13:47:12.439570 -0700][Debug  ][TaskMgr           ] Stopping the task manager...
[2014-05-13 13:47:12.439640 -0700][Debug  ][TaskMgr           ] Task manager stopped
[2014-05-13 13:47:12.439677 -0700][Debug  ][Poller            ] Stopping the poller...
[2014-05-13 13:47:12.439826 -0700][Debug  ][TaskMgr           ] Requesting unregistration of: "TickGeneratorTask for: atlxrd001:1094"
[2014-05-13 13:47:12.439842 -0700][Debug  ][AsyncSock         ] [atlxrd001:1094 #0.0] Closing the socket
[2014-05-13 13:47:12.439849 -0700][Debug  ][PostMaster        ] [atlxrd001:1094 #0] Destroying stream
[2014-05-13 13:47:12.439856 -0700][Debug  ][AsyncSock         ] [atlxrd001:1094 #0.0] Closing the socket



Wei Yang  |  [log in to unmask]  |  1-650-926-3338




On May 13, 2014, at 9:31 AM, Andrew Hanushevsky <[log in to unmask]> wrote:

> Hi Wei,
>
> OK, we need you to turn on client debugging
>
> setenv XRDLOGLEVEL=Dump
>
> and retry with restricting to sss. It appears that the server rejected
> your credentials and that is why the failure occured.
>
> Andy
>


########################################################################
Use REPLY-ALL to reply to list

To unsubscribe from the XROOTD-DEV list, click the following link:
https://listserv.slac.stanford.edu/cgi-bin/wa?SUBED1=XROOTD-DEV&A=1