Don't log connection reset errors because they are not really errors

Details

Type: Improvement
Status: Open
Priority: Major
Resolution: Unresolved
Affects Version/s: None
Fix Version/s: None
Component/s: Web
Labels:
None

Number of attachments :
0

Description

INFO   | jvm 1    | 2012/03/19 17:16:05 | 2012-03-19 17:16:04.993:WARN:/:Error: Couldn't handle error: response committed
INFO   | jvm 1    | 2012/03/19 17:16:05 | org.jruby.exceptions.RaiseException: Native Exception: 'class org.mortbay.jetty.EofException'; Message: null; StackTrace: org.mortbay.jetty.EofException|?at org.mortbay.jetty.HttpGenerator.flush(HttpGenerator.java:789)|?at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:550)|?at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:571)|?at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:1010)|?at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:650)|?at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:579)|?at java.util.zip.DeflaterOutputStream.deflate(DeflaterOutputStream.java:161)|?at java.util.zip.DeflaterOutputStream.write(DeflaterOutputStream.java:118)|?at java.util.zip.GZIPOutputStream.write(GZIPOutputStream.java:72)|?at java.io.FilterOutputStream.write(FilterOutputStream.java:80)|?at org.mortbay.servlet.GzipFilter$GzipStream.write(GzipFilter.java:504)|?at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)|?at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)|?at java.lang.reflect.Method.invoke(Method.java:597)|?at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:525)|Caused by: java.io.IOException: Broken pipe|?at sun.nio.ch.FileDispatcher.write0(Native Method)|?at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)|?at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:72)|?at sun.nio.ch.IOUtil.write(IOUtil.java:28)|?at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)|?at org.mortbay.io.nio.ChannelEndPoint.flush(ChannelEndPoint.java:179)|?at org.mortbay.io.nio.SelectChannelEndPoint.flush(SelectChannelEndPoint.java:221)|?at org.mortbay.jetty.HttpGenerator.flush(HttpGenerator.java:723)|?at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:550)|?at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:571)|?at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:1010)|?at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:650)|?at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:579)|?at java.util.zip.DeflaterOutputStream.deflate(DeflaterOutputStream.java:161)|?at java.util.zip.DeflaterOutputStream.write(DeflaterOutputStream.java:118)|?at java.util.zip.GZIPOutputStream.write(GZIPOutputStream.java:72)|?at java.io.FilterOutputStream.write(FilterOutputStream.java:80)|?at org.mortbay.servlet.GzipFilter$GzipStream.write(GzipFilter.java:504)|?at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)|?at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)|?at java.lang.reflect.Method.invoke(Method.java:597)|?at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:525)|?at org.jruby.javasupport.JavaMethod.invokeDirect(JavaMethod.java:380)|?at org.jruby.java.invokers.InstanceMethodInvoker.call(InstanceMethodInvoker.java:58)|?at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:137)|?at rubyjit.write_body_C54659A493E4EA924E2E526EBFF816C3A454B935.block_0$RUBY$__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb:75)|?at rubyjit$write_body_C54659A493E4EA924E2E526EBFF816C3A454B935$block_0$RUBY$__file__.call(rubyjit$write_body_C54659A493E4EA924E2E526EBFF816C3A454B935$block_0$RUBY$__file__:65535)|?at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:112)|?at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:95)|?at org.jruby.runtime.Block.yield(Block.java:130)|?at rubyjit.each_2A2F3973DB7F665F3A2B12AB440D36155B693CC7.block_0$RUBY$__file__(/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/vendor/rails/actionpack/lib/action_controller/string_coercion.rb:10)|?at rubyjit$each_2A2F3973DB7F665F3A2B12AB440D36155B693CC7$block_0$RUBY$__file__.call(rubyjit$each_2A2F3973DB7F665F3A2B12AB440D36155B693CC7$block_0$RUBY$__file__:65535)|?at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:112)|?at org.jruby.runtime.CompiledBlock.yield(CompiledBlock.java:95)|?at org.jruby.runtime.Block.yield(Block.java:130)|?at rubyjit.each_0499909B6B9EAC684435CF5918AACEE9894EA7F9.__file__(/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/vendor/rails/actionpack/lib/action_controller/response.rb:156)|?at rubyjit.each_0499909B6B9EAC684435CF5918AACEE9894EA7F9.__file__(/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/vendor/rails/actionpack/lib/action_controller/response.rb)|?at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:147)|?at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:112)|?at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:123)|?at rubyjit.each_2A2F3973DB7F665F3A2B12AB440D36155B693CC7.__file__(/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/vendor/rails/actionpack/lib/action_controller/string_coercion.rb:9)|?at rubyjit.each_2A2F3973DB7F665F3A2B12AB440D36155B693CC7.__file__(/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/vendor/rails/actionpack/lib/action_controller/string_coercion.rb)|?at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:147)|?at org.jruby.runtime.callsite.CachingCallSite.callBlock(CachingCallSite.java:112)|?at org.jruby.runtime.callsite.CachingCallSite.callIter(CachingCallSite.java:123)|?at rubyjit.write_body_C54659A493E4EA924E2E526EBFF816C3A454B935.chained_1_rescue_1$RUBY$SYNTHETIC__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb:74)|?at rubyjit.write_body_C54659A493E4EA924E2E526EBFF816C3A454B935.chained_0_ensure_1$RUBY$__ensure__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb)|?at rubyjit.write_body_C54659A493E4EA924E2E526EBFF816C3A454B935.__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb:73)|?at rubyjit.write_body_C54659A493E4EA924E2E526EBFF816C3A454B935.__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb)|?at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:167)|?at org.jruby.runtime.callsite.CachingCallSite.call(CachingCallSite.java:137)|?at rubyjit.respond_DDFFC8A5189F811100F5D1C24BCE1C36725A8017.__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb:37)|?at rubyjit.respond_DDFFC8A5189F811100F5D1C24BCE1C36725A8017.__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb)|?at rubyjit.respond_DDFFC8A5189F811100F5D1C24BCE1C36725A8017.__file__(file:/vol/sonar/sonar-2.14/war/sonar-server/WEB-INF/lib/jruby-rack-1.0.0.1.jar!/jruby/rack/response.rb)|?at org.jruby.internal.runtime.methods.JittedMethod.call(JittedMethod.java:87)|?at JRuby$$Rack$$Response_28090506.respond(JRuby$$Rack$$Response_28090506.gen:13)|?at org.jruby.rack.DefaultRackDispatcher.process(DefaultRackDispatcher.java:29)|?at org.jruby.rack.RackFilter.doFilter(RackFilter.java:63)|?at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)|?at org.sonar.server.ui.DatabaseSessionFilter.doFilter(DatabaseSessionFilter.java:34)|?at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)|?at org.mortbay.servlet.UserAgentFilter.doFilter(UserAgentFilter.java:78)|?at org.mortbay.servlet.GzipFilter.doFilter(GzipFilter.java:131)|?at org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1157)|?at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:388)|?at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216)|?at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:182)|?at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)|?at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:440)|?at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)|?at org.mortbay.jetty.Server.handle(Server.java:326)|?at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:542)|?at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:926)|?at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:549)|?at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)|?at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)|?at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:410)|?at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:582)|
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.HttpGenerator.flush(HttpGenerator.java:789)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.AbstractGenerator$Output.blockForOutput(AbstractGenerator.java:550)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.AbstractGenerator$Output.flush(AbstractGenerator.java:571)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.HttpConnection$Output.flush(HttpConnection.java:1010)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:650)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.jetty.AbstractGenerator$Output.write(AbstractGenerator.java:579)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at java.util.zip.DeflaterOutputStream.deflate(DeflaterOutputStream.java:161)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at java.util.zip.DeflaterOutputStream.write(DeflaterOutputStream.java:118)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at java.util.zip.GZIPOutputStream.write(GZIPOutputStream.java:72)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at java.io.FilterOutputStream.write(FilterOutputStream.java:80)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.mortbay.servlet.GzipFilter$GzipStream.write(GzipFilter.java:504)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at java.lang.reflect.Method.invoke(Method.java:597)
INFO   | jvm 1    | 2012/03/19 17:16:05 | 	at org.jruby.javasupport.JavaMethod.invokeDirectWithExceptionHandling(JavaMethod.java:525)

Activity

Ascending order - Click to sort in descending order

Hide

Permalink

Fabrice Bellingard added a comment - 03/Apr/12 2:01 AM

With only this excerpt of the log, this is difficult to know the root cause.

Show

Fabrice Bellingard added a comment - 03/Apr/12 2:01 AM With only this excerpt of the log, this is difficult to know the root cause.

Hide

Permalink

Simon Brandhof added a comment - 05/Apr/12 8:19 AM

This error is raised by the Jetty GZIP filter. It occurs when the connection has already been closed by client.
The filter is the latest one to be executed, so we can't manage to filter these logs with logback. Logs are directly written to standard output.

A solution is to fork org.mortbay.servlet.GzipFilter and to silently ignore this kind of errors. Is it allowed by the Apache License 2.0 (sonar is LGPLv3) ?

Show

Simon Brandhof added a comment - 05/Apr/12 8:19 AM This error is raised by the Jetty GZIP filter. It occurs when the connection has already been closed by client. The filter is the latest one to be executed, so we can't manage to filter these logs with logback. Logs are directly written to standard output. A solution is to fork org.mortbay.servlet.GzipFilter and to silently ignore this kind of errors. Is it allowed by the Apache License 2.0 (sonar is LGPLv3) ?

Hide

Permalink

David Gageot added a comment - 18/Jul/12 3:34 AM

I was unable to suppress these error logs because Jetty's code catches the error and print it directly to the standard output

Show

David Gageot added a comment - 18/Jul/12 3:34 AM I was unable to suppress these error logs because Jetty's code catches the error and print it directly to the standard output

Hide

Permalink

Simon Brandhof added a comment - 18/Jul/12 4:49 AM

David, do you know if upgrading to jetty 7 fixes this issue ?

Show

Simon Brandhof added a comment - 18/Jul/12 4:49 AM David, do you know if upgrading to jetty 7 fixes this issue ?

Hide

Permalink

David Gageot added a comment - 18/Jul/12 5:07 AM

Not in jetty 7. However it seems to be fixed in 7.6.0 http://jira.codehaus.org/browse/JETTY-1460

Show

David Gageot added a comment - 18/Jul/12 5:07 AM Not in jetty 7. However it seems to be fixed in 7.6.0 http://jira.codehaus.org/browse/JETTY-1460

People

Assignee:

Unassigned

Reporter:

Simon Brandhof

Vote (0)

Watch (2)

Dates

Created:

28/Mar/12 3:35 AM

Updated:

18/Jul/12 5:07 AM