 |
 |
One can reproduce this by introducing a sleep in the OfsFile::write method longer than the stream timeout which is 60. The command is a normal xrdcp:
env XRD_LOGLEVEL=Dump xrdcp -f /etc/passwd root://localhost//tmp/test1.datNow, this is a long trace but I've highlighted the flags which are passed from the client to the server in the inital open and then when a recovery of the open is done. I also removed some irrelevant lines. The thing is that in this case we are protected by the xrdcp command which opens the files for writing with kXR_delete & kXR_open_updt. An on a retry the first is dropped. So, in conclusion, I will add this protection just in case a user opens the file using XrdClFile::Open and uses as flags only OpenFlags::Delete.
[2015-09-18 17:09:34.616724 +0200][Debug ][PlugInMgr ] Initializing plug-in manager...
[2015-09-18 17:09:34.616741 +0200][Debug ][PlugInMgr ] No default plug-in, loading plug-in configs...
[2015-09-18 17:09:34.616754 +0200][Debug ][PlugInMgr ] Processing plug-in definitions in /etc/xrootd/client.plugins.d...
[2015-09-18 17:09:34.616860 +0200][Debug ][PlugInMgr ] Processing plug-in definitions in /home/esindril//.xrootd/client.plugins.d...
[2015-09-18 17:09:34.616883 +0200][Debug ][PlugInMgr ] Unable to process directory /home/esindril//.xrootd/client.plugins.d: [ERROR] OS Error: No such file or directory
[2015-09-18 17:09:34.616957 +0200][Dump ][App ] Chunk size: 16777216, parallel chunks 4, streams: 1
[2015-09-18 17:09:34.617200 +0200][Dump ][FileSystem ] [0xdc179b70@localhost:1094] Sending kXR_stat (path: /tmp/test1.dat, flags: none)
[2015-09-18 17:09:34.617246 +0200][Debug ][Poller ] Available pollers: built-in, libevent
[2015-09-18 17:09:34.617257 +0200][Debug ][Poller ] Attempting to create a poller according to preference: built-in,libevent
[2015-09-18 17:09:34.617269 +0200][Debug ][Poller ] Creating poller: built-in
[2015-09-18 17:09:34.617285 +0200][Debug ][Poller ] Creating and starting the built-in poller...
[2015-09-18 17:09:34.617508 +0200][Debug ][TaskMgr ] Starting the task manager...
[2015-09-18 17:09:34.617580 +0200][Debug ][TaskMgr ] Task manager started
[2015-09-18 17:09:34.617594 +0200][Debug ][JobMgr ] Starting the job manager...
[2015-09-18 17:09:34.617761 +0200][Debug ][JobMgr ] Job manager started, 3 workers
[2015-09-18 17:09:34.617782 +0200][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2015-09-18 17:09:34 +0200]
[2015-09-18 17:09:34.622758 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Sending out kXR_login request, username: esindril, cgi: ?xrd.cc=ch&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=esdss001.cern.ch, dual-stack: false, private IPv4: false, private IPv6: false
[2015-09-18 17:09:34.622821 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: (0x64000b80), 101 bytes
[2015-09-18 17:09:34.623013 +0200][Dump ][XRootDTransport ] [msg: 0x64000950] Expecting 16 bytes of message body
[2015-09-18 17:09:34.623032 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header, size: 8
[2015-09-18 17:09:34.623046 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received a message of 24 bytes
[2015-09-18 17:09:34.623065 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Logged in, session: 01000000271f00001800000001000000
[2015-09-18 17:09:34.623083 +0200][Debug ][PostMaster ] [localhost:1094 #0] Stream 0 connected.
[2015-09-18 17:09:34.623097 +0200][Debug ][Utility ] Monitor library name not set. No monitoring
[2015-09-18 17:09:34.623131 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: kXR_stat (path: /tmp/test1.dat, flags: none) (0xcb1440), 38 bytes
[2015-09-18 17:09:34.623157 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Successfully sent message: kXR_stat (path: /tmp/test1.dat, flags: none) (0xcb1440).
[2015-09-18 17:09:34.623174 +0200][Dump ][XRootD ] [localhost:1094] Message kXR_stat (path: /tmp/test1.dat, flags: none) has been successfully sent.
[2015-09-18 17:09:34.623192 +0200][Dump ][PostMaster ] [localhost:1094 #0.0] Nothing to write, disable uplink
[2015-09-18 17:09:34.623721 +0200][Dump ][XRootDTransport ] [msg: 0x64000b30] Expecting 34 bytes of message body
[2015-09-18 17:09:34.623738 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header for 0x64000b30 size: 8
[2015-09-18 17:09:34.623757 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message 0x64000b30 of 42 bytes
[2015-09-18 17:09:34.623771 +0200][Dump ][PostMaster ] [localhost:1094 #0] Handling received message: 0x64000b30.
[2015-09-18 17:09:34.623886 +0200][Dump ][XRootD ] [localhost:1094] Got a kXR_ok response to request kXR_stat (path: /tmp/test1.dat, flags: none)
[2015-09-18 17:09:34.623948 +0200][Dump ][XRootD ] [localhost:1094] Parsing the response to kXR_stat (path: /tmp/test1.dat, flags: none) as StatInfo: 74150355507674369 0 48 1442576592
[2015-09-18 17:09:34.624051 +0200][Dump ][App ] Processing source entry: /etc/passwd, type local file, target file: root://localhost//tmp/test1.dat
[2015-09-18 17:09:34.624208 +0200][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' = 'file:///etc/passwd', 'target' = 'root://localhost//tmp/test1.dat', 'thirdParty' = 'none', 'tpcTimeout' = '1800'
[2015-09-18 17:09:34.624693 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Sending an open command
[2015-09-18 17:09:34.624714 +0200][Dump ][XRootD ] [localhost:1094] Sending message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat )
[2015-09-18 17:09:34.624732 +0200][Dump ][PostMaster ] [localhost:1094 #0] Sending message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat ) (0xcb69d0) through substream 0 expecting answer at 0
[2015-09-18 17:09:34.624782 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat ) (0xcb69d0), 53 bytes
[2015-09-18 17:09:34.624795 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Successfully sent message: kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat ) (0xcb69d0).
[2015-09-18 17:09:34.624808 +0200][Dump ][XRootD ] [localhost:1094] Message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat ) has been successfully sent.
[2015-09-18 17:09:34.624828 +0200][Dump ][PostMaster ] [localhost:1094 #0.0] Nothing to write, disable uplink
[2015-09-18 17:09:34.625580 +0200][Dump ][XRootDTransport ] [msg: 0x64000b60] Expecting 46 bytes of message body
[2015-09-18 17:09:34.625602 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header for 0x64000b60 size: 8
[2015-09-18 17:09:34.625617 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message 0x64000b60 of 54 bytes
[2015-09-18 17:09:34.625632 +0200][Dump ][PostMaster ] [localhost:1094 #0] Handling received message: 0x64000b60.
[2015-09-18 17:09:34.625721 +0200][Dump ][XRootD ] [localhost:1094] Got a kXR_ok response to request kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat )
[2015-09-18 17:09:34.625763 +0200][Dump ][XRootD ] [localhost:1094] Parsing the response to kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat ) as OpenInfo
[2015-09-18 17:09:34.625794 +0200][Dump ][XRootD ] [localhost:1094] Parsing StatInfo in response to kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_delete kXR_open_updt kXR_async kXR_retstat )
[2015-09-18 17:09:34.625855 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Open has returned with status [SUCCESS]
[2015-09-18 17:09:34.625869 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] successfully opened at localhost:1094, handle: 0x0, session id: 1
[2015-09-18 17:09:34.626010 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Sending a write command for handle 0x0 to localhost:1094
[2015-09-18 17:09:34.626057 +0200][Dump ][XRootD ] [localhost:1094] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1730)
[2015-09-18 17:09:34.626079 +0200][Dump ][PostMaster ] [localhost:1094 #0] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1730) (0xcb69d0) through substream 0 expecting answer at 0
[1.689kB/1.689kB][100%][==================================================][1.689kB/s] [2015-09-18 17:09:34.626251 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: kXR_write (handle: 0x00000000, offset: 0, size: 1730) (0xcb69d0), 24 bytes
[2015-09-18 17:09:34.626266 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Will call raw handler to write payload for message: kXR_write (handle: 0x00000000, offset: 0, size: 1730) (0xcb69d0).
[2015-09-18 17:09:34.626283 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Successfully sent message: kXR_write (handle: 0x00000000, offset: 0, size: 1730) (0xcb69d0).
[2015-09-18 17:09:34.626294 +0200][Dump ][XRootD ] [localhost:1094] Message kXR_write (handle: 0x00000000, offset: 0, size: 1730) has been successfully sent.
[2015-09-18 17:09:34.626314 +0200][Dump ][PostMaster ] [localhost:1094 #0.0] Nothing to write, disable uplink
[2015-09-18 17:09:35.617829 +0200][Dump ][TaskMgr ] Running task: "FileTimer task"
[2015-09-18 17:09:35.617900 +0200][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2015-09-18 17:09:50 +0200]
[2015-09-18 17:09:49.619780 +0200][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: localhost:1094"
[2015-09-18 17:09:49.619881 +0200][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: localhost:1094" at [2015-09-18 17:10:04 +0200]
[2015-09-18 17:09:49.641439 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 15 seconds, TTL: 300, allocated SIDs: 1, open files: 1
[2015-09-18 17:09:49.641496 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 15 seconds, stream timeout: 60, allocated SIDs: 1, wait barrier: 2015-09-18 17:09:34 +0200
[2015-09-18 17:09:50.620025 +0200][Dump ][TaskMgr ] Running task: "FileTimer task"
[2015-09-18 17:09:50.620106 +0200][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2015-09-18 17:10:05 +0200]
[2015-09-18 17:10:04.621805 +0200][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: localhost:1094"
[2015-09-18 17:10:04.621883 +0200][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: localhost:1094" at [2015-09-18 17:10:19 +0200]
[2015-09-18 17:10:04.656600 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 30 seconds, TTL: 300, allocated SIDs: 1, open files: 1
[2015-09-18 17:10:04.656654 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 30 seconds, stream timeout: 60, allocated SIDs: 1, wait barrier: 2015-09-18 17:09:34 +0200
[2015-09-18 17:10:05.622019 +0200][Dump ][TaskMgr ] Running task: "FileTimer task"
[2015-09-18 17:10:05.622096 +0200][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2015-09-18 17:10:20 +0200]
[2015-09-18 17:10:19.623852 +0200][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: localhost:1094"
[2015-09-18 17:10:19.625024 +0200][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: localhost:1094" at [2015-09-18 17:10:34 +0200]
[2015-09-18 17:10:19.671752 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 45 seconds, TTL: 300, allocated SIDs: 1, open files: 1
[2015-09-18 17:10:19.671809 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 45 seconds, stream timeout: 60, allocated SIDs: 1, wait barrier: 2015-09-18 17:09:34 +0200
[2015-09-18 17:10:20.625171 +0200][Dump ][TaskMgr ] Running task: "FileTimer task"
[2015-09-18 17:10:20.625252 +0200][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2015-09-18 17:10:35 +0200]
[2015-09-18 17:10:34.626923 +0200][Dump ][TaskMgr ] Running task: "TickGeneratorTask for: localhost:1094"
[2015-09-18 17:10:34.627141 +0200][Dump ][TaskMgr ] Will rerun task "TickGeneratorTask for: localhost:1094" at [2015-09-18 17:10:49 +0200]
[2015-09-18 17:10:34.686944 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 60 seconds, TTL: 300, allocated SIDs: 1, open files: 1
[2015-09-18 17:10:34.687023 +0200][Dump ][XRootDTransport ] [localhost:1094 #0.0] Stream inactive since 60 seconds, stream timeout: 60, allocated SIDs: 1, wait barrier: 2015-09-18 17:09:34 +0200
[2015-09-18 17:10:34.687051 +0200][Debug ][AsyncSock ] [localhost:1094 #0.0] Closing the socket
[2015-09-18 17:10:34.687109 +0200][Debug ][Poller ] <[::ffff:127.0.0.1]:44058><--><[::ffff:127.0.0.1]:1094> Removing socket from the poller
[2015-09-18 17:10:34.687197 +0200][Debug ][PostMaster ] [localhost:1094 #0] Recovering error for stream #0: [ERROR] Socket error.
[2015-09-18 17:10:34.687225 +0200][Debug ][PostMaster ] [localhost:1094 #0] Reporting disconnection to queued message handlers.
[2015-09-18 17:10:34.687258 +0200][Dump ][XRootD ] [localhost:1094] Stream event reported for msg kXR_write (handle: 0x00000000, offset: 0, size: 1730)
[2015-09-18 17:10:34.687282 +0200][Debug ][XRootD ] [localhost:1094] Handling error while processing kXR_write (handle: 0x00000000, offset: 0, size: 1730): [ERROR] Socket error.
[2015-09-18 17:10:34.687390 +0200][Error ][XRootD ] [localhost:1094] Unable to get the response to request kXR_write (handle: 0x00000000, offset: 0, size: 1730)
[2015-09-18 17:10:34.687447 +0200][Dump ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] File state error encountered. Message kXR_write (handle: 0x00000000, offset: 0, size: 1730) returned with [ERROR] Socket error
[2015-09-18 17:10:34.687470 +0200][Dump ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Putting message kXR_write (handle: 0x00000000, offset: 0, size: 1730) in the recovery list
[2015-09-18 17:10:34.687498 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Running the recovery procedure
[2015-09-18 17:10:34.687513 +0200][Dump ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Sending a recovery open command to root://localhost:1094//tmp/test1.dat?oss.asize=1730
[2015-09-18 17:10:34.687549 +0200][Dump ][XRootD ] [localhost:1094] Sending message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt )
[2015-09-18 17:10:34.687579 +0200][Dump ][PostMaster ] [localhost:1094 #0] Sending message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ) (0x64001030) through substream 0 expecting answer at 0
[2015-09-18 17:10:34.687882 +0200][Debug ][PostMaster ] [localhost:1094] Found 2 address(es): [::ffff:127.0.0.1]:1094, [::ffff:127.0.0.1]:1094
[2015-09-18 17:10:34.687939 +0200][Debug ][AsyncSock ] [localhost:1094 #0.0] Attempting connection to [::ffff:127.0.0.1]:1094
[2015-09-18 17:10:34.688065 +0200][Debug ][Poller ] Adding socket 0xcb5d20 to the poller
[2015-09-18 17:10:34.688121 +0200][Debug ][AsyncSock ] [localhost:1094 #0.0] Async connection call returned
[2015-09-18 17:10:34.688153 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Sending out the initial hand shake + kXR_protocol
[2015-09-18 17:10:34.688192 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: (0x64000950), 44 bytes
[2015-09-18 17:10:34.688350 +0200][Dump ][XRootDTransport ] [msg: 0x64000950] Expecting 8 bytes of message body
[2015-09-18 17:10:34.688377 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header, size: 8
[2015-09-18 17:10:34.688393 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received a message of 16 bytes
[2015-09-18 17:10:34.688422 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Got the server hand shake response (type: server [], protocol version 300)
[2015-09-18 17:10:34.688455 +0200][Dump ][XRootDTransport ] [msg: 0x64000950] Expecting 8 bytes of message body
[2015-09-18 17:10:34.688467 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header, size: 8
[2015-09-18 17:10:34.688487 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received a message of 16 bytes
[2015-09-18 17:10:34.688555 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] kXR_protocol successful (type: server [], protocol version 300)
[2015-09-18 17:10:34.690817 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Sending out kXR_login request, username: esindril, cgi: ?xrd.cc=ch&xrd.tz=1&xrd.appname=xrdcp&xrd.info=&xrd.hostname=esdss001.cern.ch, dual-stack: false, private IPv4: false, private IPv6: false
[2015-09-18 17:10:34.690904 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: (0x64000ff0), 101 bytes
[2015-09-18 17:10:34.691112 +0200][Dump ][XRootDTransport ] [msg: 0x64000ff0] Expecting 16 bytes of message body
[2015-09-18 17:10:34.691143 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header, size: 8
[2015-09-18 17:10:34.691158 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received a message of 24 bytes
[2015-09-18 17:10:34.691177 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Logged in, session: 02000000271f00001900000002000000
[2015-09-18 17:10:34.691203 +0200][Debug ][XRootDTransport ] [localhost:1094 #0.0] Sending out kXR_endsess for session: 01000000271f00001800000001000000
[2015-09-18 17:10:34.691236 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: (0x64000950), 24 bytes
[2015-09-18 17:10:34.691352 +0200][Dump ][XRootDTransport ] [msg: 0x64000950] Expecting 24 bytes of message body
[2015-09-18 17:10:34.691386 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header, size: 8
[2015-09-18 17:10:34.691421 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received a message of 32 bytes
[2015-09-18 17:10:34.691452 +0200][Debug ][PostMaster ] [localhost:1094 #0] Stream 0 connected.
[2015-09-18 17:10:34.691492 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Wrote a message: kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ) (0x64001030), 53 bytes
[2015-09-18 17:10:34.691519 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Successfully sent message: kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ) (0x64001030).
[2015-09-18 17:10:34.691534 +0200][Dump ][XRootD ] [localhost:1094] Message kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ) has been successfully sent.
[2015-09-18 17:10:34.691551 +0200][Dump ][PostMaster ] [localhost:1094 #0.0] Nothing to write, disable uplink
[2015-09-18 17:10:34.692727 +0200][Dump ][XRootDTransport ] [msg: 0x640011b0] Expecting 75 bytes of message body
[2015-09-18 17:10:34.692770 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message header for 0x640011b0 size: 8
[2015-09-18 17:10:34.692817 +0200][Dump ][AsyncSock ] [localhost:1094 #0.0] Received message 0x640011b0 of 83 bytes
[2015-09-18 17:10:34.692878 +0200][Dump ][PostMaster ] [localhost:1094 #0] Handling received message: 0x640011b0.
[2015-09-18 17:10:34.692978 +0200][Dump ][XRootD ] [localhost:1094] Got a kXR_error response to request kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ) [3003] Output file /tmp/test1.dat is already opened by 1 writer; open denied.
[2015-09-18 17:10:34.693046 +0200][Debug ][XRootD ] [localhost:1094] Handling error while processing kXR_open (file: /tmp/test1.dat?oss.asize=1730, mode: 0644, flags: kXR_open_updt ): [ERROR] Error response: Unknown error 3003.
[2015-09-18 17:10:34.693147 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Open has returned with status [ERROR] Server responded with an error: [3003] Output file /tmp/test1.dat is already opened by 1 writer; open denied.
[2015-09-18 17:10:34.693174 +0200][Debug ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Error while opening at localhost:1094: [ERROR] Server responded with an error: [3003] Output file /tmp/test1.dat is already opened by 1 writer; open denied.
[2015-09-18 17:10:34.693194 +0200][Dump ][File ] [0xcb7140@root://localhost:1094//tmp/test1.dat?oss.asize=1730] Failing message kXR_write (handle: 0x00000000, offset: 0, size: 1730) with [ERROR] Server responded with an error: [3003] Output file /tmp/test1.dat is already opened by 1 writer; open denied.
[1.689kB/1.689kB][100%][==================================================][28B/s]
Run: [ERROR] Server responded with an error: [3003] Output file /tmp/test1.dat is already opened by 1 writer; open denied.
[2015-09-18 17:10:34.693511 +0200][Debug ][JobMgr ] Stopping the job manager...
[2015-09-18 17:10:34.693538 +0200][Dump ][JobMgr ] Stopping worker #0...
[2015-09-18 17:10:34.693830 +0200][Dump ][JobMgr ] Worker #0 stopped
[2015-09-18 17:10:34.693852 +0200][Dump ][JobMgr ] Stopping worker #1...
[2015-09-18 17:10:34.694170 +0200][Dump ][JobMgr ] Worker #1 stopped
[2015-09-18 17:10:34.694200 +0200][Dump ][JobMgr ] Stopping worker #2...
[2015-09-18 17:10:34.694310 +0200][Dump ][JobMgr ] Worker #2 stopped
[2015-09-18 17:10:34.694335 +0200][Debug ][JobMgr ] Job manager stopped
[2015-09-18 17:10:34.694349 +0200][Debug ][TaskMgr ] Stopping the task manager...
[2015-09-18 17:10:34.696125 +0200][Debug ][TaskMgr ] Task manager stopped
[2015-09-18 17:10:34.696161 +0200][Debug ][Poller ] Stopping the poller...
[2015-09-18 17:10:34.696321 +0200][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: localhost:1094"
[2015-09-18 17:10:34.696351 +0200][Debug ][AsyncSock ] [localhost:1094 #0.0] Closing the socket
[2015-09-18 17:10:34.696366 +0200][Debug ][Poller ] <[::ffff:127.0.0.1]:44059><--><[::ffff:127.0.0.1]:1094> Removing socket from the poller
[2015-09-18 17:10:34.696427 +0200][Debug ][PostMaster ] [localhost:1094 #0] Destroying stream
[2015-09-18 17:10:34.696454 +0200][Debug ][AsyncSock ] [localhost:1094 #0.0] Closing the socket
—
Reply to this email directly or view it on GitHub.
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