Error 500 Internal Server for /admin/modules, clear caches; barracuda on EC2

I'm getting HTTP error 500 internal server error when accessing certain selected pages on a site created using Barracuda/Aegir and running on Amazon EC2. The platform validates in Aegir and I can create an operate a site. Most functions work fine, but I get an HTTP error 500 / internal server error when I (1) access /admin/modules, (2) access admin/config/performance>clear caches, (3) run cron. EDIT: I have confirmed that drush cron and drush cc (clear all caches) work fine on the back end.

I have not tried every feature/page/function exhaustively to test for this behavior, but, again, all the usual stuff works fine.

I've not been able to figure out what's happening on the server side. I did temporarily set nginx logging to "debug" and isolated the log file produced on an attempt to access /admin/modules. The file is appended below. It shows a few "maybe" suspicious issues, but I'm not able to determine if any of these are related/causative, or if this log file would even be helpful. I do see a "temporarily unavailable" error partway down. I have one other bit of data: My drupal server status shows one error message, indicating that drupal cannot use HTTP due to a server misconfiguration. I can certainly use HTTP from my server command line to access external HTTP sites. Still, perhaps there's a common cause -- at least this is a hypothesis -- maybe due to the complexities of EC2 network configuration?

2012/08/15 16:05:06 [debug] 33749#0: post event 0000000000A80A10
2012/08/15 16:05:06 [debug] 33749#0: delete posted event 0000000000A80A10
2012/08/15 16:05:06 [debug] 33749#0: accept on 0.0.0.0:80, ready: 1
2012/08/15 16:05:06 [debug] 33749#0: posix_memalign: 00000000006F2110:256 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 accept: 128.143.71.4 fd:16
2012/08/15 16:05:06 [debug] 33749#0: *10 event timer add: 16: 60000:1345046766528
2012/08/15 16:05:06 [debug] 33749#0: *10 epoll add event: fd:16 op:1 ev:80000001
2012/08/15 16:05:06 [debug] 33749#0: accept() not ready (11: Resource temporarily unavailable)
2012/08/15 16:05:06 [debug] 33749#0: *10 post event 0000000000A80BB0
2012/08/15 16:05:06 [debug] 33749#0: *10 delete posted event 0000000000A80BB0
2012/08/15 16:05:06 [debug] 33749#0: *10 malloc: 0000000000A1E9A0:1296
2012/08/15 16:05:06 [debug] 33749#0: *10 posix_memalign: 00000000006F1E70:256 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 malloc: 0000000000B50A30:32768
2012/08/15 16:05:06 [debug] 33749#0: *10 posix_memalign: 00000000006DFE40:4096 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 http process request line
2012/08/15 16:05:06 [debug] 33749#0: *10 recv: fd:16 603 of 32768
2012/08/15 16:05:06 [debug] 33749#0: *10 http request line: "GET /admin/modules HTTP/1.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http uri: "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http args: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http exten: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http process request header line
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Host: openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Connection: keep-alive"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Cache-Control: max-age=0"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Accept-Encoding: gzip,deflate,sdch"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Accept-Language: en-US,en;q=0.8"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.3"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header: "Cookie: DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http header done
2012/08/15 16:05:06 [debug] 33749#0: *10 event timer del: 16: 1345046766528
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 0
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 1
2012/08/15 16:05:06 [debug] 33749#0: *10 http script value: "Cache"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script set $nocache_details
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1" ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if: false
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "GET"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script regex: "^(?:GET|HEAD|POST|PUT|DELETE|OPTIONS)$"
2012/08/15 16:05:06 [notice] 33749#0: *10 "^(?:GET|HEAD|POST|PUT|DELETE|OPTIONS)$" matches "GET", client: 128.143.71.4, server: openacademy.uvacs.makespot.com, request: "GET /admin/modules HTTP/1.1", host: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if: false
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "" ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if: false
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "/"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "hosting"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "core/cron.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "audio/download"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "admin"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "/settings/performance/cache-backend"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "/config/development/performance/redis"
2012/08/15 16:05:06 [debug] 33749#0: *10 using configuration "/admin"
2012/08/15 16:05:06 [debug] 33749#0: *10 http cl:-1 max:104857600
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 3
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 4
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 posix_memalign: 0000000000A23090:4096 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1" ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if: false
2012/08/15 16:05:06 [debug] 33749#0: *10 http script value: "Skip"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script set $nocache_details
2012/08/15 16:05:06 [debug] 33749#0: *10 post rewrite phase: 5
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 6
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 7
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 8
2012/08/15 16:05:06 [debug] 33749#0: *10 limit zone: 9E212841 1
2012/08/15 16:05:06 [debug] 33749#0: *10 add cleanup: 0000000000A23188
2012/08/15 16:05:06 [debug] 33749#0: *10 access phase: 9
2012/08/15 16:05:06 [debug] 33749#0: *10 access phase: 10
2012/08/15 16:05:06 [debug] 33749#0: *10 post access phase: 11
2012/08/15 16:05:06 [debug] 33749#0: *10 try files phase: 12
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 trying to use file: "/admin/modules" "/data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 add cleanup: 0000000000A23200
2012/08/15 16:05:06 [debug] 33749#0: *10 retest open file: /data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev/admin/modules, fd:-1, c:0, e:2
2012/08/15 16:05:06 [debug] 33749#0: *10 cached open file: /data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev/admin/modules, fd:-1, c:0, e:2, u:2
2012/08/15 16:05:06 [debug] 33749#0: *10 trying to use file: "@drupal" "/data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev@drupal"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "@cache"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "@drupal"
2012/08/15 16:05:06 [debug] 33749#0: *10 using location: @drupal "/admin/modules?"
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 3
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 4
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if: false
2012/08/15 16:05:06 [debug] 33749#0: *10 http script regex: "^/(.*)$"
2012/08/15 16:05:06 [notice] 33749#0: *10 "^/(.*)$" matches "/admin/modules", client: 128.143.71.4, server: openacademy.uvacs.makespot.com, request: "GET /admin/modules HTTP/1.1", host: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script args
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "q="
2012/08/15 16:05:06 [debug] 33749#0: *10 http script capture: "admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script regex end
2012/08/15 16:05:06 [notice] 33749#0: *10 rewritten data: "/index.php", args: "q=admin/modules", client: 128.143.71.4, server: openacademy.uvacs.makespot.com, request: "GET /admin/modules HTTP/1.1", host: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 post rewrite phase: 5
2012/08/15 16:05:06 [debug] 33749#0: *10 uri changes: 10
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "/"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "hosting"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "robots.txt"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "progress"
2012/08/15 16:05:06 [debug] 33749#0: *10 test location: "index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 using configuration "=/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http cl:-1 max:104857600
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 3
2012/08/15 16:05:06 [debug] 33749#0: *10 rewrite phase: 4
2012/08/15 16:05:06 [debug] 33749#0: *10 http script value: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script set $nocache
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "Skip"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script regex: "(?:OctopusNCookie|Args|Skip)"
2012/08/15 16:05:06 [notice] 33749#0: *10 "(?:OctopusNCookie|Args|Skip)" matches "Skip", client: 128.143.71.4, server: openacademy.uvacs.makespot.com, request: "GET /admin/modules HTTP/1.1", host: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script if
2012/08/15 16:05:06 [debug] 33749#0: *10 http script value: "NoCache"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script set $nocache
2012/08/15 16:05:06 [debug] 33749#0: *10 post rewrite phase: 5
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 6
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 7
2012/08/15 16:05:06 [debug] 33749#0: *10 generic phase: 8
2012/08/15 16:05:06 [debug] 33749#0: *10 access phase: 9
2012/08/15 16:05:06 [debug] 33749#0: *10 access phase: 10
2012/08/15 16:05:06 [debug] 33749#0: *10 post access phase: 11
2012/08/15 16:05:06 [debug] 33749#0: *10 try files phase: 12
2012/08/15 16:05:06 [debug] 33749#0: *10 upload-progress: ngx_http_uploadprogress_content_handler
2012/08/15 16:05:06 [debug] 33749#0: *10 http init upstream, client timer: 0
2012/08/15 16:05:06 [debug] 33749#0: *10 epoll add event: fd:16 op:3 ev:80000005
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1" "normal"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "/admin/modules" "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map started
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http regex set $session_id to "C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4;"
2012/08/15 16:05:06 [debug] 33749#0: *10 http map: "DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1" "C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4;"
2012/08/15 16:05:06 [debug] 33749#0: *10 parse header: "Cookie: DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: ""
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "normal"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "GET"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4;"
2012/08/15 16:05:06 [debug] 33749#0: *10 http cache key: "normalopenacademy.uvacs.makespot.comGET/admin/modulesC_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4;"
2012/08/15 16:05:06 [debug] 33749#0: *10 parse header: "Cookie: DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "NoCache"
2012/08/15 16:05:06 [debug] 33749#0: *10 posix_memalign: 0000000000A240A0:4096 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SCRIPT_FILENAME"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SCRIPT_FILENAME: /data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "QUERY_STRING"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "q=admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "QUERY_STRING: q=admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "REQUEST_METHOD"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "GET"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "REQUEST_METHOD: GET"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "CONTENT_TYPE"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "CONTENT_TYPE: "
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "CONTENT_LENGTH"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "CONTENT_LENGTH: "
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SCRIPT_NAME"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SCRIPT_NAME: /index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "REQUEST_URI"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "REQUEST_URI: /admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "DOCUMENT_URI"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "DOCUMENT_URI: /index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "DOCUMENT_ROOT"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "/data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "DOCUMENT_ROOT: /data/disk/wizard/static/platforms/openacademy-7.x-1.x-dev"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SERVER_PROTOCOL"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "HTTP/1.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SERVER_PROTOCOL: HTTP/1.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "GATEWAY_INTERFACE"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "CGI/1.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "GATEWAY_INTERFACE: CGI/1.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SERVER_SOFTWARE"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "ApacheSolaris/"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "1.3.0"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SERVER_SOFTWARE: ApacheSolaris/1.3.0"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "REMOTE_ADDR"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "128.143.71.4"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "REMOTE_ADDR: 128.143.71.4"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "REMOTE_PORT"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "29314"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "REMOTE_PORT: 29314"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SERVER_ADDR"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "10.62.134.43"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SERVER_ADDR: 10.62.134.43"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SERVER_PORT"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "80"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SERVER_PORT: 80"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "SERVER_NAME"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "SERVER_NAME: openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "USER_DEVICE"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script var: "normal"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "USER_DEVICE: normal"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "REDIRECT_STATUS"
2012/08/15 16:05:06 [debug] 33749#0: *10 http script copy: "200"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "REDIRECT_STATUS: 200"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_HOST: openacademy.uvacs.makespot.com"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_CONNECTION: keep-alive"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_CACHE_CONTROL: max-age=0"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_USER_AGENT: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.1 (KHTML, like Gecko) Chrome/21.0.1180.75 Safari/537.1"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_ACCEPT: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_ACCEPT_ENCODING: gzip,deflate,sdch"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_ACCEPT_LANGUAGE: en-US,en;q=0.8"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_ACCEPT_CHARSET: ISO-8859-1,utf-8;q=0.7,*;q=0.3"
2012/08/15 16:05:06 [debug] 33749#0: *10 fastcgi param: "HTTP_COOKIE: DrupalAdminToolbar=activeTab%3Dadmin-menu; SESS232d0ec4dc6957d9dd9075d0ab59bc7d=C_JXjcaGIthXR1kT_S92QU6wuMTn8zo46eH-CBZXOp4; Drupal.tableDrag.showWeight=0; has_js=1"
2012/08/15 16:05:06 [debug] 33749#0: *10 http cleanup add: 0000000000A23F70
2012/08/15 16:05:06 [debug] 33749#0: *10 get rr peer, try: 1
2012/08/15 16:05:06 [debug] 33749#0: *10 socket 17
2012/08/15 16:05:06 [debug] 33749#0: *10 epoll add connection: fd:17 ev:80000005
2012/08/15 16:05:06 [debug] 33749#0: *10 connect to 127.0.0.1:9090, fd:17 #11
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream connect: -2
2012/08/15 16:05:06 [debug] 33749#0: *10 posix_memalign: 00000000007057C0:128 @16
2012/08/15 16:05:06 [debug] 33749#0: *10 event timer add: 17: 60000:1345046766529
2012/08/15 16:05:06 [debug] 33749#0: *10 http finalize request: -4, "/index.php?q=admin/modules" a:1, c:3
2012/08/15 16:05:06 [debug] 33749#0: *10 http request count:3 blk:0
2012/08/15 16:05:06 [debug] 33749#0: *10 http finalize request: -4, "/index.php?q=admin/modules" a:1, c:2
2012/08/15 16:05:06 [debug] 33749#0: *10 http request count:2 blk:0
2012/08/15 16:05:06 [debug] 33749#0: post event 0000000000A80A10
2012/08/15 16:05:06 [debug] 33749#0: *10 post event 0000000000AE8BC0
2012/08/15 16:05:06 [debug] 33749#0: *10 post event 0000000000AE8D60
2012/08/15 16:05:06 [debug] 33749#0: delete posted event 0000000000A80A10
2012/08/15 16:05:06 [debug] 33749#0: accept on 0.0.0.0:80, ready: 1
2012/08/15 16:05:06 [debug] 33749#0: posix_memalign: 00000000006F2290:256 @16
2012/08/15 16:05:06 [debug] 33749#0: *12 accept: 128.143.71.4 fd:18
2012/08/15 16:05:06 [debug] 33749#0: *12 event timer add: 18: 60000:1345046766532
2012/08/15 16:05:06 [debug] 33749#0: *12 epoll add event: fd:18 op:1 ev:80000001
2012/08/15 16:05:06 [debug] 33749#0: posix_memalign: 00000000006F23A0:256 @16
2012/08/15 16:05:06 [debug] 33749#0: *13 accept: 128.143.71.4 fd:19
2012/08/15 16:05:06 [debug] 33749#0: *13 event timer add: 19: 60000:1345046766532
2012/08/15 16:05:06 [debug] 33749#0: *13 epoll add event: fd:19 op:1 ev:80000001
2012/08/15 16:05:06 [debug] 33749#0: accept() not ready (11: Resource temporarily unavailable)
2012/08/15 16:05:06 [debug] 33749#0: *10 delete posted event 0000000000AE8D60
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream request: "/index.php?q=admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream send request handler
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream send request
2012/08/15 16:05:06 [debug] 33749#0: *10 chain writer buf fl:0 s:1208
2012/08/15 16:05:06 [debug] 33749#0: *10 chain writer in: 0000000000A23FA8
2012/08/15 16:05:06 [debug] 33749#0: *10 writev: 1208
2012/08/15 16:05:06 [debug] 33749#0: *10 chain writer out: 0000000000000000
2012/08/15 16:05:06 [debug] 33749#0: *10 event timer del: 17: 1345046766529
2012/08/15 16:05:06 [debug] 33749#0: *10 event timer add: 17: 300000:1345047006532
2012/08/15 16:05:06 [debug] 33749#0: *10 delete posted event 0000000000AE8BC0
2012/08/15 16:05:06 [debug] 33749#0: *10 http run request: "/index.php?q=admin/modules"
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream check client, write event:1, "/index.php"
2012/08/15 16:05:06 [debug] 33749#0: *10 http upstream recv(): -1 (11: Resource temporarily unavailable)
2012/08/15 16:05:10 [debug] 33749#0: *10 post event 0000000000A80D50
2012/08/15 16:05:10 [debug] 33749#0: *10 post event 0000000000AE8D60
2012/08/15 16:05:10 [debug] 33749#0: *10 delete posted event 0000000000AE8D60
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream request: "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream dummy handler
2012/08/15 16:05:10 [debug] 33749#0: *10 delete posted event 0000000000A80D50
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream request: "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream process header
2012/08/15 16:05:10 [debug] 33749#0: *10 malloc: 0000000000B58A40:131072
2012/08/15 16:05:10 [debug] 33749#0: *10 recv: fd:17 664 of 130928
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 01
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 06
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 01
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 02
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 7B
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 05
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record length: 635
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Status: 500 Internal Server Error"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Powered-By: PHP/5.3.10-1ubuntu3.2"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Cookie-Domain: .openacademy.uvacs.makespot.com"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Backend: B"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Purge-Level: none"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Set-Cookie: OctopusNoCacheID=NOCACHEBONDa9e8b0d8859bfc2828b7af199e8f7bc5; expires=Wed, 15-Aug-2012 16:05:21 GMT; path=/; domain=.openacademy.uvacs.makespot.com"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Accel-Expires: 1"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "X-Allow-Redis: YES"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Expires: Sun, 19 Nov 1978 05:00:00 GMT"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Last-Modified: Wed, 15 Aug 2012 16:05:06 +0000"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Cache-Control: no-cache, must-revalidate, post-check=0, pre-check=0"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "ETag: "1345046706""
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Content-Encoding: gzip"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Vary: Accept-Encoding"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header: "Content-type: text/html"
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi parser: 1
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi header done
2012/08/15 16:05:10 [debug] 33749#0: *10 uploadprogress error-tracker error: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 HTTP/1.1 500 Internal Server Error
Server: nginx
Date: Wed, 15 Aug 2012 16:05:10 GMT
Content-Type: text/html
Transfer-Encoding: chunked
Connection: keep-alive
X-Cookie-Domain: .openacademy.uvacs.makespot.com
X-Backend: B
X-Purge-Level: none
Set-Cookie: OctopusNoCacheID=NOCACHEBONDa9e8b0d8859bfc2828b7af199e8f7bc5; expires=Wed, 15-Aug-2012 16:05:21 GMT; path=/; domain=.openacademy.uvacs.makespot.com
X-Accel-Expires: 1
X-Allow-Redis: YES
Expires: Sun, 19 Nov 1978 05:00:00 GMT
Last-Modified: Wed, 15 Aug 2012 16:05:06 +0000
Cache-Control: no-cache, must-revalidate, post-check=0, pre-check=0
ETag: "1345046706"
Content-Encoding: gzip
Vary: Accept-Encoding

2012/08/15 16:05:10 [debug] 33749#0: *10 write new buf t:1 f:0 0000000000A24B38, pos 0000000000A24B38, size: 677 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http write filter: l:0 f:0 s:677
2012/08/15 16:05:10 [debug] 33749#0: *10 http script var: "NoCache"
2012/08/15 16:05:10 [debug] 33749#0: *10 http cacheable: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream process upstream
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe read upstream: 1
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe preread: 47
2012/08/15 16:05:10 [debug] 33749#0: *10 readv: 1:130264
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe recv chain: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe buf free s:0 t:1 f:0 0000000000B58A40, pos 0000000000B58D39, size: 47 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe length: -1
2012/08/15 16:05:10 [debug] 33749#0: *10 input buf #0 0000000000B58D39
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 01
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 03
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 01
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 08
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record byte: 00
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi record length: 8
2012/08/15 16:05:10 [debug] 33749#0: *10 http fastcgi sent end request
2012/08/15 16:05:10 [debug] 33749#0: *10 input buf 0000000000B58D39 26
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe write downstream: 1
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe write downstream flush in
2012/08/15 16:05:10 [debug] 33749#0: *10 http output filter "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http copy filter: "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http postpone filter "/index.php?q=admin/modules" 0000000000A24F50
2012/08/15 16:05:10 [debug] 33749#0: *10 http chunk: 26
2012/08/15 16:05:10 [debug] 33749#0: *10 posix_memalign: 0000000000A250B0:4096 @16
2012/08/15 16:05:10 [debug] 33749#0: *10 write old buf t:1 f:0 0000000000A24B38, pos 0000000000A24B38, size: 677 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write new buf t:1 f:0 0000000000A25038, pos 0000000000A25038, size: 4 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write new buf t:1 f:0 0000000000B58A40, pos 0000000000B58D39, size: 26 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write new buf t:0 f:0 0000000000000000, pos 000000000047EEDD, size: 2 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http write filter: l:0 f:0 s:709
2012/08/15 16:05:10 [debug] 33749#0: *10 http copy filter: 0 "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 pipe write downstream done
2012/08/15 16:05:10 [debug] 33749#0: *10 event timer del: 17: 1345047006532
2012/08/15 16:05:10 [debug] 33749#0: *10 event timer add: 17: 300000:1345047010943
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream exit: 0000000000000000
2012/08/15 16:05:10 [debug] 33749#0: *10 finalize http upstream request: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 finalize http fastcgi request
2012/08/15 16:05:10 [debug] 33749#0: *10 free rr peer 1 0
2012/08/15 16:05:10 [debug] 33749#0: *10 close http upstream connection: 17
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 00000000007057C0, unused: 48
2012/08/15 16:05:10 [debug] 33749#0: *10 event timer del: 17: 1345047010943
2012/08/15 16:05:10 [debug] 33749#0: *10 reusable connection: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http upstream temp fd: -1
2012/08/15 16:05:10 [debug] 33749#0: *10 http output filter "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http copy filter: "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http postpone filter "/index.php?q=admin/modules" 00007FFF9744E980
2012/08/15 16:05:10 [debug] 33749#0: *10 http chunk: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write old buf t:1 f:0 0000000000A24B38, pos 0000000000A24B38, size: 677 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write old buf t:1 f:0 0000000000A25038, pos 0000000000A25038, size: 4 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write old buf t:1 f:0 0000000000B58A40, pos 0000000000B58D39, size: 26 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write old buf t:0 f:0 0000000000000000, pos 000000000047EEDD, size: 2 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 write new buf t:0 f:0 0000000000000000, pos 000000000047EEDA, size: 5 file: 0, size: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 http write filter: l:1 f:0 s:714
2012/08/15 16:05:10 [debug] 33749#0: *10 http write filter limit 0
2012/08/15 16:05:10 [debug] 33749#0: *10 writev: 714
2012/08/15 16:05:10 [debug] 33749#0: *10 http write filter 0000000000000000
2012/08/15 16:05:10 [debug] 33749#0: *10 http copy filter: 0 "/index.php?q=admin/modules"
2012/08/15 16:05:10 [debug] 33749#0: *10 http finalize request: 0, "/index.php?q=admin/modules" a:1, c:1
2012/08/15 16:05:10 [debug] 33749#0: *10 set http keepalive handler
2012/08/15 16:05:10 [debug] 33749#0: *10 http close request
2012/08/15 16:05:10 [debug] 33749#0: *10 http log handler
2012/08/15 16:05:10 [debug] 33749#0: *10 run cleanup: 0000000000A23188
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000B58A40
2012/08/15 16:05:10 [debug] 33750#0: epoll add event: fd:8 op:1 ev:00000001
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 00000000006DFE40, unused: 0
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000A23090, unused: 0
2012/08/15 16:05:10 [debug] 33750#0: epoll add event: fd:9 op:1 ev:00000001
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000A240A0, unused: 0
2012/08/15 16:05:10 [debug] 33750#0: epoll add event: fd:10 op:1 ev:00000001
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000A250B0, unused: 3494
2012/08/15 16:05:10 [debug] 33749#0: *10 event timer add: 16: 65000:1345046775943
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000A1E9A0
2012/08/15 16:05:10 [debug] 33749#0: *10 free: 0000000000B50A30
2012/08/15 16:05:10 [debug] 33749#0: *10 hc free: 0000000000000000 0
2012/08/15 16:05:10 [debug] 33749#0: *10 hc busy: 0000000000000000 0
2012/08/15 16:05:10 [debug] 33749#0: *10 tcp_nodelay
2012/08/15 16:05:10 [debug] 33749#0: *10 reusable connection: 1
2012/08/15 16:05:10 [debug] 33749#0: *10 post event 0000000000A80BB0
2012/08/15 16:05:10 [debug] 33749#0: *10 delete posted event 0000000000A80BB0
2012/08/15 16:05:10 [debug] 33749#0: *10 http keepalive handler
2012/08/15 16:05:10 [debug] 33749#0: *10 malloc: 0000000000B50A30:32768
2012/08/15 16:05:10 [debug] 33749#0: *10 recv: fd:16 -1 of 32768
2012/08/15 16:05:10 [debug] 33749#0: *10 recv() not ready (11: Resource temporarily unavailable)
2012/08/15 16:05:10 [debug] 33749#0: epoll del event: fd:8 op:2 ev:00000000
2012/08/15 16:05:10 [debug] 33749#0: epoll del event: fd:9 op:2 ev:00000000
2012/08/15 16:05:10 [debug] 33749#0: epoll del event: fd:10 op:2 ev:00000000
2012/08/15 16:05:16 [debug] 33749#0: *12 malloc: 0000000000A1E9A0:1296
2012/08/15 16:05:16 [debug] 33749#0: *12 posix_memalign: 00000000006D2480:256 @16
2012/08/15 16:05:16 [debug] 33749#0: *12 malloc: 0000000000B58A40:32768
2012/08/15 16:05:16 [debug] 33749#0: *12 posix_memalign: 00000000006DFE40:4096 @16
2012/08/15 16:05:16 [debug] 33749#0: *12 http process request line
2012/08/15 16:05:16 [debug] 33749#0: *12 recv: fd:18 0 of 32768
2012/08/15 16:05:16 [info] 33749#0: *12 client prematurely closed connection while reading client request line, client: 128.143.71.4, server: _
2012/08/15 16:05:16 [debug] 33749#0: *12 http finalize request: 400, "?" a:1, c:1
2012/08/15 16:05:16 [debug] 33749#0: *12 http terminate request count:1
2012/08/15 16:05:16 [debug] 33749#0: *12 http terminate cleanup count:1 blk:0
2012/08/15 16:05:16 [debug] 33749#0: *12 http request count:1 blk:0
2012/08/15 16:05:16 [debug] 33749#0: *12 http close request
2012/08/15 16:05:16 [debug] 33749#0: *12 http log handler
2012/08/15 16:05:16 [debug] 33749#0: *12 free: 00000000006DFE40, unused: 1646
2012/08/15 16:05:16 [debug] 33749#0: *12 close http connection: 18
2012/08/15 16:05:16 [debug] 33749#0: *12 event timer del: 18: 1345046766532
2012/08/15 16:05:16 [debug] 33749#0: *12 reusable connection: 0
2012/08/15 16:05:16 [debug] 33749#0: *12 free: 0000000000B58A40
2012/08/15 16:05:16 [debug] 33749#0: *12 free: 0000000000A1E9A0
2012/08/15 16:05:16 [debug] 33749#0: *12 free: 00000000006F2290, unused: 8
2012/08/15 16:05:16 [debug] 33749#0: *12 free: 00000000006D2480, unused: 128
2012/08/15 16:05:16 [debug] 33749#0: *13 malloc: 0000000000A1E9A0:1296
2012/08/15 16:05:16 [debug] 33749#0: *13 posix_memalign: 00000000006F2290:256 @16
2012/08/15 16:05:16 [debug] 33749#0: *13 malloc: 0000000000B58A40:32768
2012/08/15 16:05:16 [debug] 33749#0: *13 posix_memalign: 00000000006DFE40:4096 @16
2012/08/15 16:05:16 [debug] 33749#0: *13 http process request line
2012/08/15 16:05:16 [debug] 33749#0: *13 recv: fd:19 0 of 32768
2012/08/15 16:05:16 [info] 33749#0: *13 client prematurely closed connection while reading client request line, client: 128.143.71.4, server: _
2012/08/15 16:05:16 [debug] 33749#0: *13 http finalize request: 400, "?" a:1, c:1
2012/08/15 16:05:16 [debug] 33749#0: *13 http terminate request count:1
2012/08/15 16:05:16 [debug] 33749#0: *13 http terminate cleanup count:1 blk:0
2012/08/15 16:05:16 [debug] 33749#0: *13 http request count:1 blk:0
2012/08/15 16:05:16 [debug] 33749#0: *13 http close request
2012/08/15 16:05:16 [debug] 33749#0: *13 http log handler
2012/08/15 16:05:16 [debug] 33749#0: *13 free: 00000000006DFE40, unused: 1646
2012/08/15 16:05:16 [debug] 33749#0: *13 close http connection: 19
2012/08/15 16:05:16 [debug] 33749#0: *13 event timer del: 19: 1345046766532
2012/08/15 16:05:16 [debug] 33749#0: *13 reusable connection: 0
2012/08/15 16:05:16 [debug] 33749#0: *13 free: 0000000000B58A40
2012/08/15 16:05:16 [debug] 33749#0: *13 free: 0000000000A1E9A0
2012/08/15 16:05:16 [debug] 33749#0: *13 free: 00000000006F23A0, unused: 8
2012/08/15 16:05:16 [debug] 33749#0: *13 free: 00000000006F2290, unused: 128