Hi Andy,
here's a log of a single request, made using "xrootd -d ..."
The command to trigger the issue is (with a valid dteam proxy behind):
$ davix-get -P grid -H "Want-Digest: md5" https://littlexrdhttp.cern.ch:1094/dynafeds_demo/ape.jpg /dev/null --trace header
If you need I can give you access to my vanilla setup "littlexrdhttp".
190211 10:07:13 13902 sysXrdHttp: Parsing first line: GET /dynafeds_demo/ape.jpg?xrdhttptk=rrVM1VvkuZhJLifgh2geKg==&xrdhttptime=1549876033&xrdhttpname=%2FDC=ch%2FDC=cern%2FOU=Organic%20Uni
ts%2FOU=Users%2FCN=furano%2FCN=644746%2FCN=Fabrizio%20Furano&xrdhttpvorg=dteam&xrdhttphost=%5B%3A%3Affff%3A188.184.31.59%5D&xrdhttpdn=%2FDC=ch%2FDC=cern%2FOU=Organic%20Units%2FOU=Users%2FCN=furano%2FCN=644746%2FCN=Fabrizio%20Furano%2FCN=786122054&xrdhttprole=NULL&xrdhttpgrps=%2Fdteam%20%2Fdteam%2FRole=NULL%20%2Fdteam%2FRole=NULL%2FCapability=NULL&xrdhttpendorsements=%2Fdteam%2FRole=NULL%2FCapability=NULL HTTP/1.1
190211 10:07:13 13902 sysXrdHttp: rc:40 got hdr line: User-Agent: libdavix/0.7.1 neon/0.0.29
190211 10:07:13 13902 sysXrdHttp: rc:14 got hdr line: Keep-Alive:
190211 10:07:13 13902 sysXrdHttp: rc:24 got hdr line: Connection: Keep-Alive
190211 10:07:13 13902 sysXrdHttp: rc:14 got hdr line: TE: trailers
190211 10:07:13 13902 sysXrdHttp: rc:36 got hdr line: Host: [::ffff:128.142.135.19]:1094
190211 10:07:13 13902 sysXrdHttp: rc:18 got hdr line: Want-Digest: md5
190211 10:07:13 13902 sysXrdHttp: rc:2 got hdr line:
190211 10:07:13 13902 sysXrdHttp: rc:2 detected header end.
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting vorg: dteam
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting vorg: dteam
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting name: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting name: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting host: [::ffff:188.184.31.59]
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting host: [::ffff:188.184.31.59]
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting dn: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano/CN=786122054
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting dn: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano/CN=786122054
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting role: NULL
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting role: NULL
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting grps: /dteam /dteam/Role=NULL /dteam/Role=NULL/Capability=NULL
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting grps: /dteam /dteam/Role=NULL /dteam/Role=NULL/Capability=NULL
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting endorsements: /dteam/Role=NULL/Capability=NULL
190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting endorsements: /dteam/Role=NULL/Capability=NULL
190211 10:07:13 13902 XrootdMonitor: 343 bytes sent to xrootd.t2.ucsd.edu:9960 rc=0
190211 10:07:13 13902 XrootdBridge: /DC=ch/D.16468:7@lxplus115 login as /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process. lp:0x7f0294001c78 reqstate: 0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge req=3017 dlen=468 blen=468
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process is exiting rc:0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 ofs_stat: fn=/dynafeds_demo/ape.jpg
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 rc=0 stat /dynafeds_demo/ape.jpg
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdResponse: 0000 sending 35 data bytes
190211 10:07:13 13902 sysXrdHttp: XrdHttpReq::Data! final=0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: PostProcessHTTPReq req: 2 reqstate: 0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Stat for GET /dynafeds_demo/ape.jpg stat=669744315301121 9943 49 1396526518
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process. lp:0 reqstate: 0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge req=3001 dlen=481 blen=481
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process is exiting rc:0
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 ofs_chksum: md5 fn=/dynafeds_demo/ape.jpg
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdResponse: 0000 sending 18 data bytes; status=4006
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge waiting for resp; sid=0 wt=600 (Job scheduled.)
190211 10:07:13 13902 XrdSched: running chksum inq=0
190211 10:07:13 13902 http ofs_chksum: md5 fn=/dynafeds_demo/ape.jpg
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrdLink: Setting ref to 1+1 post=0
190211 10:07:13 13902 sysXrdHttp: XrdHttpReq::Data! final=1
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: PostProcessHTTPReq req: 2 reqstate: 1
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Checksum for HEAD /dynafeds_demo/ape.jpg md5=a6c819209001be98139faecf177d352c
190211 10:07:13 13902 XrdPoll: Poller 0 enabled /DC=ch/D.16468:7@lxplus115
190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrdLink: Setting ref to 2+-1 post=0
190211 10:07:13 13902 XrootdsendResult: sent async ok to /DC=ch/D.16468:7@lxplus115
190211 10:07:13 13956 XrdSched: running main accept inq=0
—
You are receiving this because you commented.
Reply to this email directly, view it on GitHub, or mute the thread.
{"api_version":"1.0","publisher":{"api_key":"05dde50f1d1a384dd78767c55493e4bb","name":"GitHub"},"entity":{"external_key":"github/xrootd/xrootd","title":"xrootd/xrootd","subtitle":"GitHub repository","main_image_url":"https://github.githubassets.com/images/email/message_cards/header.png","avatar_image_url":"https://github.githubassets.com/images/email/message_cards/avatar.png","action":{"name":"Open in GitHub","url":"https://github.com/xrootd/xrootd"}},"updates":{"snippets":[{"icon":"PERSON","message":"@ffurano in #902: Hi Andy,\r\n here's a log of a single request, made using \"xrootd -d ...\"\r\n\r\n The command to trigger the issue is (with a valid dteam proxy behind):\r\n\r\n $ davix-get -P grid -H \"Want-Digest: md5\" https://littlexrdhttp.cern.ch:1094/dynafeds_demo/ape.jpg /dev/null --trace header\r\n\r\n If you need I can give you access to my vanilla setup \"littlexrdhttp\". \r\n\r\n-----------------------------------------------------------------------------------------------------------------------------\r\n190211 10:07:13 13902 sysXrdHttp: Parsing first line: GET /dynafeds_demo/ape.jpg?xrdhttptk=rrVM1VvkuZhJLifgh2geKg==\u0026xrdhttptime=1549876033\u0026xrdhttpname=%2FDC=ch%2FDC=cern%2FOU=Organic%20Uni\r\nts%2FOU=Users%2FCN=furano%2FCN=644746%2FCN=Fabrizio%20Furano\u0026xrdhttpvorg=dteam\u0026xrdhttphost=%5B%3A%3Affff%3A188.184.31.59%5D\u0026xrdhttpdn=%2FDC=ch%2FDC=cern%2FOU=Organic%20Units%2FOU=Users%2FCN=furano%2FCN=644746%2FCN=Fabrizio%20Furano%2FCN=786122054\u0026xrdhttprole=NULL\u0026xrdhttpgrps=%2Fdteam%20%2Fdteam%2FRole=NULL%20%2Fdteam%2FRole=NULL%2FCapability=NULL\u0026xrdhttpendorsements=%2Fdteam%2FRole=NULL%2FCapability=NULL HTTP/1.1\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:40 got hdr line: User-Agent: libdavix/0.7.1 neon/0.0.29\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:14 got hdr line: Keep-Alive: \r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:24 got hdr line: Connection: Keep-Alive\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:14 got hdr line: TE: trailers\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:36 got hdr line: Host: [::ffff:128.142.135.19]:1094\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:18 got hdr line: Want-Digest: md5\r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:2 got hdr line: \r\n\r\n190211 10:07:13 13902 sysXrdHttp: rc:2 detected header end.\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting vorg: dteam\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting vorg: dteam\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting name: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting name: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting host: [::ffff:188.184.31.59]\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting host: [::ffff:188.184.31.59]\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting dn: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano/CN=786122054\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting dn: /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano/CN=786122054\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting role: NULL\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting role: NULL\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting grps: /dteam /dteam/Role=NULL /dteam/Role=NULL/Capability=NULL\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting grps: /dteam /dteam/Role=NULL /dteam/Role=NULL/Capability=NULL\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting endorsements: /dteam/Role=NULL/Capability=NULL\r\n190211 10:07:13 13902 ?:7@lxplus115 sysXrdHttp: Setting endorsements: /dteam/Role=NULL/Capability=NULL\r\n190211 10:07:13 13902 XrootdMonitor: 343 bytes sent to xrootd.t2.ucsd.edu:9960 rc=0\r\n190211 10:07:13 13902 XrootdBridge: /DC=ch/D.16468:7@lxplus115 login as /DC=ch/DC=cern/OU=Organic Units/OU=Users/CN=furano/CN=644746/CN=Fabrizio Furano\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process. lp:0x7f0294001c78 reqstate: 0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge req=3017 dlen=468 blen=468\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process is exiting rc:0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 ofs_stat: fn=/dynafeds_demo/ape.jpg\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 rc=0 stat /dynafeds_demo/ape.jpg\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdResponse: 0000 sending 35 data bytes\r\n190211 10:07:13 13902 sysXrdHttp: XrdHttpReq::Data! final=0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: PostProcessHTTPReq req: 2 reqstate: 0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Stat for GET /dynafeds_demo/ape.jpg stat=669744315301121 9943 49 1396526518\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process. lp:0 reqstate: 0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge req=3001 dlen=481 blen=481\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Process is exiting rc:0\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 ofs_chksum: md5 fn=/dynafeds_demo/ape.jpg\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdResponse: 0000 sending 18 data bytes; status=4006\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrootdProtocol: 0000 Bridge waiting for resp; sid=0 wt=600 (Job scheduled.)\r\n190211 10:07:13 13902 XrdSched: running chksum inq=0\r\n190211 10:07:13 13902 http ofs_chksum: md5 fn=/dynafeds_demo/ape.jpg\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrdLink: Setting ref to 1+1 post=0\r\n190211 10:07:13 13902 sysXrdHttp: XrdHttpReq::Data! final=1\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: PostProcessHTTPReq req: 2 reqstate: 1\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 sysXrdHttp: Checksum for HEAD /dynafeds_demo/ape.jpg md5=a6c819209001be98139faecf177d352c\r\n190211 10:07:13 13902 XrdPoll: Poller 0 enabled /DC=ch/D.16468:7@lxplus115\r\n190211 10:07:13 13902 /DC=ch/D.16468:7@lxplus115 XrdLink: Setting ref to 2+-1 post=0\r\n190211 10:07:13 13902 XrootdsendResult: sent async ok to /DC=ch/D.16468:7@lxplus115\r\n190211 10:07:13 13956 XrdSched: running main accept inq=0\r\n"}],"action":{"name":"View Pull Request","url":"https://github.com/xrootd/xrootd/pull/902#issuecomment-462258986"}}}
[
{
"@context": "http://schema.org",
"@type": "EmailMessage",
"potentialAction": {
"@type": "ViewAction",
"target": "https://github.com/xrootd/xrootd/pull/902#issuecomment-462258986",
"url": "https://github.com/xrootd/xrootd/pull/902#issuecomment-462258986",
"name": "View Pull Request"
},
"description": "View this Pull Request on GitHub",
"publisher": {
"@type": "Organization",
"name": "GitHub",
"url": "https://github.com"
}
}
]
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
