Console Output
Skipping 33,800 KB..
Full Log at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:791)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:913)
at java.base/java.lang.Thread.run(Thread.java:829)
04:25:03.671 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - consumeAll [e=java.io.IOException: Unconsumed content] AsyncContentProducer@47c130d3[r=null,t=null,i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1568}]
04:25:03.672 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failing all content with java.io.IOException: Unconsumed content HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1568}
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent has no content, parsing and filling
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=END}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1570} parse null
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@50eab9b9[p=0,l=0,c=0,r=0]={<<<>>>}
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> CONTENT_END
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - onContentComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1570}
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT_END --> END
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - onRequestComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1570}
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - received EOF, content = null
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=4/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=END,0 of -1},g=HttpGenerator@c2738ad{s=END}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1570} parsed false HttpParser{s=END,0 of -1}
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent produced EofContent
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failed all content, EOF = true
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - failed all content of http channel EOF=true AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1570}]
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isFinished=true HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:03.674 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - isError = false AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=12,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/content,age=1571}]
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isError=false HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - recycle HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,0 of -1}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - !handle TERMINATED HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} parse DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:03.675 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=5/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:03.676 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@6a480888{AC.ReadCB@61c95062{HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}}}
04:25:03.676 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:03.730 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0 on ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=0
04:25:03.730 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=1
04:25:03.731 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=55/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null
04:25:03.731 [qtp1367504650-35 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=2/2,p=0}@8bcf3bf waiting
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken with none selected
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken up from select, 0/0/1 selected
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d processing 0 keys, 1 updates
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=55/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:03.731 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
04:25:03.732 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d waiting with 1 keys
04:25:03.742 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - ******** Comparing files:
04:25:03.744 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> path is: /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/
04:25:03.744 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> exp is: test-classes/expected-results/audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-content.mp3.txt
04:25:03.744 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> act is: test-results/audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-content.mp3.txt
04:25:05.549 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST PASSED in 3506 msec
04:25:05.549 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest -
04:25:05.573 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - *********************************************************************************************************************************************
04:25:05.573 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST "must serve REST resource"
04:25:05.573 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - ARGS [rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart, audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-coverart.jpg.txt]
04:25:05.573 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - *********************************************************************************************************************************************
04:25:05.630 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken up from select, 1/1/1 selected
04:25:05.630 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d processing 1 keys, 0 updates
04:25:05.630 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 channel=java.nio.channels.SocketChannel[connected local=/10.244.2.50:46773 remote=/10.244.2.50:50984], selector=sun.nio.ch.EPollSelectorImpl@7c5643d, interestOps=1, readyOps=1 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=1954/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.630 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=1954/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.630 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - task SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=1954/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
04:25:05.630 [qtp1367504650-37 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=2/2,p=0} tryExecute EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/PRODUCING/p=false/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=2/2,p=0}][pc=0,pic=0,pec=1,epc=10]@2024-12-01T04:25:05.630814+01:00
04:25:05.630 [qtp1367504650-37 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=1/2,p=0}@8bcf3bf offer EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/PRODUCING/p=false/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=10]@2024-12-01T04:25:05.630981+01:00
04:25:05.631 [qtp1367504650-37 ] DEBUG o.e.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=10]@2024-12-01T04:25:05.631075+01:00 m=EXECUTE_PRODUCE_CONSUME t=SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=1955/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
04:25:05.631 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@6a480888{AC.ReadCB@61c95062{HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=1955/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0}}}
04:25:05.631 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1955/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null
04:25:05.631 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 247 DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.631 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} filled 247 DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=12,c=false/false,a=IDLE,uri=null,age=0} parse DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - START --> SPACE1
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - SPACE1 --> URI
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - URI --> SPACE2
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - SPACE2 --> REQUEST_VERSION
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - REQUEST_VERSION --> HEADER
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Accept: application/octet-stream, application/json, application/*+json, */* --> IN_VALUE
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Accept: application/octet-stream, application/json, application/*+json, */* --> FIELD
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:User-Agent: Java/11.0.25 --> IN_VALUE
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:User-Agent: Java/11.0.25 --> FIELD
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Host: 10.244.2.50:46773 --> IN_VALUE
04:25:05.632 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Host: 10.244.2.50:46773 --> FIELD
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Connection: keep-alive --> IN_VALUE
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Connection: keep-alive --> FIELD
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER --> CONTENT
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpInput - reopen HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.BlockingContentProducer - recycling org.eclipse.jetty.server.BlockingContentProducer@593ebdbf
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - recycling AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}]
04:25:05.633 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - REQUEST for /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=0}
GET /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart HTTP/1.1
Accept: application/octet-stream, application/json, application/*+json, */*
User-Agent: Java/11.0.25
Host: 10.244.2.50:46773
Connection: keep-alive
04:25:05.633 [qtp1367504650-35 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=1/2,p=0}@8bcf3bf task=EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:05.633912+01:00
04:25:05.634 [qtp1367504650-35 ] DEBUG o.e.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:05.634251+01:00 tryProduce true
04:25:05.634 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
04:25:05.634 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
04:25:05.634 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=1}
04:25:05.634 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d waiting with 1 keys
04:25:05.634 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=1} parsed true HttpParser{s=CONTENT,0 of -1}
04:25:05.634 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=3/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=1}
04:25:05.634 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - handle http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=1}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - handling HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - action DISPATCH HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=2}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.Server - REQUEST GET /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart ?null on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=2}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.handler.ContextHandler - scope null||/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart @ o.e.j.s.ServletContextHandler@59dcbc9a{/,[file:///var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/classes/webapp/],AVAILABLE}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.handler.ContextHandler - context=||/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart @ o.e.j.s.ServletContextHandler@59dcbc9a{/,[file:///var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/classes/webapp/],AVAILABLE}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - servlet |/rest|/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart|ServletPathMapping{matchValue=rest, pattern=/rest/*, servletName=spring, mappingMatch=PATH, servletPath=/rest, pathInfo=/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart} -> spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null}
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - ServletHandler@4151c186{STARTED} cached filter chain for REQUEST: Chain@37d9d615(it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter-560b67b4==it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter@560b67b4{inst=true,async=true,src=EMBEDDED:null})->ChainEnd@7ad7a3bb(spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null})
04:25:05.635 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - chain=Chain@37d9d615(it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter-560b67b4==it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter@560b67b4{inst=true,async=true,src=EMBEDDED:null})->ChainEnd@7ad7a3bb(spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null})
04:25:05.635 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request: GET /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart HTTP/1.1
04:25:05.635 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Accept = application/octet-stream, application/json, application/*+json, */*
04:25:05.635 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: User-Agent = Java/11.0.25
04:25:05.635 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Connection = keep-alive
04:25:05.635 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Host = 10.244.2.50:46773
04:25:05.638 [qtp1367504650-37 ] INFO i.t.bluemarine2.rest.impl.MusicResourcesController - getAudioFileCoverArt(urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=)
04:25:05.653 [qtp1367504650-37 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - query() - interface it.tidalwave.bluemarine2.model.audio.AudioFile
04:25:05.653 [qtp1367504650-37 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query: PREFIX foaf: <http://xmlns.com/foaf/0.1/> PREFIX rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#> PREFIX rel: <http://purl.org/vocab/relationship/> PREFIX bmmo: <http://bluemarine.tidalwave.it/2015/04/mo/> PREFIX mo: <http://purl.org/ontology/mo/> PREFIX vocab: <http://dbtune.org/musicbrainz/resource/vocab/> PREFIX xs: <http://www.w3.org/2001/XMLSchema#> SELECT DISTINCT * WHERE { ?audioFile a mo:AudioFile; bmmo:path ?path; mo:encodes ?signal; OPTIONAL { ?audioFile bmmo:fileSize ?fileSize. } ?signal a mo:DigitalSignal; mo:duration ?duration. } ORDER BY ?path
04:25:05.654 [qtp1367504650-37 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query parameters: [audioFile, urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=, source, "embedded"]
04:25:05.655 [qtp1367504650-37 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query returned 1 entities in 1.664324 msec
04:25:05.655 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.MusicResourcesController - >>>> audioFile: Optional[RepositoryAudioFileEntity(Música Antigua & Eduardo Paniagua/La Vida de María - Cantigas de las Fiestas de Santa María/1-01 Estrella Del Dia.mp3, urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=)]
04:25:05.692 [qtp1367504650-37 ] DEBUG i.t.b.model.impl.catalog.RepositoryAudioFile - >>>> loading fallback metadata from: /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Música Antigua & Eduardo Paniagua/La Vida de María - Cantigas de las Fiestas de Santa María/1-01 Estrella Del Dia.mp3
04:25:05.693 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.model.impl.AudioMetadataFactory - path: /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Música Antigua & Eduardo Paniagua/La Vida de María - Cantigas de las Fiestas de Santa María/1-01 Estrella Del Dia.mp3
Dec 01, 2024 4:25:05 AM org.jaudiotagger.audio.mp3.MP3File readV2Tag
SEVERE: Could not invoke DirectBuffer method - illegal access
04:25:05.723 [qtp1367504650-37 ] TRACE i.t.bluemarine2.model.impl.AudioMetadataFactory - >>>> loaded keys for /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Música Antigua & Eduardo Paniagua/La Vida de María - Cantigas de las Fiestas de Santa María/1-01 Estrella Del Dia.mp3: [Key(name=mp3.album, type=class java.lang.String), Key(name=tag.GENRE, type=interface java.util.List), Key(name=tag.ENCODER, type=interface java.util.List), Key(name=tag.TRACK_TOTAL, type=interface java.util.List), Key(name=mp3.diskCount, type=class java.lang.Integer), Key(name=tag.ALBUM_ARTIST, type=interface java.util.List), Key(name=tag.YEAR, type=interface java.util.List), Key(name=mp3.artwork, type=interface java.util.List), Key(name=mp3.encodingType, type=class java.lang.String), Key(name=mp3.title, type=class java.lang.String), Key(name=tag.COVER_ART, type=interface java.util.List), Key(name=mp3.bitRate, type=class java.lang.Integer), Key(name=mp3.composer, type=class java.lang.String), Key(name=mp3.sampleRate, type=class java.lang.Integer), Key(name=mp3.duration, type=class java.time.Duration), Key(name=mp3.bitsPerSample, type=class java.lang.Integer), Key(name=mp3.format, type=class java.lang.String), Key(name=mp3.comment, type=interface java.util.List), Key(name=mp3.diskNumber, type=class java.lang.Integer), Key(name=mp3.artist, type=class java.lang.String), Key(name=mp3.trackNumber, type=class java.lang.Integer), Key(name=file.size, type=class java.lang.Long)]
04:25:05.742 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpOutput - write(array HeapByteBuffer@4e96c15b[p=0,l=398111,c=398111,r=398111]={<<<\xFf\xD8\xFf\xE0\x00\x10JFIF\x00\x01\x01\x01\x01,\x01,\x00\x00\xFf\xDb\x00C\x00...Y\xD8\rf\x9d\x96\xF9\x18\xFa\xF2\x9d|P\xBe\xC9\xE2\xFeES\xBe,\xFf\xD9>>>})
04:25:05.742 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpOutput - write(array) s=CLOSING,api=BLOCKED,sc=false,e=null last=true agg=false flush=true async=false, len=398111 null
04:25:05.742 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=0,l=32768,c=398111,r=32768]={<<<\xFf\xD8\xFf\xE0\x00\x10JFIF\x00\x01\x01\x01\x01,\x01,\x00\x00\xFf\xDb\x00C\x00...\x8f\x93\x8d\x99\x89\xAbb\xC6d\xF2x{@\xE4\x0f\x9f\x84\xB8e\x1a\xE0\xC2\xFf>>>\x00%\xCd/[~\xAb3\x06...\xFeES\xBe,\xFf\xD9} complete=false committing=true callback=Blocker@5fa429c5{null}
04:25:05.742 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=109}
200 null HTTP/1.1
Date: Sun, 01 Dec 2024 03:25:05 GMT
Content-Type: image/jpeg
Content-Length: 398111
Content-Disposition: filename="coverart.jpg"; filename*=utf-8''coverart.jpg
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: NEED_HEADER for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=200,h=4,cl=398111},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@59ba4398] (null,[p=0,l=32768,c=398111,r=32768],false)@START
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=200,h=4,cl=398111} last=false content=HeapByteBuffer@67be1464[p=0,l=32768,c=398111,r=32768]={<<<\xFf\xD8\xFf\xE0\x00\x10JFIF\x00\x01\x01\x01\x01,\x01,\x00\x00\xFf\xDb\x00C\x00...\x8f\x93\x8d\x99\x89\xAbb\xC6d\xF2x{@\xE4\x0f\x9f\x84\xB8e\x1a\xE0\xC2\xFf>>>\x00%\xCd/[~\xAb3\x06...\xFeES\xBe,\xFf\xD9}
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpGenerator - Date: Sun, 01 Dec 2024 03:25:05 GMT
Content-Type: image/jpeg
Content-Length: 398111
Content-Disposition: filename="coverart.jpg"; filename*=utf-8''coverart.jpg
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=200,h=4,cl=398111},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@59ba4398] ([p=0,l=206,c=8192,r=206],[p=0,l=32768,c=398111,r=32768],false)@COMMITTED
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [DirectByteBuffer@4d1c3740[p=0,l=206,c=8192,r=206]={<<<HTTP/1.1 200 OK\r\nDate: Su...rver: Jetty(10.0.1)\r\n\r\n>>>44.2.50:4...\x00\x00\x00\x00\x00\x00\x00},HeapByteBuffer@67be1464[p=0,l=32768,c=398111,r=32768]={<<<\xFf\xD8\xFf\xE0\x00\x10JFIF\x00\x01\x01\x01\x01,\x01,\x00\x00\xFf\xDb\x00C\x00...\x8f\x93\x8d\x99\x89\xAbb\xC6d\xF2x{@\xE4\x0f\x9f\x84\xB8e\x1a\xE0\xC2\xFf>>>\x00%\xCd/[~\xAb3\x06...\xFeES\xBe,\xFf\xD9}]
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.743 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32974 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=111/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=110}
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32974 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=200,h=4,cl=398111},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@59ba4398] ([p=206,l=206,c=8192,r=0],[p=32768,l=32768,c=398111,r=0],false)@COMMITTED
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=32768,l=65536,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x84\xB8e\x1a\xE0\xC2\xFf<<<\x00%\xCd/[~\xAb3\x066$\x8c\x89\xBc\xC9#\xC7\x03\xE0{\x95\\\xF1('..."\xF1Uh1\xA6h\x0f\x97l\xD9m\xDa\xCe\xA1\xA4rW?\xC8\xF3\x14~>>>0\xEc\xDb\x87\xC7\xF7#B\xC7...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@4e6a8cb5] (null,[p=32768,l=65536,c=398111,r=32768],false)@COMMITTED
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=32768,l=65536,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x84\xB8e\x1a\xE0\xC2\xFf<<<\x00%\xCd/[~\xAb3\x066$\x8c\x89\xBc\xC9#\xC7\x03\xE0{\x95\\\xF1('..."\xF1Uh1\xA6h\x0f\x97l\xD9m\xDa\xCe\xA1\xA4rW?\xC8\xF3\x14~>>>0\xEc\xDb\x87\xC7\xF7#B\xC7...\xFeES\xBe,\xFf\xD9}]
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.748 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=120}
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@4e6a8cb5] (null,[p=65536,l=65536,c=398111,r=0],false)@COMMITTED
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=65536,l=98304,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...rW?\xC8\xF3\x14~<<<0\xEc\xDb\x87\xC7\xF7#B\xC7G\x0ca\x8d \x01\xD1r\xAaSv\xCd\x9d\r\x157....b\xD7_\x1dx]t\xEdZ0J<Y6,\xE2\x0c\x86\x96\x92\x1bj>>>IZ*\x9a\r\xC98tE...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@368e103a] (null,[p=65536,l=98304,c=398111,r=32768],false)@COMMITTED
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=65536,l=98304,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...rW?\xC8\xF3\x14~<<<0\xEc\xDb\x87\xC7\xF7#B\xC7G\x0ca\x8d \x01\xD1r\xAaSv\xCd\x9d\r\x157....b\xD7_\x1dx]t\xEdZ0J<Y6,\xE2\x0c\x86\x96\x92\x1bj>>>IZ*\x9a\r\xC98tE...\xFeES\xBe,\xFf\xD9}]
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=121}
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@368e103a] (null,[p=98304,l=98304,c=398111,r=0],false)@COMMITTED
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=98304,l=131072,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\xE2\x0c\x86\x96\x92\x1bj<<<IZ*\x9a\r\xC98tE\xEd\xE4\x91\xD5W\xEfe<v\x05\xF3\x0e\xEb\xAf\xCe\x95...\xBa\x15\x8f;\xD9\xA7\x08X\xBb\x9a+?\xA2\xE0v[\x9e\x1ev\xF4\xA5Z\x7f>>>"\xF8\xAbFsK\xCd\x96\x1d...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@21042fbb] (null,[p=98304,l=131072,c=398111,r=32768],false)@COMMITTED
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=98304,l=131072,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\xE2\x0c\x86\x96\x92\x1bj<<<IZ*\x9a\r\xC98tE\xEd\xE4\x91\xD5W\xEfe<v\x05\xF3\x0e\xEb\xAf\xCe\x95...\xBa\x15\x8f;\xD9\xA7\x08X\xBb\x9a+?\xA2\xE0v[\x9e\x1ev\xF4\xA5Z\x7f>>>"\xF8\xAbFsK\xCd\x96\x1d...\xFeES\xBe,\xFf\xD9}]
04:25:05.754 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.756 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=124}
04:25:05.757 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.757 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.757 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@21042fbb] (null,[p=131072,l=131072,c=398111,r=0],false)@COMMITTED
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=131072,l=163840,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x9e\x1ev\xF4\xA5Z\x7f<<<"\xF8\xAbFsK\xCd\x96\x1du\xAck\x8e\xC9\x1d\xB4\x8f\x85\xD7Z\x85\x1c\xC9\x7f\xA9...\xB8\xB0\xCb\x1d\xB6"\xD0@#\xF7\xFb\xFf\x00\xEa\xB1\xC9|\xAc\xD4\x95h\xE1\x01>>>\x922\x1cl\xBb\x85\x1c\xE9\xDa...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@2a290e80] (null,[p=131072,l=163840,c=398111,r=32768],false)@COMMITTED
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=131072,l=163840,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x9e\x1ev\xF4\xA5Z\x7f<<<"\xF8\xAbFsK\xCd\x96\x1du\xAck\x8e\xC9\x1d\xB4\x8f\x85\xD7Z\x85\x1c\xC9\x7f\xA9...\xB8\xB0\xCb\x1d\xB6"\xD0@#\xF7\xFb\xFf\x00\xEa\xB1\xC9|\xAc\xD4\x95h\xE1\x01>>>\x922\x1cl\xBb\x85\x1c\xE9\xDa...\xFeES\xBe,\xFf\xD9}]
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=6/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=130}
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.763 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@2a290e80] (null,[p=163840,l=163840,c=398111,r=0],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=163840,l=196608,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...|\xAc\xD4\x95h\xE1\x01<<<\x922\x1cl\xBb\x85\x1c\xE9\xDa#\xA39\xA8c\x8c\x1c\xB0\x18~\xB4\xBaX\xA5\xF5!...z\xA6\x83\x9eY]\xF4,\xA3\xC1\x11\xEa\x1a|\x1a\xA6\x10\x926\xEd\x92\xB7p>>>\x16\x9ej;F~\r\xBa\x91...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@30bc3054] (null,[p=163840,l=196608,c=398111,r=32768],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=163840,l=196608,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...|\xAc\xD4\x95h\xE1\x01<<<\x922\x1cl\xBb\x85\x1c\xE9\xDa#\xA39\xA8c\x8c\x1c\xB0\x18~\xB4\xBaX\xA5\xF5!...z\xA6\x83\x9eY]\xF4,\xA3\xC1\x11\xEa\x1a|\x1a\xA6\x10\x926\xEd\x92\xB7p>>>\x16\x9ej;F~\r\xBa\x91...\xFeES\xBe,\xFf\xD9}]
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=131}
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@30bc3054] (null,[p=196608,l=196608,c=398111,r=0],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=196608,l=229376,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x10\x926\xEd\x92\xB7p<<<\x16\x9ej;F~\r\xBa\x91\x91|\x13\xE1M\xDd\x85\xBd\x08?\xEe\xAf\x8c\xD4\x95\xA2...A\xFf\x00\x82g\xBe\xD0\xBc\xE6D\xB9\xB3\xAd\x8f\xA4\x861\xDb\x0b\x9az\x9e\xFf>>>\x00\xBf\xDf\xFb\xD8\xD5\xAd\x0f\xB3...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@7675f9b9] (null,[p=196608,l=229376,c=398111,r=32768],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=196608,l=229376,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x10\x926\xEd\x92\xB7p<<<\x16\x9ej;F~\r\xBa\x91\x91|\x13\xE1M\xDd\x85\xBd\x08?\xEe\xAf\x8c\xD4\x95\xA2...A\xFf\x00\x82g\xBe\xD0\xBc\xE6D\xB9\xB3\xAd\x8f\xA4\x861\xDb\x0b\x9az\x9e\xFf>>>\x00\xBf\xDf\xFb\xD8\xD5\xAd\x0f\xB3...\xFeES\xBe,\xFf\xD9}]
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=131}
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@7675f9b9] (null,[p=229376,l=229376,c=398111,r=0],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=229376,l=262144,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...1\xDb\x0b\x9az\x9e\xFf<<<\x00\xBf\xDf\xFb\xD8\xD5\xAd\x0f\xB35\xE2\x06\x0f\xB7\x0b\x03\xF0\xAe\xAf\x86\xEf\x19\xCe\xF2\x92...\xDb@x\x0f\xEf\xEc\xB3\xCa\xD6\x86_\xC9\x94\xD5\r\xE6>\xE9vp\xFd\x88\xE6>>>\xE7\xFb\xF6\x11\xD0\xAc\xB9\xCd\x1f...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@760c693b] (null,[p=229376,l=262144,c=398111,r=32768],false)@COMMITTED
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=229376,l=262144,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...1\xDb\x0b\x9az\x9e\xFf<<<\x00\xBf\xDf\xFb\xD8\xD5\xAd\x0f\xB35\xE2\x06\x0f\xB7\x0b\x03\xF0\xAe\xAf\x86\xEf\x19\xCe\xF2\x92...\xDb@x\x0f\xEf\xEc\xB3\xCa\xD6\x86_\xC9\x94\xD5\r\xE6>\xE9vp\xFd\x88\xE6>>>\xE7\xFb\xF6\x11\xD0\xAc\xB9\xCd\x1f...\xFeES\xBe,\xFf\xD9}]
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.764 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=131}
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@760c693b] (null,[p=262144,l=262144,c=398111,r=0],false)@COMMITTED
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=262144,l=294912,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...>\xE9vp\xFd\x88\xE6<<<\xE7\xFb\xF6\x11\xD0\xAc\xB9\xCd\x1f\xDb\x95V}\xA0@6i\xB1\x9eyXvl\x82\xB6...L\x8e=\xB0=\xC0r:\x05T\xD3z`\x8c\xB9-\x0c\xCd\xD4>\xCa\xE6\xB0>>>\xFf\x00?\x16\x9b\x0e\x1ej\xC5...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@14b32474] (null,[p=262144,l=294912,c=398111,r=32768],false)@COMMITTED
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=262144,l=294912,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...>\xE9vp\xFd\x88\xE6<<<\xE7\xFb\xF6\x11\xD0\xAc\xB9\xCd\x1f\xDb\x95V}\xA0@6i\xB1\x9eyXvl\x82\xB6...L\x8e=\xB0=\xC0r:\x05T\xD3z`\x8c\xB9-\x0c\xCd\xD4>\xCa\xE6\xB0>>>\xFf\x00?\x16\x9b\x0e\x1ej\xC5...\xFeES\xBe,\xFf\xD9}]
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=132}
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@14b32474] (null,[p=294912,l=294912,c=398111,r=0],false)@COMMITTED
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=294912,l=327680,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x0c\xCd\xD4>\xCa\xE6\xB0<<<\xFf\x00?\x16\x9b\x0e\x1ej\xC5\x9b\xA37\xA8jy/.\xC62\x13\x116\x02\xEc\xC5...\xC2\xD9\xD1H\xC6\xE2\xE4\x8c\xF8\xB2%4\xA5\xD0P\x01\x063\x03x\x0b\x9f%>>>\xCan\xCd\xCc\xA7\x9cKE\x86...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.765 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@455c8826] (null,[p=294912,l=327680,c=398111,r=32768],false)@COMMITTED
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=294912,l=327680,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x0c\xCd\xD4>\xCa\xE6\xB0<<<\xFf\x00?\x16\x9b\x0e\x1ej\xC5\x9b\xA37\xA8jy/.\xC62\x13\x116\x02\xEc\xC5...\xC2\xD9\xD1H\xC6\xE2\xE4\x8c\xF8\xB2%4\xA5\xD0P\x01\x063\x03x\x0b\x9f%>>>\xCan\xCd\xCc\xA7\x9cKE\x86...\xFeES\xBe,\xFf\xD9}]
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=133}
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@455c8826] (null,[p=327680,l=327680,c=398111,r=0],false)@COMMITTED
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=327680,l=360448,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x063\x03x\x0b\x9f%<<<\xCan\xCd\xCc\xA7\x9cKE\x86\x074\xF7V\xE1I\xE8I9-\xA14\x16yM\x9e...\xC2\x0c\xD2\xDd\xBbs\xC74\xAbsT\x0b\xA2lm(:M\xCf\x1e\x9bCr>>>A\xFa\x8a$y\xB1F\xC2Z...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@1cbd1905] (null,[p=327680,l=360448,c=398111,r=32768],false)@COMMITTED
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=327680,l=360448,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...\x063\x03x\x0b\x9f%<<<\xCan\xCd\xCc\xA7\x9cKE\x86\x074\xF7V\xE1I\xE8I9-\xA14\x16yM\x9e...\xC2\x0c\xD2\xDd\xBbs\xC74\xAbsT\x0b\xA2lm(:M\xCf\x1e\x9bCr>>>A\xFa\x8a$y\xB1F\xC2Z...\xFeES\xBe,\xFf\xD9}]
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=133}
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@1cbd1905] (null,[p=360448,l=360448,c=398111,r=0],false)@COMMITTED
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=360448,l=393216,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...:M\xCf\x1e\x9bCr<<<A\xFa\x8a$y\xB1F\xC2Z\x00\xB3\xDb\xD9\x08\xA6\x81\xF59\x0e\xD3\xF4o4\x87\xBf...\xE45\xFb?\t\xBeBx\xE7\x87\xB0K\x03\xA2l\xBcI\xA4\xC9/\x1d(\x01>>>\xC2_\xAf\x17\xA0G\x06\x89\xDd...\xFeES\xBe,\xFf\xD9} complete=false committing=false callback=Blocker@5fa429c5{null}
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@96dfe18] (null,[p=360448,l=393216,c=398111,r=32768],false)@COMMITTED
04:25:05.766 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=360448,l=393216,c=398111,r=32768]={\xFf\xD8\xFf\xE0\x00\x10JFI...:M\xCf\x1e\x9bCr<<<A\xFa\x8a$y\xB1F\xC2Z\x00\xB3\xDb\xD9\x08\xA6\x81\xF59\x0e\xD3\xF4o4\x87\xBf...\xE45\xFb?\t\xBeBx\xE7\x87\xB0K\x03\xA2l\xBcI\xA4\xC9/\x1d(\x01>>>\xC2_\xAf\x17\xA0G\x06\x89\xDd...\xFeES\xBe,\xFf\xD9}]
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 32768 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=14/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMMITTED}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=148}
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=32768 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@96dfe18] (null,[p=393216,l=393216,c=398111,r=0],false)@COMMITTED
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=HeapByteBuffer@67be1464[p=393216,l=398111,c=398111,r=4895]={\xFf\xD8\xFf\xE0\x00\x10JFI...I\xA4\xC9/\x1d(\x01<<<\xC2_\xAf\x17\xA0G\x06\x89\xDd\x83$\xF0\x86\xB9\xBc\xB5$\xBc\x84\x98#\x85"\x9f\xF0...Y\xD8\rf\x9d\x96\xF9\x18\xFa\xF2\x9d|P\xBe\xC9\xE2\xFeES\xBe,\xFf\xD9>>>} complete=true committing=false callback=Blocker@5fa429c5{null}
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@470ebc5] (null,[p=393216,l=398111,c=398111,r=4895],true)@COMPLETING
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [HeapByteBuffer@67be1464[p=393216,l=398111,c=398111,r=4895]={\xFf\xD8\xFf\xE0\x00\x10JFI...I\xA4\xC9/\x1d(\x01<<<\xC2_\xAf\x17\xA0G\x06\x89\xDd\x83$\xF0\x86\xB9\xBc\xB5$\xBc\x84\x98#\x85"\x9f\xF0...Y\xD8\rf\x9d\x96\xF9\x18\xFa\xF2\x9d|P\xBe\xC9\xE2\xFeES\xBe,\xFf\xD9>>>}]
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 4895 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=COMPLETING}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMMITTED is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=148}
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=4895 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$SendCallback@470ebc5] (null,[p=398111,l=398111,c=398111,r=0],true)@END
04:25:05.781 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpOutput - onWriteComplete(true,null) s=CLOSING,api=BLOCKED,sc=false,e=null->s=CLOSED,api=BLOCKING,sc=false,e=null c=null cb=null w=false
04:25:05.782 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response 200 OK
04:25:05.782 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response header: Content-Disposition = [filename="coverart.jpg"; filename*=utf-8''coverart.jpg]
04:25:05.782 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response header: Content-Length = [398111]
04:25:05.782 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response header: Date = [Sun, 01 Dec 2024 03:25:05 GMT]
04:25:05.782 [qtp1367504650-37 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response header: Content-Type = [image/jpeg]
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMPLETED is=IDLE awp=false se=false i=true al=0},r=13,c=false/true,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=COMPLETED is=IDLE awp=false se=false i=true al=0}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - nextAction(false) COMPLETE HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - action COMPLETE HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=false/true,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpOutput - complete(org.eclipse.jetty.util.Callback$3@254c5ed5) s=CLOSED,api=BLOCKING,sc=false,e=null s=true e=null, c=null
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - completed HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - nextAction(false) TERMINATED HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - action TERMINATED HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - onCompleted for /rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart written=398111
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpInput - consumeAll HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=false
java.io.IOException: Unconsumed content
at org.eclipse.jetty.server.HttpInput.consumeAll(HttpInput.java:153)
at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:434)
at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:865)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:379)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:284)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100)
at org.eclipse.jetty.io.SocketChannelEndPoint$1.run(SocketChannelEndPoint.java:101)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:370)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:791)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:913)
at java.base/java.lang.Thread.run(Thread.java:829)
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - consumeAll [e=java.io.IOException: Unconsumed content] AsyncContentProducer@47c130d3[r=null,t=null,i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}]
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failing all content with java.io.IOException: Unconsumed content HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent has no content, parsing and filling
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=END}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149} parse null
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@50eab9b9[p=0,l=0,c=0,r=0]={<<<>>>}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> CONTENT_END
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - onContentComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT_END --> END
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - onRequestComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=149}
04:25:05.782 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - received EOF, content = null
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=END,0 of -1},g=HttpGenerator@c2738ad{s=END}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=150} parsed false HttpParser{s=END,0 of -1}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent produced EofContent
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failed all content, EOF = true
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - failed all content of http channel EOF=true AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=150}]
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpInput - isFinished=true HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - isError = false AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=13,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:Nmd7Bm3DQ922WhPkJn5YD_i_eK4=/coverart,age=150}]
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpInput - isError=false HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpInput - recycle HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,0 of -1}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpChannel - !handle TERMINATED HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.783 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} parse DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.785 [main ] TRACE it.tidalwave.bluemarine2.rest.ResponseEntityIo - store(target/test-results/audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-coverart.jpg.txt, ..., ...)
04:25:05.786 [main ] INFO it.tidalwave.bluemarine2.rest.ResponseEntityIo - >>>> TYPE class [B
04:25:05.787 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - ******** Comparing files:
04:25:05.787 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> path is: /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/
04:25:05.787 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> exp is: test-classes/expected-results/audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-coverart.jpg.txt
04:25:05.787 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> act is: test-results/audiofile-Nmd7Bm3DQ922WhPkJn5YD_i_eK4=-coverart.jpg.txt
04:25:05.801 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:05.801 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=19/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
04:25:05.801 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=19/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.801 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=20/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.801 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@6a480888{AC.ReadCB@61c95062{HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}}}
04:25:05.802 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.802 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0 on ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=0
04:25:05.802 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=1
04:25:05.802 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null
04:25:05.802 [qtp1367504650-37 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=2/2,p=0}@34934d45 waiting
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken with none selected
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken up from select, 0/0/1 selected
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d processing 0 keys, 1 updates
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0
04:25:05.802 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:05.803 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
04:25:05.803 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d waiting with 1 keys
04:25:05.997 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST PASSED in 424 msec
04:25:05.997 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest -
04:25:06.006 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - *********************************************************************************************************************************************
04:25:06.006 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST "must serve REST resource"
04:25:06.006 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - ARGS [rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart, audiofile-5lCKAUoE3IfmgttCE3a5U23gxQg=-coverart.jpg.txt]
04:25:06.006 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - *********************************************************************************************************************************************
04:25:06.036 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken up from select, 1/1/1 selected
04:25:06.036 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d processing 1 keys, 0 updates
04:25:06.037 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - selected 1 channel=java.nio.channels.SocketChannel[connected local=/10.244.2.50:46773 remote=/10.244.2.50:50984], selector=sun.nio.ch.EPollSelectorImpl@7c5643d, interestOps=1, readyOps=1 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=235/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.037 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - onSelected 1->0 r=true w=false for SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=235/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.037 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - task SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=235/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
04:25:06.037 [qtp1367504650-35 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=2/2,p=0} tryExecute EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/PRODUCING/p=false/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=2/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:06.037445+01:00
04:25:06.037 [qtp1367504650-35 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=1/2,p=0}@34934d45 offer EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/PRODUCING/p=false/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:06.037621+01:00
04:25:06.037 [qtp1367504650-35 ] DEBUG o.e.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:06.037775+01:00 m=EXECUTE_PRODUCE_CONSUME t=SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=235/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
04:25:06.037 [qtp1367504650-37 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=1/2,p=0}@34934d45 task=EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=11]@2024-12-01T04:25:06.037892+01:00
04:25:06.037 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.FillInterest - fillable FillInterest@6a480888{AC.ReadCB@61c95062{HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=236/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}}}
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=236/30000}{io=1/0,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null
04:25:06.038 [qtp1367504650-37 ] DEBUG o.e.jetty.util.thread.strategy.EatWhatYouKill - EatWhatYouKill@3daefac5/SelectorProducer@5df97bcf/IDLE/p=true/QueuedThreadPool[qtp1367504650]@5182770a{STARTED,8<=8<=200,i=4,r=2,q=0}[ReservedThreadExecutor@428b5372{s=1/2,p=0}][pc=0,pic=0,pec=1,epc=12]@2024-12-01T04:25:06.038089+01:00 tryProduce true
04:25:06.038 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 0
04:25:06.038 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 247 DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} filled 247 DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.038 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - Key interests updated 1 -> 0 on SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=13,c=false/false,a=IDLE,uri=null,age=0} parse DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.038 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d waiting with 1 keys
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@4d1c3740[p=0,l=247,c=8192,r=247]={<<<GET /rest/audiofile/urn:b...nection: keep-alive\r\n\r\n>>>\nServer: ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - START --> SPACE1
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - SPACE1 --> URI
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - URI --> SPACE2
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - SPACE2 --> REQUEST_VERSION
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - REQUEST_VERSION --> HEADER
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Accept: application/octet-stream, application/json, application/*+json, */* --> IN_VALUE
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Accept: application/octet-stream, application/json, application/*+json, */* --> FIELD
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:User-Agent: Java/11.0.25 --> IN_VALUE
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:User-Agent: Java/11.0.25 --> FIELD
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Host: 10.244.2.50:46773 --> IN_VALUE
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Host: 10.244.2.50:46773 --> FIELD
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Connection: keep-alive --> IN_VALUE
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER:Connection: keep-alive --> FIELD
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - HEADER --> CONTENT
04:25:06.038 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - reopen HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.BlockingContentProducer - recycling org.eclipse.jetty.server.BlockingContentProducer@593ebdbf
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - recycling AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=1}]
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - REQUEST for /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1}
GET /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart HTTP/1.1
Accept: application/octet-stream, application/json, application/*+json, */*
User-Agent: Java/11.0.25
Host: 10.244.2.50:46773
Connection: keep-alive
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1} parsed true HttpParser{s=CONTENT,0 of -1}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - handle http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - handling HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - action DISPATCH HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.Server - REQUEST GET /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart ?null on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=1}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.handler.ContextHandler - scope null||/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart @ o.e.j.s.ServletContextHandler@59dcbc9a{/,[file:///var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/classes/webapp/],AVAILABLE}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.handler.ContextHandler - context=||/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart @ o.e.j.s.ServletContextHandler@59dcbc9a{/,[file:///var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/classes/webapp/],AVAILABLE}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - servlet |/rest|/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart|ServletPathMapping{matchValue=rest, pattern=/rest/*, servletName=spring, mappingMatch=PATH, servletPath=/rest, pathInfo=/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart} -> spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null}
04:25:06.039 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - ServletHandler@4151c186{STARTED} cached filter chain for REQUEST: Chain@57dccd12(it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter-560b67b4==it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter@560b67b4{inst=true,async=true,src=EMBEDDED:null})->ChainEnd@5f6ab8af(spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null})
04:25:06.040 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.servlet.ServletHandler - chain=Chain@57dccd12(it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter-560b67b4==it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter@560b67b4{inst=true,async=true,src=EMBEDDED:null})->ChainEnd@5f6ab8af(spring==org.springframework.web.servlet.DispatcherServlet@ca9d020d{jsp=null,order=-1,inst=true,async=true,src=EMBEDDED:null})
04:25:06.040 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request: GET /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart HTTP/1.1
04:25:06.040 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Accept = application/octet-stream, application/json, application/*+json, */*
04:25:06.040 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: User-Agent = Java/11.0.25
04:25:06.040 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Connection = keep-alive
04:25:06.040 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> request header: Host = 10.244.2.50:46773
04:25:06.041 [qtp1367504650-35 ] INFO i.t.bluemarine2.rest.impl.MusicResourcesController - getAudioFileCoverArt(urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=)
04:25:06.041 [qtp1367504650-35 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - query() - interface it.tidalwave.bluemarine2.model.audio.AudioFile
04:25:06.042 [qtp1367504650-35 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query: PREFIX foaf: <http://xmlns.com/foaf/0.1/> PREFIX rdf: <http://www.w3.org/1999/02/22-rdf-syntax-ns#> PREFIX rel: <http://purl.org/vocab/relationship/> PREFIX bmmo: <http://bluemarine.tidalwave.it/2015/04/mo/> PREFIX mo: <http://purl.org/ontology/mo/> PREFIX vocab: <http://dbtune.org/musicbrainz/resource/vocab/> PREFIX xs: <http://www.w3.org/2001/XMLSchema#> SELECT DISTINCT * WHERE { ?audioFile a mo:AudioFile; bmmo:path ?path; mo:encodes ?signal; OPTIONAL { ?audioFile bmmo:fileSize ?fileSize. } ?signal a mo:DigitalSignal; mo:duration ?duration. } ORDER BY ?path
04:25:06.042 [qtp1367504650-35 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query parameters: [audioFile, urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=, source, "embedded"]
04:25:06.042 [qtp1367504650-35 ] INFO i.t.b.m.i.catalog.finder.RepositoryFinderSupport - >>>> query returned 1 entities in 1.016766 msec
04:25:06.042 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.MusicResourcesController - >>>> audioFile: Optional[RepositoryAudioFileEntity(Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3, urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=)]
04:25:06.043 [qtp1367504650-35 ] DEBUG i.t.b.model.impl.catalog.RepositoryAudioFile - >>>> loading fallback metadata from: /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3
04:25:06.043 [qtp1367504650-35 ] WARN it.tidalwave.bluemarine2.util.PathNormalization - >>>> fixing failed at: /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations
04:25:06.043 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.model.impl.AudioMetadataFactory - path: /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3
Dec 01, 2024 4:25:06 AM org.jaudiotagger.audio.AudioFile checkFileExists
SEVERE: Unable to find:/var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3
04:25:06.043 [qtp1367504650-35 ] ERROR i.t.bluemarine2.model.impl.AudioMetadataFactory - While reading null --- /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3 --- /var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3
java.io.FileNotFoundException: Unable to find:/var/test-sets/MusicTestSets/iTunes-aac-fg-20170131-1/Music/Compilations/Satchmo 2/10 Stars Feel On Alabama.mp3
at org.jaudiotagger.audio.AudioFile.checkFileExists(AudioFile.java:222)
at org.jaudiotagger.audio.AudioFile.checkFilePermissions(AudioFile.java:239)
at org.jaudiotagger.audio.mp3.MP3File.<init>(MP3File.java:473)
at org.jaudiotagger.audio.mp3.MP3FileReader.read(MP3FileReader.java:39)
at it.tidalwave.bluemarine2.model.impl.AudioMetadataFactory.loadFrom(AudioMetadataFactory.java:86)
at it.tidalwave.bluemarine2.model.impl.catalog.RepositoryAudioFile.loadFallbackMetadata(RepositoryAudioFile.java:173)
at it.tidalwave.util.Memoize.get(Memoize.java:70)
at it.tidalwave.bluemarine2.model.impl.catalog.RepositoryAudioFile.lambda$new$0(RepositoryAudioFile.java:104)
at it.tidalwave.bluemarine2.model.spi.MetadataSupport.lambda$getAll$1(MetadataSupport.java:93)
at java.base/java.util.Optional.flatMap(Optional.java:294)
at it.tidalwave.bluemarine2.model.spi.MetadataSupport.getAll(MetadataSupport.java:93)
at it.tidalwave.bluemarine2.rest.impl.MusicResourcesController.lambda$getAudioFileCoverArt$5(MusicResourcesController.java:292)
at java.base/java.util.Optional.flatMap(Optional.java:294)
at it.tidalwave.bluemarine2.rest.impl.MusicResourcesController.getAudioFileCoverArt(MusicResourcesController.java:292)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:197)
at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:141)
at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:106)
at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:893)
at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:807)
at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87)
at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1061)
at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:961)
at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006)
at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:503)
at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:590)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:756)
at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1670)
at it.tidalwave.bluemarine2.rest.impl.server.LoggingFilter.doFilter(LoggingFilter.java:76)
at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:202)
at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1645)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:510)
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1356)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:176)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:467)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:174)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1278)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:129)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:122)
at org.eclipse.jetty.server.Server.handle(Server.java:562)
at org.eclipse.jetty.server.HttpChannel.lambda$handle$0(HttpChannel.java:399)
at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:656)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:391)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:284)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100)
at org.eclipse.jetty.io.SocketChannelEndPoint$1.run(SocketChannelEndPoint.java:101)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:370)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:791)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:913)
at java.base/java.lang.Thread.run(Thread.java:829)
04:25:06.044 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - sendError HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0}
04:25:06.065 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response 404 Not Found
04:25:06.065 [qtp1367504650-35 ] DEBUG i.t.bluemarine2.rest.impl.server.LoggingFilter - >>>> response header: Date = [Sun, 01 Dec 2024 03:25:06 GMT]
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.Server - handled=true async=false committed=true on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=true i=true al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=27}
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=true i=true al=0}
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - nextAction(false) SEND_ERROR HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0}
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - action SEND_ERROR HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=27}
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - consumeAll HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=false
java.io.IOException: Unconsumed content
at org.eclipse.jetty.server.HttpInput.consumeAll(HttpInput.java:153)
at org.eclipse.jetty.server.HttpChannel.ensureConsumeAllOrNotPersistent(HttpChannel.java:587)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:434)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:284)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100)
at org.eclipse.jetty.io.SocketChannelEndPoint$1.run(SocketChannelEndPoint.java:101)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:370)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:791)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:913)
at java.base/java.lang.Thread.run(Thread.java:829)
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - consumeAll [e=java.io.IOException: Unconsumed content] AsyncContentProducer@47c130d3[r=null,t=null,i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=27}]
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failing all content with java.io.IOException: Unconsumed content HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=27}
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent has no content, parsing and filling
04:25:06.065 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=27/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=CONTENT,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=27} parse null
04:25:06.066 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=CONTENT HeapByteBuffer@50eab9b9[p=0,l=0,c=0,r=0]={<<<>>>}
04:25:06.066 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT --> CONTENT_END
04:25:06.066 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - onContentComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=28}
04:25:06.066 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - CONTENT_END --> END
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - onRequestComplete HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=57}
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - received EOF, content = null
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=57/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=END,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=57} parsed false HttpParser{s=END,0 of -1}
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - produceContent produced EofContent
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failed all content, EOF = true
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - failed all content of http channel EOF=true AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=57}]
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isFinished=true HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - isError = false AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=57}]
04:25:06.095 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isError=false HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.107 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - completing HttpChannelState@5a356aa7{s=HANDLING rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=false al=0}
04:25:06.107 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - sendResponse info=null content=DirectByteBuffer@1814be12[p=0,l=149,c=32768,r=149]={<<<{\n"servlet":"spring",\n"me...rart",\n"status":"404"\n}>>>"\xA6\x07\x87\xC5$\xCc\x17\x1c...\x1a\xB5\xE7\x0c\x0c\xE1-} complete=true committing=true callback=org.eclipse.jetty.util.Callback$3@2f66c046
04:25:06.107 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - COMMIT for /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart on HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMMITTED is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=69}
404 null HTTP/1.1
Cache-Control: must-revalidate,no-cache,no-store
Content-Type: application/json
04:25:06.107 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: NEED_HEADER for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=404,h=2,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@33fdcffd] (null,[p=0,l=149,c=32768,r=149],true)@START
04:25:06.108 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpGenerator - generateHeaders HTTP/1.1{s=404,h=2,cl=-1} last=true content=DirectByteBuffer@1814be12[p=0,l=149,c=32768,r=149]={<<<{\n"servlet":"spring",\n"me...rart",\n"status":"404"\n}>>>"\xA6\x07\x87\xC5$\xCc\x17\x1c...\x1a\xB5\xE7\x0c\x0c\xE1-}
04:25:06.108 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpGenerator - Cache-Control: must-revalidate,no-cache,no-store
Content-Type: application/json
04:25:06.108 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpGenerator - CONTENT_LENGTH
04:25:06.108 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: FLUSH for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=404,h=2,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@33fdcffd] ([p=0,l=152,c=8192,r=152],[p=0,l=149,c=32768,r=149],true)@COMPLETING
04:25:06.116 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.WriteFlusher - write: WriteFlusher@348478f3{IDLE}->null [DirectByteBuffer@4d1c3740[p=0,l=152,c=8192,r=152]={<<<HTTP/1.1 404 Not Found\r\nC...rver: Jetty(10.0.1)\r\n\r\n>>>tion/*+js...\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@1814be12[p=0,l=149,c=32768,r=149]={<<<{\n"servlet":"spring",\n"me...rart",\n"status":"404"\n}>>>"\xA6\x07\x87\xC5$\xCc\x17\x1c...\x1a\xB5\xE7\x0c\x0c\xE1-}]
04:25:06.116 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{WRITING}->null:IDLE-->WRITING
04:25:06.117 [main ] TRACE it.tidalwave.bluemarine2.rest.ResponseEntityIo - store(target/test-results/audiofile-5lCKAUoE3IfmgttCE3a5U23gxQg=-coverart.jpg.txt, ..., ...)
04:25:06.118 [main ] INFO it.tidalwave.bluemarine2.rest.ResponseEntityIo - >>>> TYPE class [B
04:25:06.118 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - ******** Comparing files:
04:25:06.118 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> path is: /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/
04:25:06.119 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> exp is: test-classes/expected-results/audiofile-5lCKAUoE3IfmgttCE3a5U23gxQg=-coverart.jpg.txt
04:25:06.119 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> act is: test-results/audiofile-5lCKAUoE3IfmgttCE3a5U23gxQg=-coverart.jpg.txt
04:25:06.119 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST PASSED in 113 msec
04:25:06.119 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest -
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - flushed 301 SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=W,to=86/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=END,0 of -1},g=HttpGenerator@c2738ad{s=COMPLETING}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMMITTED is=IDLE awp=false se=false i=false al=0},r=14,c=false/false,a=HANDLING,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=86}
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.WriteFlusher - Flushed=true written=301 remaining=0 WriteFlusher@348478f3{WRITING}->null
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.WriteFlusher - update WriteFlusher@348478f3{IDLE}->null:WRITING-->IDLE
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - generate: DONE for SendCallback@1169cc77[PROCESSING][i=HTTP/1.1{s=404,h=2,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$SendCallback@33fdcffd] ([p=152,l=152,c=8192,r=0],[p=149,l=149,c=32768,r=0],true)@END
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - completed HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETING os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - unhandle HttpChannelState@5a356aa7{s=HANDLING rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - nextAction(false) TERMINATED HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - action TERMINATED HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=14,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=86}
04:25:06.124 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - onCompleted for /rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart written=149
04:25:06.125 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - consumeAll HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
java.io.IOException: Unconsumed content
at org.eclipse.jetty.server.HttpInput.consumeAll(HttpInput.java:153)
at org.eclipse.jetty.server.HttpConnection.onCompleted(HttpConnection.java:434)
at org.eclipse.jetty.server.HttpChannel.onCompleted(HttpChannel.java:865)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:379)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:284)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:319)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:100)
at org.eclipse.jetty.io.SocketChannelEndPoint$1.run(SocketChannelEndPoint.java:101)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:370)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:791)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:913)
at java.base/java.lang.Thread.run(Thread.java:829)
04:25:06.125 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - consumeAll [e=java.io.IOException: Unconsumed content] AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=14,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=87}]
04:25:06.125 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelOverHttp - failing all content with java.io.IOException: Unconsumed content HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=14,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=87}
04:25:06.125 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - failed all content of http channel EOF=true AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=14,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=87}]
04:25:06.125 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isFinished=true HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.135 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.AsyncContentProducer - isError = false AsyncContentProducer@47c130d3[r=ErrorContent [java.io.IOException: Unconsumed content],t=ErrorContent [java.io.IOException: Unconsumed content],i=null,error=false,c=HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0},r=14,c=true/true,a=IDLE,uri=http://10.244.2.50:46773/rest/audiofile/urn:bluemarine:audiofile:5lCKAUoE3IfmgttCE3a5U23gxQg=/coverart,age=97}]
04:25:06.137 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - ******************************
04:25:06.140 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST "test service publishing"
04:25:06.140 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - ******************************
04:25:06.142 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - TEST PASSED in 5 msec
04:25:06.143 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest -
04:25:06.143 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - isError=false HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.143 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannelState - recycle HttpChannelState@5a356aa7{s=IDLE rs=COMPLETED os=COMPLETED is=IDLE awp=false se=false i=false al=0}
04:25:06.143 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpInput - recycle HttpInput@681148668 cs=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} cp=org.eclipse.jetty.server.BlockingContentProducer@593ebdbf eof=true
04:25:06.144 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - reset HttpParser{s=END,0 of -1}
04:25:06.144 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - END --> START
04:25:06.144 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpChannel - !handle TERMINATED HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=23/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0} filled 0 DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0} parse DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.http.HttpParser - parseNext s=START DirectByteBuffer@4d1c3740[p=0,l=0,c=8192,r=0]={<<<>>>HTTP/1.1 ...\x00\x00\x00\x00\x00\x00\x00}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0} parsed false HttpParser{s=START,0 of -1}
04:25:06.148 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - releaseRequestBuffer HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=24/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.158 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.AbstractConnection - fillInterested HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=-,flush=-,to=34/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.158 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.FillInterest - interested FillInterest@6a480888{AC.ReadCB@61c95062{HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}}}
04:25:06.158 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - changeInterests p=false 0->1 for SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.171 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Queued change lazy=false org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0 on ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=0
04:25:06.172 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Wakeup on submit ManagedSelector@1fbebcd6{STARTED} id=0 keys=1 selected=0 updates=1
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken with none selected
04:25:06.172 [qtp1367504650-35 ] DEBUG org.eclipse.jetty.server.HttpConnection - HttpConnection@61c95062::SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=13/30000}{io=0/1,kio=0,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0} onFillable exit HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0} null
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d woken up from select, 0/0/1 selected
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d processing 0 keys, 1 updates
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updateable 1
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - update org.eclipse.jetty.io.SocketChannelEndPoint$$Lambda$305/0x000000010051f840@49c124b0
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.SocketChannelEndPoint - Key interests updated 0 -> 1 on SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=13/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0}
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - updates 0
04:25:06.172 [qtp1367504650-37 ] DEBUG org.eclipse.jetty.io.ManagedSelector - Selector sun.nio.ch.EPollSelectorImpl@7c5643d waiting with 1 keys
04:25:06.172 [qtp1367504650-35 ] DEBUG o.eclipse.jetty.util.thread.ReservedThreadExecutor - ReservedThreadExecutor@428b5372{s=2/2,p=0}@8bcf3bf waiting
04:25:06.190 [Invoker-afterClass()] INFO i.t.bluemarine2.commons.test.SpringTestSupport - Closing Spring context...
04:25:06.234 [Invoker-afterClass()] DEBUG it.tidalwave.messagebus.spi.SimpleMessageBus - unsubscribe(MessageBusAdapterFactory.MessageBusListenerAdapter(method=public void it.tidalwave.bluemarine2.model.impl.DefaultCacheManager.onPersistenceUpdated(it.tidalwave.bluemarine2.message.PersistenceInitializedNotification)))
04:25:06.248 [Invoker-afterClass()] DEBUG it.tidalwave.messagebus.spi.SimpleMessageBus - unsubscribe(MessageBusAdapterFactory.MessageBusListenerAdapter(method=public void it.tidalwave.bluemarine2.rest.impl.server.DefaultResourceServer.onPowerOnNotification(it.tidalwave.bluemarine2.message.PowerOnNotification) throws java.lang.Exception))
04:25:06.248 [Invoker-afterClass()] DEBUG it.tidalwave.messagebus.spi.SimpleMessageBus - unsubscribe(MessageBusAdapterFactory.MessageBusListenerAdapter(method=public void it.tidalwave.bluemarine2.rest.impl.server.DefaultResourceServer.onPowerOffNotification(it.tidalwave.bluemarine2.message.PowerOffNotification) throws java.lang.Exception))
04:25:06.248 [Invoker-afterClass()] DEBUG it.tidalwave.messagebus.spi.SimpleMessageBus - unsubscribe(MessageBusAdapterFactory.MessageBusListenerAdapter(method=void it.tidalwave.bluemarine2.rest.impl.MusicResourcesController.onPersistenceInitializedNotification(it.tidalwave.bluemarine2.message.PersistenceInitializedNotification) throws java.io.IOException))
04:25:06.248 [Invoker-afterClass()] INFO o.s.scheduling.concurrent.ThreadPoolTaskExecutor - Shutting down ExecutorService 'taskExecutor'
04:25:06.545 [main ] INFO i.t.b.rest.impl.MusicResourcesControllerTest - FINISHED TESTS OF it.tidalwave.bluemarine2.rest.impl.MusicResourcesControllerTest
[INFO] Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 86.606 s - in it.tidalwave.bluemarine2.rest.impl.MusicResourcesControllerTest
04:25:08.552 [Scheduler-1b1e6928-1] DEBUG org.eclipse.jetty.io.IdleTimeout - SocketChannelEndPoint@2cc99d7a{l=/10.244.2.50:46773,r=/10.244.2.50:50984,OPEN,fill=FI,flush=-,to=2393/30000}{io=1/1,kio=1,kro=1}->HttpConnection@61c95062[p=HttpParser{s=START,0 of -1},g=HttpGenerator@c2738ad{s=START}]=>HttpChannelOverHttp@5cbb3d17{s=HttpChannelState@5a356aa7{s=IDLE rs=BLOCKING os=OPEN is=IDLE awp=false se=false i=true al=0},r=14,c=false/false,a=IDLE,uri=null,age=0} idle timeout check, elapsed: 2393 ms, remaining: 27607 ms
[INFO]
[INFO] Results:
[INFO]
[INFO] Tests run: 15, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[JENKINS] Recording test results
[INFO]
[INFO] --- jar:3.2.0:jar (default-jar) @ it-tidalwave-bluemarine2-rest ---
[INFO] Building jar: /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.5bd725d04573.jar
[INFO]
[INFO] --- jar:3.2.0:test-jar (default-test-jar) @ it-tidalwave-bluemarine2-rest ---
[INFO] Building jar: /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.5bd725d04573-tests.jar
[INFO]
[INFO] --- install:3.0.0-M1:install (default-install) @ it-tidalwave-bluemarine2-rest ---
[INFO] Installing /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.5bd725d04573.jar to /var/jenkins_home/maven-repositories/2/it/tidalwave/bluemarine2/it-tidalwave-bluemarine2-rest/1.1-ALPHA-2-SNAPSHOT/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.jar
[INFO] Installing /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/pom.xml to /var/jenkins_home/maven-repositories/2/it/tidalwave/bluemarine2/it-tidalwave-bluemarine2-rest/1.1-ALPHA-2-SNAPSHOT/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.pom
[INFO] Installing /var/jenkins_home/jobs/blueMarine2_Build_and_Quick_Test/workspace/modules/REST/target/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT.5bd725d04573-tests.jar to /var/jenkins_home/maven-repositories/2/it/tidalwave/bluemarine2/it-tidalwave-bluemarine2-rest/1.1-ALPHA-2-SNAPSHOT/it-tidalwave-bluemarine2-rest-1.1-ALPHA-2-SNAPSHOT-tests.jar
[JENKINS] Archiving disabled
![[Jenkins]](/ci/static/0840cea4/images/svgs/logo.svg){kind=logo}
