Print

Print


Hi,
  Actually, this seemed to reappear (for me at least with 5.3.0), e.g. 

`event: [1627545093982] BOTH   xroot	TRANSFER:EXIT	Job finished, [ERROR] Server responded with an error: [3019] File size mismatch (expected=1000000000, actual=1002438656) (destination)
INFO     Event triggered: BOTH xroot TRANSFER:EXIT Job finished, [ERROR] Server responded with an error: [3019] File size mismatch (expected=1000000000, actual=1002438656) (destination)
INFO     Destination file removed
gfal-copy error: 33 (Numerical argument out of domain) - Error on XrdCl::CopyProcess::Run(): [ERROR] Server responded with an error: [3019] File size mismatch (expected=1000000000, actual=1002438656) (destination)
`

In the Xrootd server logs I see:
`
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdAioFob: Running aio read 4194304@994050048 for dteam:test1/domatest/jwalder/HTTP_1GB
210729 09:05:21 3349574 XrdSched: running aio request inq=0
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR beg 4194304@994050048 inF=1
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR end 4194304@994050048 result=4194304 D-S=0-1 inF=0
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdResponse: 0100 sending 4194304 data bytes; status=0
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdAioBuff: Recycle 4194304@994050048 numF=2
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdFile: File::Ref 0 after +-1
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrdLink: Setting FD 111 ref to 2+-1 post=0
210729 09:05:21 3349574 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR recycle; reorders=0 D-S=1-1
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdProtocol: 0100 req=read dlen=8
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdProtocol: 0100 0 fh=0 read 4194304@998244352
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrdLink: Setting FD 111 ref to 1+1 post=0
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdFile: File::Ref 1 after +1
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdAioFob: Running aio read 4194304@998244352 for dteam:test1/domatest/jwalder/HTTP_1GB
210729 09:05:21 3349683 XrdSched: running aio request inq=0
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR beg 4194304@998244352 inF=1
210729 09:05:21 3349687 xrootd.3349588:32@ceph-gw8 XrootdProtocol: 0800 req=write dlen=16777216
210729 09:05:21 3349687 xrootd.3349588:32@ceph-gw8 XrootdProtocol: 0800 0 fh=0 write 16777216@419430400
210729 09:05:21 3349687 xrootd.3349588:32@ceph-gw8 XrootdAioTask: 0800 gdDone: 0x7fb9d00034f0 pendWrite not set
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR end 4194304@998244352 result=1755648 D-S=0-1 inF=0
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdResponse: 0100 sending 1755648 data bytes; status=0
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdAioBuff: Recycle 4194304@998244352 numF=1
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdFile: File::Ref 0 after +-1
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrdLink: Setting FD 111 ref to 2+-1 post=0
210729 09:05:21 3349683 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR recycle; reorders=0 D-S=1-1
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdProtocol: 0100 req=read dlen=8
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdProtocol: 0100 0 fh=0 read 2438656@1000000000
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrdLink: Setting FD 111 ref to 1+1 post=0
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdFile: File::Ref 1 after +1
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdAioFob: Running aio read 2438656@1000000000 for dteam:test1/domatest/jwalder/HTTP_1GB
210729 09:05:21 3350058 XrdSched: running aio request inq=0
210729 09:05:21 3349687 xrootd.3349588:32@ceph-gw8 XrootdNormAio: 0800 aioW beg 16777216@419430400 inF=1
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR beg 2438656@1000000000 inF=1
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR end 2438656@1000000000 result=2438656 D-S=0-1 inF=0
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdResponse: 0100 sending 2438656 data bytes; status=0
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdAioBuff: Recycle 2438656@1000000000 numF=1
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdFile: File::Ref 0 after +-1
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrdLink: Setting FD 111 ref to 2+-1 post=0
210729 09:05:21 3350058 u85.3349588:111@ceph-gw8 XrootdNormAio: 0100 aioR recycle; reorders=0 D-S=1-1
210729 09:05:21 3349684 u85.3349588:111@ceph-gw8 XrootdProtocol: 0100 req=close dlen=0
ceph_close: closed fd 10 for file test1/domatest/jwalder/HTTP_1GB, read ops count 0, write ops count 0, async write ops 0/0, async pending write bytes 0, async read ops 240/240, bytes written/max offset 0/0, longest as
ync write 0.000000, longest callback invocation 0.000000, last async op age 0.000000
`

I don't understand why 1) the penultimate read is over the 'boundary' and 2) why the final read is on the 1GB offset ? 


James


-- 
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/xrootd/xrootd/issues/1454#issuecomment-888903817

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