 |
 |
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