Hi,
Good catch! I'll create an issue on GitHub.
Thanks,
Michal
________________________________________
From: [log in to unmask] [[log in to unmask]] on behalf of Matevz Tadel [[log in to unmask]]
Sent: 10 August 2018 12:33
To: xrootd-dev
Subject: xrdcp fails to write to local file on osx
Hi,
I'm getting errors writing to a local file with xrdcp on osx (master from 3 days back). I haven't done any xrootd development (or usage, actually) on osx in a long while ... so maybe I missed something obvious.
This is the essence of it, details in [1]:
[2018-08-10 12:27:10.747496 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Sending a write command for handle 0x12 to localhost
[2018-08-10 12:27:10.747622 +0200][Error ][File ] Write: failed Resource temporarily unavailable
File is created ok, it's just writing that fails:
matevz@glut test> ls -l A_FILE
-rw-r--r-- 1 matevz staff 0 Aug 10 12:27 A_FILE
xcache works ok, i.e., it writes files ok through oss layer.
Matevz
[1]
matevz@glut test> xrdcp -d 2 -f root://redirector.t2.ucsd.edu//store/user/matevz/A_FILE .
[2018-08-10 12:27:06.788353 +0200][Debug ][Utility ] CopyProcess: 2 jobs to prepare
[2018-08-10 12:27:06.788440 +0200][Debug ][File ] 16777220 256 2 1533821939
[2018-08-10 12:27:06.788876 +0200][Debug ][Utility ] Creating a classic copy job, from root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE to file://localhost/mt-dev/xrd/test/./A_FILE
[2018-08-10 12:27:06.788901 +0200][Debug ][Utility ] Monitor library name not set. No monitoring
[2018-08-10 12:27:06.789291 +0200][Debug ][Utility ] Opening root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE for reading
[2018-08-10 12:27:06.789368 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] Sending an open command
[2018-08-10 12:27:06.789430 +0200][Debug ][PostMaster ] Creating new channel to: redirector.t2.ucsd.edu:1094 1 stream(s)
[2018-08-10 12:27:06.789449 +0200][Debug ][PostMaster ] [redirector.t2.ucsd.edu:1094 #0] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800
[2018-08-10 12:27:06.790320 +0200][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: redirector.t2.ucsd.edu:1094" to be run at: [2018-08-10 12:27:21 +0200]
[2018-08-10 12:27:06.790800 +0200][Debug ][PostMaster ] [redirector.t2.ucsd.edu:1094] Found 2 address(es): [::ffff:169.228.130.78]:1094, [2607:f720:1700:1b30::4e]:1094
[2018-08-10 12:27:06.790864 +0200][Debug ][AsyncSock ] [redirector.t2.ucsd.edu:1094 #0.0] Attempting connection to [2607:f720:1700:1b30::4e]:1094
[2018-08-10 12:27:06.790944 +0200][Debug ][Poller ] Adding socket 0x10703f70 to the poller
[2018-08-10 12:27:06.962523 +0200][Debug ][AsyncSock ] [redirector.t2.ucsd.edu:1094 #0.0] Async connection call returned
[2018-08-10 12:27:06.962650 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2018-08-10 12:27:07.134556 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Got the server hand shake response (type: manager [], protocol version 310)
[2018-08-10 12:27:07.134680 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] kXR_protocol successful (type: manager [], protocol version 310)
[2018-08-10 12:27:07.137255 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Sending out kXR_login request, username: matevz, cgi: ?xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=glut-2.local&xrd.rn=v20180801-df577ab9, dual-stack: true, private IPv4: false, private IPv6: false
[2018-08-10 12:27:07.310009 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Logged in, session: 6f9502007703000089000000d8d00200
[2018-08-10 12:27:07.310066 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Authentication is required: &P=gsi,v:10300,c:ssl,ca:70d35895.0|d690e530.0
[2018-08-10 12:27:07.310090 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Sending authentication data
[2018-08-10 12:27:07.315947 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Trying to authenticate using gsi
[2018-08-10 12:27:07.617998 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Sending more authentication data for gsi
[2018-08-10 12:27:08.026924 +0200][Debug ][XRootDTransport ] [redirector.t2.ucsd.edu:1094 #0.0] Authenticated with gsi.
[2018-08-10 12:27:08.027011 +0200][Debug ][PostMaster ] [redirector.t2.ucsd.edu:1094 #0] Stream 0 connected.
[2018-08-10 12:27:08.213649 +0200][Debug ][PostMaster ] Creating new channel to: gftp-1.t2.ucsd.edu:1094 1 stream(s)
[2018-08-10 12:27:08.213699 +0200][Debug ][PostMaster ] [gftp-1.t2.ucsd.edu:1094 #0] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800
[2018-08-10 12:27:08.214857 +0200][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: gftp-1.t2.ucsd.edu:1094" to be run at: [2018-08-10 12:27:23 +0200]
[2018-08-10 12:27:08.214913 +0200][Info ][XRootD ] [gftp-1.t2.ucsd.edu:1094] Retrying request: kXR_open (file: /store/user/matevz/A_FILE, mode: 00, flags: kXR_open_read kXR_async kXR_retstat ).
[2018-08-10 12:27:08.421802 +0200][Debug ][PostMaster ] [gftp-1.t2.ucsd.edu:1094] Found 2 address(es): [::ffff:169.228.130.132]:1094, [2607:f720:1700:1b30::84]:1094
[2018-08-10 12:27:08.421886 +0200][Debug ][AsyncSock ] [gftp-1.t2.ucsd.edu:1094 #0.0] Attempting connection to [2607:f720:1700:1b30::84]:1094
[2018-08-10 12:27:08.421994 +0200][Debug ][Poller ] Adding socket 0x10404e80 to the poller
[2018-08-10 12:27:08.594056 +0200][Debug ][AsyncSock ] [gftp-1.t2.ucsd.edu:1094 #0.0] Async connection call returned
[2018-08-10 12:27:08.594147 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2018-08-10 12:27:09.552781 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Got the server hand shake response (type: server [], protocol version 310)
[2018-08-10 12:27:09.552863 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] kXR_protocol successful (type: server [], protocol version 310)
[2018-08-10 12:27:09.555115 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Sending out kXR_login request, username: matevz, cgi: ?xrd.cc=us&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=glut-2.local&xrd.rn=v20180801-df577ab9, dual-stack: true, private IPv4: false, private IPv6: false
[2018-08-10 12:27:09.727249 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Logged in, session: f78f0000b12d00007f00000091be0000
[2018-08-10 12:27:09.727315 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Authentication is required: &P=gsi,v:10300,c:ssl,ca:70d35895.0|d690e530.0
[2018-08-10 12:27:09.727356 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Sending authentication data
[2018-08-10 12:27:09.727404 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Trying to authenticate using gsi
[2018-08-10 12:27:09.938874 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Sending more authentication data for gsi
[2018-08-10 12:27:10.360644 +0200][Debug ][XRootDTransport ] [gftp-1.t2.ucsd.edu:1094 #0.0] Authenticated with gsi.
[2018-08-10 12:27:10.360754 +0200][Debug ][PostMaster ] [gftp-1.t2.ucsd.edu:1094 #0] Stream 0 connected.
[2018-08-10 12:27:10.571468 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] Open has returned with status [SUCCESS]
[2018-08-10 12:27:10.571515 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] successfully opened at gftp-1.t2.ucsd.edu:1094, handle: 0x0, session id: 1
[2018-08-10 12:27:10.571604 +0200][Debug ][Utility ] Opening file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211 for writing
[2018-08-10 12:27:10.571670 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Sending an open command
[2018-08-10 12:27:10.572007 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Open has returned with status [SUCCESS]
[2018-08-10 12:27:10.572029 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] successfully opened at localhost, handle: 0x12, session id: 1
[2018-08-10 12:27:10.572075 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] Sending a read command for handle 0x0 to gftp-1.t2.ucsd.edu:1094
[2018-08-10 12:27:10.747496 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Sending a write command for handle 0x12 to localhost
[2018-08-10 12:27:10.747622 +0200][Error ][File ] Write: failed Resource temporarily unavailable
[2018-08-10 12:27:10.747647 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Sending a close command for handle 0x12 to localhost
[2018-08-10 12:27:10.747722 +0200][Debug ][File ] [0x10704920@file://localhost/mt-dev/xrd/test/./A_FILE?oss.asize=8211] Close returned from localhost with: [SUCCESS]
[2018-08-10 12:27:10.747774 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] Sending a close command for handle 0x0 to gftp-1.t2.ucsd.edu:1094
[2018-08-10 12:27:10.919329 +0200][Debug ][File ] [0x10703210@root://redirector.t2.ucsd.edu:1094//store/user/matevz/A_FILE] Close returned from gftp-1.t2.ucsd.edu:1094 with: [SUCCESS]
[0B/0B][100%][==================================================][0B/s]
Run: [ERROR] OS Error: Permission denied
[2018-08-10 12:27:10.919695 +0200][Debug ][JobMgr ] Stopping the job manager...
[2018-08-10 12:27:10.919911 +0200][Debug ][JobMgr ] Job manager stopped
[2018-08-10 12:27:10.919943 +0200][Debug ][TaskMgr ] Stopping the task manager...
[2018-08-10 12:27:10.920014 +0200][Debug ][TaskMgr ] Task manager stopped
[2018-08-10 12:27:10.920048 +0200][Debug ][Poller ] Stopping the poller...
[2018-08-10 12:27:10.920154 +0200][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: gftp-1.t2.ucsd.edu:1094"
[2018-08-10 12:27:10.920174 +0200][Debug ][AsyncSock ] [gftp-1.t2.ucsd.edu:1094 #0.0] Closing the socket
[2018-08-10 12:27:10.920189 +0200][Debug ][Poller ] <[2001:1470:ff80:4:6d73:609e:5286:42e]:49955><--><[2607:f720:1700:1b30::84]:1094> Removing socket from the poller
[2018-08-10 12:27:10.920245 +0200][Debug ][PostMaster ] [gftp-1.t2.ucsd.edu:1094 #0] Destroying stream
[2018-08-10 12:27:10.920261 +0200][Debug ][AsyncSock ] [gftp-1.t2.ucsd.edu:1094 #0.0] Closing the socket
[2018-08-10 12:27:10.920280 +0200][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: redirector.t2.ucsd.edu:1094"
[2018-08-10 12:27:10.920294 +0200][Debug ][AsyncSock ] [redirector.t2.ucsd.edu:1094 #0.0] Closing the socket
[2018-08-10 12:27:10.920307 +0200][Debug ][Poller ] <[2001:1470:ff80:4:6d73:609e:5286:42e]:49954><--><[2607:f720:1700:1b30::4e]:1094> Removing socket from the poller
[2018-08-10 12:27:10.920343 +0200][Debug ][PostMaster ] [redirector.t2.ucsd.edu:1094 #0] Destroying stream
[2018-08-10 12:27:10.920359 +0200][Debug ][AsyncSock ] [redirector.t2.ucsd.edu:1094 #0.0] Closing the socket
matevz@glut test>
########################################################################
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
########################################################################
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
|
