New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Ubuntu 17.10 - DAV communication freezes up and becomes unresponsive #597

Closed
DougBeney opened this Issue Oct 30, 2017 · 16 comments

Comments

Projects
None yet
8 participants
@DougBeney
Copy link

DougBeney commented Oct 30, 2017

Basic Info

I'm using Linux / Ubuntu in version: 17.10

I'm running Cryptomator in version: 1.3.1

Description

I unlock my Cryptomator vault and all is fine. I edit some files and all appears to be working fine.

Then five minute-ish into my session, I will try to save a file (Or open a file) and whatever program I am using will hang.

I try to lock my Cryptomator vault, but it says Drive eject failed. Looking into my syslog, it says the drive is busy.

I have to then force eject my drive from my file manager and then I am able to lock my vault.

This issue became present in Ubuntu 17.10.

With Ubuntu 16.04, Cryptomator worked very reliably.

Attachments

Unfortunately, cryptomator.log does not exist in this location:
~/.Cryptomator/cryptomator.log

The .Cryptomator folder does, but the log file does not.

My Ubuntu 17.10 setup is essentially vanilla, meaning that I didn't make any real changes. You should be able to produce this result easily. I've noticed this issue both on my laptop and desktop.

@DougBeney DougBeney changed the title Ubuntu 17.10 Ubuntu 17.10 - DAV communication freezes up and becomes unresponsive Oct 30, 2017

@DougBeney

This comment has been minimized.

Copy link

DougBeney commented Oct 31, 2017

Ok, so I ran cryptomator.sh and managed to catch the exact output from when dav communication froze: https://ghostbin.com/paste/9b66w

Hope this helps identify the problem.

@karschau

This comment has been minimized.

Copy link

karschau commented Nov 13, 2017

I can also confirm this happening on my machine.

@tobihagemann

This comment has been minimized.

Copy link
Member

tobihagemann commented Nov 25, 2017

Hey everyone. Just wanted to let you know that we didn't forget this issue (I had this tab open since forever). We haven't managed to set everything up for reproducing the issue yet. At the same time, we're about to release the update 1.3.2 soon.

Could you please try 1.3.2 after its release and let us know if you're still experiencing issues? In the meantime, could you please try out regular WebDAV drives if you're experiencing the same issues? You can use WebDAV test servers like https://webdavserver.com/ or https://ajaxbrowser.com/.

@DougBeney

This comment has been minimized.

Copy link

DougBeney commented Nov 25, 2017

Thanks for the response @tobihagemann. Will definitely try out 1.3.2. Any estimate on when that will be?

I've tried regular WebDAV before, but it fails to mount. I think it has something to do with #585

@DougBeney

This comment has been minimized.

Copy link

DougBeney commented Nov 25, 2017

Just wanted to report that I started using Cryptomator again for casual work (It's been 2-4 weeks since I started using Veracrypt instead, as this bug was making it hard to work.) I've been editing a financial spreadsheet for the last couple of hours with no issues whatsoever, constantly saving.

Maybe it was just an upstream Ubuntu 17.10 problem rather than something you guys needed to fix? They've been fixing a heck of a lot of problems lately. I'm 100% positive that this was an issue a few weeks ago.

If by Wednesday, I haven't experienced this problem again, I will close this issue. Stay tuned.

@DougBeney

This comment has been minimized.

Copy link

DougBeney commented Nov 26, 2017

Hmm. I did run into the issue again but it is no where as bad as it was before. Happens once a day after I boot up my computer, I force eject the volume, lock & unlock the vault again in Cryptomator, then it will work fine for the rest of the day. Once again, probably an Upstream Ubuntu/Gnome issue, but could be worth investigating.

Whether you'd like to keep this issue open for visibility or close it is completely up to you.

@chilliger

This comment has been minimized.

Copy link

chilliger commented Dec 16, 2017

I have the same problem running Linux Mint 18.3 Kernel 4.10.0-40-generic x86_64 and Cryptomator 1.3.2 when working on a tex file in the vault. When trying to build from the file with texstudio pdflatex freezes. Building with texstudio on local storage does not freeze texstudio/pdflatex.

@tobihagemann

This comment has been minimized.

Copy link
Member

tobihagemann commented Dec 18, 2017

Just to be sure: Is this issue related to #607 (or #174)?

@hb0

This comment has been minimized.

Copy link

hb0 commented Jan 28, 2018

Same here with Cryptomator 1.3.2 & Ubuntu 17.10:

Problem: Nautilus freezes after some time working perfectly with Cryptomator (copying needs infinite time, unable to do anything with the encrypted data. Killing Nautilus and restarting it allows you to browse the encrypted data again but as soon as you actually try to open something, nothing happens (or nautilus freezes). Ejecting the vault in Crytomator => "ejecting drive failed"

Are there any log files I should post on pastebin and link here?

cat /proc/version: Linux version 4.13.0-32-generic (buildd@lgw01-amd64-016) (gcc version 7.2.0 (Ubuntu 7.2.0-8ubuntu3)) #35-Ubuntu SMP Thu Jan 25 09:13:46 UTC 2018

Update 1
Ejecting the drive in the Nautilus Sidebar: This helps: I can unmount and remount the vault and open and copy the encrypted data again. I was not able to reproduce the freezing by doing the stuff I did the seconds before the freeze came (open a 40 MB large mp4 video in Ubuntu's default video player). So this seems to be time-related, i.e. after some time it stops working.

Update 2
Okay, after trying to copy 400 MB of data (a folder containing media files) nautilus closed before finishing and when I try to copy the folder again, I end up in "infinite" copying state: see attached screenshot

screenshot from 2018-01-28 01-24-12

@gerdawg

This comment has been minimized.

Copy link

gerdawg commented Feb 8, 2018

I'm having the same issue with cryptomator 1.3.2. and Ubuntu 17.10 as hb0.

Using dropbox sync, when decrypting the drive I am able to open up a file or two and then it freezes. I can shut down nautilus and unmount the drive. If I leave nautilus open I recieve the "failed to unmount drive" option.

There are not any log files in the default location for cryptomator but syslog does show messages when using the application.

Linux version 4.13.0-32-generic (buildd@lgw01-amd64-016) (gcc version 7.2.0 (Ubuntu 7.2.0-8ubuntu3)) #35-Ubuntu SMP Thu Jan 25 09:13:46 UTC 2018

@hb0

This comment has been minimized.

Copy link

hb0 commented Feb 8, 2018

Not sure if there is anything meaningful in there but here is a dump of the log output when running cryptomator from terminal until I end up in the state where I cannot access nor move any files anymore.

btw: as @gerdawg mentioned Dropbox: I'm using a cloud provider tool, too: pCloud.

20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@6105deee[p=0,l=8192,c=8192,r=8192]={<<<T|\xCd\x0b,\x84\xE7\xCb\x8d\xC0\r\xCe\x10\xF7\x9e/\x0c...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb>>>}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} onContent Content@1ad77c8c{HeapByteBufferR@5662299e[p=0,l=8192,c=8192,r=8192]={<<<T|\xCd\x0b,\x84\xE7\xCb\x8d\xC0\r\xCe\x10\xF7\x9e/\x0c...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb>>>}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@8ca6dd0[c=3457024,q=0,[0]=null,s=STREAM] addContent Content@1ad77c8c{HeapByteBufferR@5662299e[p=0,l=8192,c=8192,r=8192]={<<<T|\xCd\x0b,\x84\xE7\xCb\x8d\xC0\r\xCe\x10\xF7\x9e/\x0c...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb>>>}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} parsed false HttpParser{s=CONTENT,3465216 of 3466495}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@8ca6dd0[c=3465216,q=0,[0]=null,s=STREAM] read 8192 from Content@1ad77c8c{HeapByteBufferR@5662299e[p=8192,l=8192,c=8192,r=0]={T|\xCd\x0b,\x84\xE7\xCb\x8d\xC0\r\xCe\x10\xF7\x9e/\x0c...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb<<<>>>}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 1279 SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} filled 1279 HeapByteBuffer@6105deee[p=0,l=1279,c=8192,r=1279]={<<<[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CONTENT,3465216 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} parse HeapByteBuffer@6105deee[p=0,l=1279,c=8192,r=1279]={<<<[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb} {}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@6105deee[p=0,l=1279,c=8192,r=1279]={<<<[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} onContent Content@780d25e9{HeapByteBufferR@59554613[p=0,l=1279,c=8192,r=1279]={<<<[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@8ca6dd0[c=3465216,q=0,[0]=null,s=STREAM] addContent Content@780d25e9{HeapByteBufferR@59554613[p=0,l=1279,c=8192,r=1279]={<<<[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> END
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} onContentComplete
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} onRequestComplete
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@8ca6dd0[c=3465216,q=1,[0]=EOF,s=STREAM] addContent EOF
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=END,3466495 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=END,3466495 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} parsed false HttpParser{s=END,3466495 of 3466495}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpInput - HttpInputOverHTTP@8ca6dd0[c=3466495,q=1,[0]=EOF,s=STREAM] read 1279 from Content@780d25e9{HeapByteBufferR@59554613[p=1279,l=1279,c=8192,r=0]={[[\xFf\x00\xC8\xB7Yx\x8flz(\xB3\xD9Q\xE7\x8b...\xF2\xAf\xFe\xB2?\xCb\xAaK\x9c\xB6}J\xFf\xD9<<<>>>\x98\xAe\x1f\xFbf\xAc\xFf\x00\xEd\x92\x9b\xAe\x87\x05\xFb}\x16...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@51ae76e8[p=HttpParser{s=END,3466495 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=END,3466495 of 3466495},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
20:23:22.835 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - onEof HttpChannelState@5b9340b8{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
20:23:22.877 [Server thread 003] TRACE org.cryptomator.frontend.webdav.servlet.LoggingFilter - RESPONSE 374:
201
Date: Thu, 08 Feb 2018 19:23:20 GMT

20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.session - FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler516781195==dftMaxIdleSec=-1
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=false on HttpChannelOverHttp@714d0130{r=19,c=false,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@5b9340b8{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=COMPLETING,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg} action COMPLETE
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@3126e520[p=0,l=0,c=0,r=0]={<<<>>>} complete=true committing=true callback=Blocker@7f0fd006{null}
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg on HttpChannelOverHttp@714d0130{r=19,c=true,a=COMPLETING,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
201 null HTTP/1.1
Date: Thu, 08 Feb 2018 19:23:20 GMT


20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@5d26fe16[PROCESSING][i=HTTP/1.1{s=201,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@784762a9] generate: NEED_HEADER (null,[p=0,l=0,c=0,r=0],true)@START
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=201,h=1,cl=-1} last=true content=HeapByteBuffer@3126e520[p=0,l=0,c=0,r=0]={<<<>>>}
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - Date: Thu, 08 Feb 2018 19:23:20 GMT


20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@5d26fe16[PROCESSING][i=HTTP/1.1{s=201,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@784762a9] generate: FLUSH ([p=0,l=112,c=8192,r=112],[p=0,l=0,c=0,r=0],true)@COMPLETING
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@2610aeb8{IDLE}->null [HeapByteBuffer@6105deee[p=0,l=112,c=8192,r=112]={<<<HTTP/1.1 201 Crea....v20170914)\r\n\r\n>>>\xF5K\xBd\xFf\x005\xAa\xEb\xD3\xCf\xF9\x83\xD3\xFf\x00\xB6...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}]
20:23:22.878 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@2610aeb8{WRITING}->null:IDLE-->WRITING
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 112 SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=W,to=43/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=END,3466495 of 3466495},g=HttpGenerator@5040bd72{s=COMPLETING}]=>HttpChannelOverHttp@714d0130{r=19,c=true,a=COMPLETING,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true 112/112+0 WriteFlusher@2610aeb8{WRITING}->null
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@2610aeb8{IDLE}->null:WRITING-->IDLE
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@5d26fe16[PROCESSING][i=HTTP/1.1{s=201,h=1,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@784762a9] generate: DONE ([p=112,l=112,c=8192,r=0],[p=0,l=0,c=0,r=0],true)@END
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@5b9340b8{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /_wR9eazJQZ9y/privat/1%20Selten/Fotos/Family/calender_dad/saksalaiset_ja_hanna.jpg written=0
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@5b9340b8{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,3466495 of 3466495}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} handle exit, result COMPLETE
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} filled 0 HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 201 Crea...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} parse HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 201 Crea...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb} {}
20:23:22.879 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 201 Crea...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} parsed false HttpParser{s=START,0 of -1}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@491d62cb{AC.ReadCB@51ae76e8{HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}}}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change CEP:SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=2 selected=0
20:23:22.880 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/2 selected
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} onFillable exit HttpChannelState@5b9340b8{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
20:23:22.880 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action CEP:SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING
20:23:22.880 [Server thread 003] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread@be46878 waiting
20:23:22.880 [Server thread 006] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:22.880 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select
20:23:46.515 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=23947/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=START,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 23947 ms, remaining: 6053 ms
20:23:46.515 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=23635/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 23635 ms, remaining: 6365 ms
20:23:52.568 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=START,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 30001 ms, remaining: -1 ms
20:23:52.569 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=START,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} idle timeout expired
20:23:52.569 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onFail FillInterest@1662b23a{AC.ReadCB@3f73d434{HttpConnection@3f73d434[p=HttpParser{s=START,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=START,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}}} {}
20:23:52.569 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
20:23:52.569 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
20:23:52.569 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} onFillInterestedFailed {}
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=-,flush=-,to=30003/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@1662b23a{AC.ReadCB@3f73d434{HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}}}
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 1->1 for SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change CEP:SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=2 selected=0
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@6b7e6491{IDLE}->null {}
20:23:52.570 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/2 selected
20:23:52.570 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractEndPoint - Ignored idle endpoint SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:23:52.571 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action CEP:SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING
20:23:52.571 [Server thread 006] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 1 on SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:23:52.571 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select
20:23:52.881 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 30001 ms, remaining: -1 ms
20:23:52.881 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} idle timeout expired
20:23:52.882 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onFail FillInterest@491d62cb{AC.ReadCB@51ae76e8{HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=START,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}}} {}
20:23:52.882 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=START,0 of -1}
20:23:52.882 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - START --> CLOSE
20:23:52.882 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} onFillInterestedFailed {}
20:23:52.883 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=-,flush=-,to=30003/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:52.883 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@491d62cb{AC.ReadCB@51ae76e8{HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}}}
20:23:52.883 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 1->1 for SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:52.884 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change CEP:SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=2 selected=0
20:23:52.884 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@2610aeb8{IDLE}->null {}
20:23:52.884 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/2 selected
20:23:52.884 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractEndPoint - Ignored idle endpoint SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:52.884 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action CEP:SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING
20:23:52.885 [Server thread 006] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 1 -> 1 on SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:23:52.885 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 30000 ms, remaining: 0 ms
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} idle timeout expired
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onFail FillInterest@1662b23a{AC.ReadCB@3f73d434{HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}}} {}
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=CLOSE,0 of -1}
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - CLOSE --> CLOSE
20:24:22.571 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,OSHUT,fill=-,flush=-,to=30000/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null} onFillInterestedFailed {}
20:24:22.572 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:24:22.572 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@6b7e6491{IDLE}->null {}
20:24:22.572 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@1662b23a{null}
20:24:22.572 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@730d3ed6 on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=2 selected=0
20:24:22.572 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@6b7e6491{IDLE}->null {}
20:24:22.573 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/1 selected
20:24:22.573 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@730d3ed6
20:24:22.573 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractEndPoint - Ignored idle endpoint SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=30001/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:24:22.573 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=30002/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:24:22.573 [Server thread 006] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@70fb4a47{/127.0.0.1:56712<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=0/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@3f73d434[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@157008ef{s=START}]=>HttpChannelOverHttp@5adb2942{r=117,c=false,a=IDLE,uri=null}
20:24:22.573 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select
20:24:22.885 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} idle timeout check, elapsed: 30001 ms, remaining: -1 ms
20:24:22.885 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} idle timeout expired
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onFail FillInterest@491d62cb{AC.ReadCB@51ae76e8{HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=FI,flush=-,to=30001/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}}} {}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - close HttpParser{s=CLOSE,0 of -1}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.http.HttpParser - CLOSE --> CLOSE
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractConnection - HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,OSHUT,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null} onFillInterestedFailed {}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ChannelEndPoint - doClose SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=30002/30000}{io=1/1,kio=1,kro=1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@2610aeb8{IDLE}->null {}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.FillInterest - onClose FillInterest@491d62cb{null}
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@483731f7 on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=1 selected=0
20:24:22.886 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.WriteFlusher - ignored: WriteFlusher@2610aeb8{IDLE}->null {}
20:24:22.887 [Scheduler-1314310176] DEBUG org.eclipse.jetty.io.AbstractEndPoint - Ignored idle endpoint SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=30002/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:24:22.887 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/0 selected
20:24:22.887 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action org.eclipse.jetty.io.ManagedSelector$DestroyEndPoint@483731f7
20:24:22.887 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Destroyed SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=0/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:24:22.887 [Server thread 006] DEBUG org.eclipse.jetty.io.AbstractConnection - onClose HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@5c5bb691{/127.0.0.1:56748<->/127.0.0.1:42427,CLOSED,fill=-,flush=-,to=0/30000}{io=1/1,kio=-1,kro=-1}->HttpConnection@51ae76e8[p=HttpParser{s=CLOSE,0 of -1},g=HttpGenerator@5040bd72{s=START}]=>HttpChannelOverHttp@714d0130{r=19,c=false,a=IDLE,uri=null}
20:24:22.887 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select

---------------


20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@7f6db90a[PROCESSING][i=HTTP/1.1{s=207,h=3,cl=617},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@6b78d299] generate: NEED_HEADER (null,[p=0,l=617,c=617,r=617],true)@START
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=207,h=3,cl=617} last=true content=HeapByteBuffer@1dfa0adf[p=0,l=617,c=617,r=617]={<<<<?xml version="1..../D:multistatus>>>>}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - Date: Thu, 08 Feb 2018 19:25:44 GMT
Content-Type: text/xml;charset=utf-8
Content-Length: 617


20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@7f6db90a[PROCESSING][i=HTTP/1.1{s=207,h=3,cl=617},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@6b78d299] generate: FLUSH ([p=0,l=157,c=8192,r=157],[p=0,l=617,c=617,r=617],true)@COMPLETING
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@27353e0{IDLE}->null [HeapByteBuffer@6105deee[p=0,l=157,c=8192,r=157]={<<<HTTP/1.1 207 Mult....v20170914)\r\n\r\n>>>cept-Encoding: gz...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb},HeapByteBuffer@1dfa0adf[p=0,l=617,c=617,r=617]={<<<<?xml version="1..../D:multistatus>>>>}]
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@27353e0{WRITING}->null:IDLE-->WRITING
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - flushed 774 SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=W,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=END,235 of 235},g=HttpGenerator@a3d3524{s=COMPLETING}]=>HttpChannelOverHttp@33a0f73e{r=21,c=true,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Freunde}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true 157/157+1 WriteFlusher@27353e0{WRITING}->null
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@27353e0{IDLE}->null:WRITING-->IDLE
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - org.eclipse.jetty.server.HttpConnection$SendCallback@7f6db90a[PROCESSING][i=HTTP/1.1{s=207,h=3,cl=617},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@6b78d299] generate: DONE ([p=157,l=157,c=8192,r=0],[p=617,l=617,c=617,r=0],true)@END
20:25:44.251 [Server thread 003] TRACE org.cryptomator.frontend.webdav.servlet.LoggingFilter - RESPONSE 406:
207
Content-Length: 617
Date: Thu, 08 Feb 2018 19:25:44 GMT
Content-Type: text/xml;charset=utf-8

20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.session - FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler516781195==dftMaxIdleSec=-1
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@33a0f73e{r=21,c=true,a=DISPATCHED,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Freunde}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@393c481e{s=DISPATCHED a=NOT_ASYNC i=true r=READY w=false}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@33a0f73e{r=21,c=true,a=COMPLETING,uri=//localhost:42427/_wR9eazJQZ9y/privat/1%20Selten/Fotos/Freunde} action COMPLETE
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - onComplete HttpChannelState@393c481e{s=COMPLETING a=NOT_ASYNC i=false r=READY w=false}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - COMPLETE for /_wR9eazJQZ9y/privat/1%20Selten/Fotos/Freunde written=617
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@393c481e{s=COMPLETED a=NOT_ASYNC i=false r=READY w=false}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,235 of 235}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpChannel - HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null} handle exit, result COMPLETE
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - filled 0 SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null} filled 0 HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 207 Mult...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null} parse HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 207 Mult...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb} {}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START HeapByteBuffer@6105deee[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 207 Mult...\x99\xFe\xE4>!\xFc\x8ah\xB1\xEa4\xFf\x00\xFb}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null} parsed false HttpParser{s=START,0 of -1}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.251 [Server thread 003] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.252 [Server thread 003] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@3e903a5a{AC.ReadCB@6c95f4aa{HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}}}
20:25:44.252 [Server thread 003] DEBUG org.eclipse.jetty.io.ChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.252 [Server thread 003] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change CEP:SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING on org.eclipse.jetty.io.ManagedSelector@3c82b9d3 id=0 keys=1 selected=0
20:25:44.252 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop woken up from select, 0/1 selected
20:25:44.252 [Server thread 003] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}<-SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null} onFillable exit HttpChannelState@393c481e{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
20:25:44.252 [Server thread 003] DEBUG org.eclipse.jetty.util.thread.ReservedThreadExecutor - org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread@be46878 waiting
20:25:44.252 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Running action CEP:SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}:runUpdateKey:NON_BLOCKING
20:25:44.252 [Server thread 006] DEBUG org.eclipse.jetty.io.ChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@15de620e{/127.0.0.1:56772<->/127.0.0.1:42427,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@6c95f4aa[p=HttpParser{s=START,0 of -1},g=HttpGenerator@a3d3524{s=START}]=>HttpChannelOverHttp@33a0f73e{r=21,c=false,a=IDLE,uri=null}
20:25:44.252 [Server thread 006] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector loop waiting on select
@overheadhunter

This comment has been minimized.

Copy link
Member

overheadhunter commented Apr 6, 2018

Today we released our first beta of 1.4.0, which brings FUSE support to macOS and Linux.

Please retest this issue with FUSE enabled and report your findings in this thread.


If you experience any new issues, please report them and tell us what software version (including macOS version, linux kernel, display manager, desktop environment) you're using.

⚠️ This is a beta version! Make backups and don't use this version for production data. ⚠️

@hb0

This comment has been minimized.

Copy link

hb0 commented Apr 13, 2018

I didn't have this issue for some time now, even though I'm on cryptomator 1.3.2 (linux). Maybe this was Ubuntu 17.10 related and was fixed in os updates? But to be honest, I didn't copy large amounts of data around and didn't use it too intensively.

Update
Using a fresh Ubuntu 18.04 installation it starts hanging again, same as before. I'm awayting the 1.4.0 update and will report back.

@no-response no-response bot closed this Jun 17, 2018

@no-response

This comment has been minimized.

Copy link

no-response bot commented Jun 17, 2018

This issue has been automatically closed because there has been no response to our request for more information from the original author. With only the information that is currently in the issue, we don't have enough information to take action. Please reach out if you have or find the answers we need so that we can investigate further.

@overheadhunter overheadhunter added this to the 1.4.0 milestone Jun 17, 2018

@philborman

This comment has been minimized.

Copy link

philborman commented Aug 18, 2018

Seems to be fixed with 1.4.0 using fuse instead of webdav
BUT 1.4.0 does not run with ubuntu default java, works fine with Java JDK10 from Oracle

@hb0

This comment has been minimized.

Copy link

hb0 commented Sep 5, 2018

Can confirm that it works with FUSE: I installed 1.4.0-beta2 via ppa (18.04/Bionic) and I encrypted 600 k files / 160 GB which would never run though even for a small part on 1.3.X and I can even open Spreadsheet files in LibreOffice directly from the Vault - which stopped working on 1.3.X with 18.04. I've OpenJDK version "10.0.2" installed.

This is a beta version! Make backups and don't use this version for production data.

How bad is it? I made a backup but would use this now for production as I guess this is only a "to be sure" warning? :-)

Great work !! 👍

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment