[
https://issues.apache.org/jira/browse/COUCHDB-394?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12764524#action_12764524
]
Mark Hammond commented on COUCHDB-394:
--------------------------------------
COUCHDB-452 is now closed as the trunk ignores this 'normal exit' case.
However, this bug goes a little further than 452 - it has identified a
potential enhancement to mochiweb to propagate the error reason so it could be
logged or special cased in other ways - therefore I'm leaving this open for
now...
> Intermittent HTTP 500 errors on queries, no useful diagnostics
> --------------------------------------------------------------
>
> Key: COUCHDB-394
> URL: https://issues.apache.org/jira/browse/COUCHDB-394
> Project: CouchDB
> Issue Type: Bug
> Components: HTTP Interface
> Affects Versions: 0.10
> Environment: Ubuntu 8.10 on x386 on Amazon Web Services VM
> Reporter: Curt Arnold
>
> Originally raised on [email protected] on 2009-06-16, but no
> resolution. Original message follows later.
> Analysis: seems like there is a failure during the call to gen_tcp:send, a
> connection dropped or something like that, however the calling code in
> mochiweb_request:send appears to discard the reason returned by gen_tcp:send
> leaving a user with no clue of the underlying problem.
> Here is Mochiweb_request:send
> send(Data) ->
> case gen_tcp:send(Socket, Data) of
> ok ->
> ok;
> _ ->
> exit(normal)
> end.
> In the case of a non ok return from gen_tcp:send, exit(normal) is called
> which is connected with the log file in the original message. It would seem
> much more helpful if the recent included in the return value from
> gen_tcp:send was passed to the exit/1 function.
> Actually, a different function appears in the original log file. I was
> looking at a slightly different entry in a different log file, but I think
> the analysis holds.
> Different log snippet:
> [Wed, 27 May 2009 00:39:00 GMT] [info] [<0.3318.13>] 127.0.0.1 - - 'GET'
> /default/_design/query/_view/byURI?include_docs=true;key=... 200
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3318.13>] 'GET'
> /default/_design/query/_view/byURI?include_docs=true;key=.. {1,
>
> 1}
> Headers: [{'Accept',"text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2"},
> {'Connection',"keep-alive"},
> {'Host',"localhost:5984"},
> {'User-Agent',"Java/1.6.0_0"}]
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3318.13>] request_group {Pid,
> Seq} {<0.3872.0>,1890}
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3318.13>] Include Doc: <<"...">>
> nil
> [Wed, 27 May 2009 00:39:00 GMT] [error] [<0.3318.13>] Uncaught error in HTTP
> request: {exit,normal}
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3318.13>] Stacktrace:
> [{mochiweb_request,send,2},
> {couch_httpd,send_chunk,2},
> {couch_httpd_view,design_doc_view,5},
> {couch_httpd_db,do_db_req,2},
> {couch_httpd,handle_request,5},
> {mochiweb_http,headers,5},
> {proc_lib,init_p,5}]
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3332.13>] 'GET'
> /default/_design/query/_view/forSubject?include_docs=true;key=[] {1,
>
>
> 1}
> Headers: [{'Accept',"text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2"},
> {'Connection',"keep-alive"},
> {'Host',"localhost:5984"},
> {'User-Agent',"Java/1.6.0_0"}]
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3318.13>] httpd 500 error
> response:
> {"error":"unknown_error","reason":"normal"}
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3332.13>] request_group {Pid,
> Seq} {<0.3872.0>,1890}
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3332.13>] Include Doc: <<"...">>
> nil
> [Wed, 27 May 2009 00:39:00 GMT] [debug] [<0.3332.13>] Include Doc: <<"...">>
> nil
> ------
> Original message:
> I've been seeing HTTP 500 failures from time to time with a fairly
> recent snapshot build ("0.10.0a777361") from the SVN. The actual keys
> have been omitted, however the queries have been working for a long
> time and the keys are valid and return the right data most of the time.
> Any clues what might be happening?
> [Thu, 18 Jun 2009 01:22:59 GMT] [debug] [<0.1178.84>] 'POST' /loaded/
> _design/query/_view/byURI?include_docs=true {1,1}
> Headers: [{'Accept',"*/*"},
> {'Accept-Encoding',"gzip, deflate"},
> {'Accept-Language',"en-us"},
> {'Cache-Control',"no-cache"},
> {'Connection',"Keep-Alive"},
> {'Content-Length',"537"},
> {'Content-Type',"text/plain; charset=utf-8"},
> {'Cookie',"x-auto-16=m%3Asize%7Cf%3A235.0"},
> {'Host',"localhost:5984"},
> {'Referer',"http://localhost:8888/browser/hosted.html?
> browser"},
> {'User-Agent',"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT
> 5.1; SV1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR
> 3.5.30729; .NET CLR 1.1.4322)"}]
> [Thu, 18 Jun 2009 01:22:59 GMT] [debug] [<0.1180.84>] 'GET' /loaded/
> _design/query/_view/forSubject?include_docs=true;;key=[omitted] {1,
>
>
> 1
> }
> Headers: [{'Accept',"*/*"},
> {'Accept-Encoding',"gzip, deflate"},
> {'Accept-Language',"en-us"},
> {'Cache-Control',"no-cache"},
> {'Connection',"Keep-Alive"},
> {'Cookie',"x-auto-16=m%3Asize%7Cf%3A235.0"},
> {'Host',"localhost:5984"},
> {'Referer',"http://localhost:8888/browser/hosted.html?
> browser"},
> {'User-Agent',"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT
> 5.1; SV1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR
> 3.5.30729; .NET CLR 1.1.4322)"}]
> [Thu, 18 Jun 2009 01:22:59 GMT] [debug] [<0.1180.84>] request_group
> {Pid, Seq} {<0.15823.64>,4485}
> [Thu, 18 Jun 2009 01:22:59 GMT] [info] [<0.1180.84>] 127.0.0.1 - -
> 'GET' /loaded/_design/query/_view/forSubject?
> include_docs=true;;key=[omitted] 200
> [Thu, 18 Jun 2009 01:22:59 GMT] [debug] [<0.1180.84>] 'GET' /loaded/
> _design/query/_view/byURI?include_docs=true;key=[omitted] {1,
>
> 1
> }
> Headers: [{'Accept',"*/*"},
> {'Accept-Encoding',"gzip, deflate"},
> {'Accept-Language',"en-us"},
> {'Cache-Control',"no-cache"},
> {'Connection',"Keep-Alive"},
> {'Cookie',"x-auto-16=m%3Asize%7Cf%3A235.0"},
> {'Host',"localhost:5984"},
> {'If-None-Match',"\"60FWBA3D7UUZI9NSMR4PS9VM4\""},
> {'Referer',"http://localhost:8888/browser/hosted.html?
> browser"},
> {'User-Agent',"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT
> 5.1; SV1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR
> 3.5.30729; .NET CLR 1.1.4322)"}]
> [Thu, 18 Jun 2009 01:22:59 GMT] [debug] [<0.1180.84>] request_group
> {Pid, Seq} {<0.15823.64>,4485}
> [Thu, 18 Jun 2009 01:22:59 GMT] [info] [<0.1180.84>] 127.0.0.1 - -
> 'GET' /loaded/_design/query/_view/byURI?
> include_docs=true;key=[omitted] 304
> [Thu, 18 Jun 2009 01:23:09 GMT] [error] [<0.1178.84>] Uncaught error
> in HTTP request: {exit,normal}
> [Thu, 18 Jun 2009 01:23:09 GMT] [debug] [<0.1178.84>] Stacktrace:
> [{mochiweb_request,recv,3},
> {mochiweb_request,stream_unchunked_body,5},
> {mochiweb_request,recv_body,2},
> {couch_httpd,json_body,1},
> {couch_httpd,json_body_obj,1},
> {couch_httpd_view,handle_view_req,2},
> {couch_httpd_db,do_db_req,2},
> {couch_httpd,handle_request,5}]
> [Thu, 18 Jun 2009 01:23:09 GMT] [debug] [<0.1178.84>] httpd 500 error
> response:
> {"error":"unknown_error","reason":"normal"}
> [Thu, 18 Jun 2009 01:23:09 GMT] [info] [<0.1178.84>] 127.0.0.1 - -
> 'POST' /loaded/_design/query/_view/byURI?include_docs=true 500
> [Thu, 18 Jun 2009 01:23:20 GMT] [debug] [<0.1180.84>] 'GET' /loaded/
> _design/query/_view/forValue?key=[omitted] {1,
>
>
> 1
> }
> Headers: [{'Accept',"*/*"},
> {'Accept-Encoding',"gzip, deflate"},
> {'Accept-Language',"en-us"},
> {'Connection',"Keep-Alive"},
> {'Content-Type',"text/plain; charset=utf-8"},
> {'Cookie',"x-auto-16=m%3Asize%7Cf%3A235.0"},
> {'Host',"localhost:5984"},
> {'If-None-Match',"\"60FWBA3D7UUZI9NSMR4PS9VM4\""},
> {'Referer',"http://localhost:8888/browser/hosted.html?
> browser"},
> {'User-Agent',"Mozilla/4.0 (compatible; MSIE 6.0; Windows NT
> 5.1; SV1; .NET CLR 2.0.50727; .NET CLR 3.0.4506.2152; .NET CLR
> 3.5.30729; .NET CLR 1.1.4322)"}]
> [Thu, 18 Jun 2009 01:23:20 GMT] [debug] [<0.1180.84>] request_group
> {Pid, Seq} {<0.15823.64>,4485}
--
This message is automatically generated by JIRA.
-
You can reply to this email to add a comment to the issue online.
