ruby - Passenger can't load Sinatra app in production? -

in development , in previous production environments, sinatra app worked fine. on recent new server deployment, can't app load @ under passenger , nginx. response says:

an error occurred while starting web application: did not write startup response in time. 

    [ 2013-08-26 16:05:23.8219 30156/7f379e621700 eventedbufferedinput.h:381 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(0)="", paused=1, socketpaused=1, nexttickinstalled=0, generation=2, error=0] start() [ 2013-08-26 16:05:23.8219 30156/7f379e621700 agents/helperagent/requesthandler.h:1300 ]: [client 19] new client accepted; new client count = 1 [ 2013-08-26 16:05:23.8219 30156/7f379e621700 eventedbufferedinput.h:146 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(0)="", paused=0, socketpaused=0, nexttickinstalled=0, generation=2, error=0] onreadable [ 2013-08-26 16:05:23.8220 30156/7f379e621700 eventedbufferedinput.h:187 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(0)="", paused=0, socketpaused=0, nexttickinstalled=0, generation=2, error=0] read 2629 bytes [ 2013-08-26 16:05:23.8220 30156/7f379e621700 eventedbufferedinput.h:235 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(2629)="qfg1dxf7s0em6fwqcdkhibtnqszinykfrjsr8jyixhghrod0ddlbsd6co1oaawed2559:document_root\x00/home/avishai/apps/myapp/current/public\x00script_name\x00\x00path_info\x00/favicon.ico\x00request_uri\x00/favicon.ico\x00server_name\x00myapp.mydomain.com\x00passenger_friendly_error_pages\x00true\x00union_station_support\x00false\x00passenger_debugger\x00false\x00passenger_show_version_in_header\x00true\x00passenger_ruby\x00/home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby\x00passenger_env\x00production\x00passenger_spawn_method\x00direct\x00passenger_user\x00\x00passenger_group\x00\x00passenger_app_type\x00rack\x00passenger_min_instances\x001\x00passenger_max_requests\x000\x00passenger_max_preloader_idle_time\x00-1\x00scgi\x001\x00query_string\x00\x00request_method\x00get\x00server_protocol\x00http/1.1\x00server_software\x00nginx/1.4.2\x00remote_addr\x0074.73.245.151\x00remote_port\x0058922\x00server_addr\x00192.155.90.5\x00server_port\x0080\x00http_host\x00myapp.mydomain.com\x00http_connection\x00keep-alive\x00http_accept\x00*/*\x00http_dnt\x001\x00http_user_agent\x00mozilla/5.0 (macintosh; intel mac os x 10_8_4) applewebkit/537.36 (khtml, gecko) chrome/29.0.1547.57 safari/537.36\x00http_accept_encoding\x00gzip,deflate,sdch\x00http_accept_language\x00en-us,en;q=0.8\x00http_cookie\x00fbm_2387994113=base_domain=.mydomain.com; ", paused=0, socketpaused=0, nexttickinstalled=0, generation=2, error=0] processbuffer [ 2013-08-26 16:05:23.8221 30156/7f379e621700 agents/helperagent/requesthandler.h:1315 ]: [client 19] event: onclientinputdata [ 2013-08-26 16:05:23.8221 30156/7f379e621700 agents/helperagent/requesthandler.h:1336 ]: [client 19] processing client data: "qfg1dxf7s0em6fwqcdkhibtnqszinykfrjsr8jyixhghrod0ddlbsd6co1oaawed2559:document_root\x00/home/avishai/apps/myapp/current/public\x00script_name\x00\x00path_info\x00/favicon.ico\x00request_uri\x00/favicon.ico\x00server_name\x00myapp.mydomain.com\x00passenger_friendly_error_pages\x00true\x00union_station_support\x00false\x00passenger_debugger\x00false\x00passenger_show_version_in_header\x00true\x00passenger_ruby\x00/home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby\x00passenger_env\x00production\x00passenger_spawn_method\x00direct\x00passenger_user\x00\x00passenger_group\x00\x00passenger_app_type\x00rack\x00passenger_min_instances\x001\x00passenger_max_requests\x000\x00passenger_max_preloader_idle_time\x00-1\x00scgi\x001\x00query_string\x00\x00request_method\x00get\x00server_protocol\x00http/1.1\x00server_software\x00nginx/1.4.2\x00remote_addr\x0074.73.245.151\x00remote_port\x0058922\x00server_addr\x00192.155.90.5\x00server_port\x0080\x00http_host\x00myapp.mydomain.com\x00http_connection\x00keep-alive\x00http_accept\x00*/*\x00http_dnt\x001\x00http_user_agent\x00mozilla/5.0 (macintosh; intel mac os x 10_8_4) applewebkit/537.36 (khtml, gecko) chrome/29.0.1547.57 safari/537.36\x00http_accept_encoding\x00gzip,deflate,sdch\x00http_accept_language\x00en-us,en;q=0.8\x00http_cookie\x00fbm_2387994113=base_domain=.mydomain.com; " [ 2013-08-26 16:05:23.8222 30156/7f379e621700 agents/helperagent/requesthandler.h:1508 ]: [client 19] given connect password: "qfg1dxf7s0em6fwqcdkhibtnqszinykfrjsr8jyixhghrod0ddlbsd6co1oaawed" [ 2013-08-26 16:05:23.8222 30156/7f379e621700 agents/helperagent/requesthandler.h:1510 ]: [client 19] connect password correct; reading header [ 2013-08-26 16:05:23.8222 30156/7f379e621700 agents/helperagent/requesthandler.h:1358 ]: [client 19] processed client data: consumed 64 bytes [ 2013-08-26 16:05:23.8222 30156/7f379e621700 agents/helperagent/requesthandler.h:1336 ]: [client 19] processing client data: "2559:document_root\x00/home/avishai/apps/myapp/current/public\x00script_name\x00\x00path_info\x00/favicon.ico\x00request_uri\x00/favicon.ico\x00server_name\x00myapp.mydomain.com\x00passenger_friendly_error_pages\x00true\x00union_station_support\x00false\x00passenger_debugger\x00false\x00passenger_show_version_in_header\x00true\x00passenger_ruby\x00/home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby\x00passenger_env\x00production\x00passenger_spawn_method\x00direct\x00passenger_user\x00\x00passenger_group\x00\x00passenger_app_type\x00rack\x00passenger_min_instances\x001\x00passenger_max_requests\x000\x00passenger_max_preloader_idle_time\x00-1\x00scgi\x001\x00query_string\x00\x00request_method\x00get\x00server_protocol\x00http/1.1\x00server_software\x00nginx/1.4.2\x00remote_addr\x0074.73.245.151\x00remote_port\x0058922\x00server_addr\x00192.155.90.5\x00server_port\x0080\x00http_host\x00myapp.mydomain.com\x00http_connection\x00keep-alive\x00http_accept\x00*/*\x00http_dnt\x001\x00http_user_agent\x00mozilla/5.0 (macintosh; intel mac os x 10_8_4) applewebkit/537.36 (khtml, gecko) chrome/29.0.1547.57 safari/537.36\x00http_accept_encoding\x00gzip,deflate,sdch\x00http_accept_language\x00en-us,en;q=0.8\x00http_cookie\x00fbm_2387994113=base_domain=.mydomain.com; " [ 2013-08-26 16:05:23.8224 30156/7f379e621700 agents/helperagent/requesthandler.h:1775 ]: [client 19] valid scgi header; not buffering request body; checking out session [ 2013-08-26 16:05:23.8225 30156/7f379e621700 eventedbufferedinput.h:367 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(2629)="qfg1dxf7s0em6fwqcdkhibtnqszinykfrjsr8jyixhghrod0ddlbsd6co1oaawed2559:document_root\x00/home/avishai/apps/myapp/current/public\x00script_name\x00\x00path_info\x00/favicon.ico\x00request_uri\x00/favicon.ico\x00server_name\x00myapp.mydomain.com\x00passenger_friendly_error_pages\x00true\x00union_station_support\x00false\x00passenger_debugger\x00false\x00passenger_show_version_in_header\x00true\x00passenger_ruby\x00/home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby\x00passenger_env\x00production\x00passenger_spawn_method\x00direct\x00passenger_user\x00\x00passenger_group\x00\x00passenger_app_type\x00rack\x00passenger_min_instances\x001\x00passenger_max_requests\x000\x00passenger_max_preloader_idle_time\x00-1\x00scgi\x001\x00query_string\x00\x00request_method\x00get\x00server_protocol\x00http/1.1\x00server_software\x00nginx/1.4.2\x00remote_addr\x0074.73.245.151\x00remote_port\x0058922\x00server_addr\x00192.155.90.5\x00server_port\x0080\x00http_host\x00myapp.mydomain.com\x00http_connection\x00keep-alive\x00http_accept\x00*/*\x00http_dnt\x001\x00http_user_agent\x00mozilla/5.0 (macintosh; intel mac os x 10_8_4) applewebkit/537.36 (khtml, gecko) chrome/29.0.1547.57 safari/537.36\x00http_accept_encoding\x00gzip,deflate,sdch\x00http_accept_language\x00en-us,en;q=0.8\x00http_cookie\x00fbm_2387994113=base_domain=.mydomain.com; ", paused=0, socketpaused=0, nexttickinstalled=0, generation=2, error=0] stop() [ 2013-08-26 16:05:23.8225 30156/7f379e621700 agents/helperagent/requesthandler.h:1860 ]: [client 19] checking out session: approot=/home/avishai/apps/myapp/current [ 2013-08-26 16:05:23.8226 30156/7f379e621700 pool2/pool.h:941 ]: asyncget(approot=/home/avishai/apps/myapp/current) [ 2013-08-26 16:05:23.8226 30156/7f379e621700 pool2/pool.h:946 ]: found existing supergroup [ 2013-08-26 16:05:23.8227 30156/7f379e621700 pool2/group.h:1003 ]: requested spawning of new process group /home/avishai/apps/myapp/current#default [ 2013-08-26 16:05:23.8229 30156/7f379e621700 pool2/group.h:727 ]: no session checked out yet: group spawning or restarting [ 2013-08-26 16:05:23.8229 30156/7f379e621700 pool2/pool.h:951 ]: asyncget() finished [ 2013-08-26 16:05:23.8229 30156/7f37a04ff700 pool2/directspawner.h:168 ]: spawning new process: approot=/home/avishai/apps/myapp/current [ 2013-08-26 16:05:23.8229 30156/7f379e621700 agents/helperagent/requesthandler.h:1358 ]: [client 19] processed client data: consumed 2565 bytes [ 2013-08-26 16:05:23.8229 30156/7f379e621700 eventedbufferedinput.h:251 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(2629)="qfg1dxf7s0em6fwqcdkhibtnqszinykfrjsr8jyixhghrod0ddlbsd6co1oaawed2559:document_root\x00/home/avishai/apps/myapp/current/public\x00script_name\x00\x00path_info\x00/favicon.ico\x00request_uri\x00/favicon.ico\x00server_name\x00myapp.mydomain.com\x00passenger_friendly_error_pages\x00true\x00union_station_support\x00false\x00passenger_debugger\x00false\x00passenger_show_version_in_header\x00true\x00passenger_ruby\x00/home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby\x00passenger_env\x00production\x00passenger_spawn_method\x00direct\x00passenger_user\x00\x00passenger_group\x00\x00passenger_app_type\x00rack\x00passenger_min_instances\x001\x00passenger_max_requests\x000\x00passenger_max_preloader_idle_time\x00-1\x00scgi\x001\x00query_string\x00\x00request_method\x00get\x00server_protocol\x00http/1.1\x00server_software\x00nginx/1.4.2\x00remote_addr\x0074.73.245.151\x00remote_port\x0058922\x00server_addr\x00192.155.90.5\x00server_port\x0080\x00http_host\x00myapp.mydomain.com\x00http_connection\x00keep-alive\x00http_accept\x00*/*\x00http_dnt\x001\x00http_user_agent\x00mozilla/5.0 (macintosh; intel mac os x 10_8_4) applewebkit/537.36 (khtml, gecko) chrome/29.0.1547.57 safari/537.36\x00http_accept_encoding\x00gzip,deflate,sdch\x00http_accept_language\x00en-us,en;q=0.8\x00http_cookie\x00fbm_2387994113=base_domain=.mydomain.com; ", paused=1, socketpaused=1, nexttickinstalled=0, generation=2, error=0] consumed 2629 bytes [ 2013-08-26 16:05:23.8242 30156/7f37a04ff700 pool2/directspawner.h:215 ]: process forked approot=/home/avishai/apps/myapp/current: pid 27926 [ 2013-08-26 16:05:24.0347 30156/7f37a04ff700 pool2/spawner.h:738 ]: [app 27926 stdout]  [ 2013-08-26 16:05:24.0645 30156/7f37a04ff700 pool2/spawner.h:730 ]: [app 27926 stdout] !> have control 1.0 [ 2013-08-26 16:05:24.0647 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] have control 1.0 [ 2013-08-26 16:05:24.0647 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] passenger_root: /home/avishai/.rvm/gems/ruby-1.9.3-p429/gems/passenger-4.0.13 [ 2013-08-26 16:05:24.0647 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] passenger_version: 4.0.13 [ 2013-08-26 16:05:24.0647 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] ruby_libdir: /home/avishai/.rvm/gems/ruby-1.9.3-p429/gems/passenger-4.0.13/lib [ 2013-08-26 16:05:24.0647 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] generation_dir: /tmp/passenger.1.0.28678/generation-2 [ 2013-08-26 16:05:24.0648 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] gupid: 15e5325-z9yudukpx5y [ 2013-08-26 16:05:24.0648 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] connect_password: wzna6zcgdsytg5s5tkq1a3zkgpm8wabmnfxyirdeyvs [ 2013-08-26 16:05:24.0648 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] app_root: /home/avishai/apps/myapp/current [ 2013-08-26 16:05:24.0648 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] app_group_name: /home/avishai/apps/myapp/current [ 2013-08-26 16:05:24.0648 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] app_type: rack [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] start_command: /home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby   /home/avishai/.rvm/gems/ruby-1.9.3-p429/gems/passenger-4.0.13/helper-scripts/rack-loader.rb [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] startup_file: config.ru [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] process_title: passenger rackapp [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] log_level: 3 [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] start_timeout: 90000 [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] environment: production [ 2013-08-26 16:05:24.0649 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] base_uri: / [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] spawn_method: direct [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] default_user: nobody [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] default_group: nogroup [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] ruby: /home/avishai/.rvm/wrappers/ruby-1.9.3-p429/ruby [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] python: python [ 2013-08-26 16:05:24.0650 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] logging_agent_address: unix:/tmp/passenger.1.0.28678/generation-2/logging [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] logging_agent_username: logging [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] logging_agent_password: kvyl30do9tvmt7rfak09icgcmzwdbxwibkgpqq9xnt3j4fsqecollpbspx4yx2gn [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] debugger: false [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] analytics: false [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>] group_secret: gutsaf7irrgfmv6agdcgg66sfuvb9aorovzpm2rgdjw [ 2013-08-26 16:05:24.0651 30156/7f37a04ff700 pool2/spawner.h:404 ]: [app 27926 stdin >>]  [ 2013-08-26 16:05:25.1863 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:25.1864 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.814 sec [ 2013-08-26 16:05:30.0005 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:30.0006 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:05:34.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:34.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:05:37.2055 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:05:39.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:39.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:05:44.0001 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:44.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:05:50.0003 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:50.0005 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:05:52.2057 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:05:55.0006 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:55.0007 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:05:59.0004 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:05:59.0006 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:06:02.4117 30156/7f37a05e3700 pool2/pool.h:608 ]: garbage collection time... [ 2013-08-26 16:06:02.4118 30156/7f37a05e3700 pool2/pool.h:657 ]: garbage collection done; next garbage collect in 300.000 sec [ 2013-08-26 16:06:04.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:04.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:07.2063 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:06:09.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:09.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:14.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:14.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:19.0004 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:19.0006 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:06:22.2058 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:06:24.0004 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:24.0006 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:06:29.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:29.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:34.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:34.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:37.2050 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:06:39.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:39.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec [ 2013-08-26 16:06:45.1031 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:45.1032 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.897 sec [ 2013-08-26 16:06:49.0004 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:50.0006 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 4.999 sec [ 2013-08-26 16:06:52.2052 30165/7f9d9d79a780 agents/loggingagent/loggingserver.h:744 ]: flushing sinks [ 2013-08-26 16:06:53.9324 30156/7f37a04ff700 pool2/implementation.cpp:767 ]: not spawn process group /home/avishai/apps/myapp/current#default: error occurred while starting web application: did not write startup response in time.      in 'void passenger::applicationpool2::spawner::throwappspawnexception(const string&, passenger::spawnexception::errorkind, passenger::applicationpool2::spawner::negotiationdetails&)' (spawner.h:666)      in 'passenger::applicationpool2::processptr passenger::applicationpool2::spawner::negotiatespawn(passenger::applicationpool2::spawner::negotiationdetails&)' (spawner.h:1133)      in 'virtual passenger::applicationpool2::processptr passenger::applicationpool2::directspawner::spawn(const passenger::applicationpool2::options&)' (directspawner.h:213)      in 'void passenger::applicationpool2::group::spawnthreadrealmain(const spawnerptr&, const passenger::applicationpool2::options&, unsigned int)' (implementation.cpp:700)  [ 2013-08-26 16:06:53.9325 30156/7f37a04ff700 pool2/group.h:446 ]: enabling disabling processes result dr_error [ 2013-08-26 16:06:53.9325 30156/7f37a04ff700 pool2/implementation.cpp:786 ]: spawn loop done [ 2013-08-26 16:06:53.9326 30156/7f379e621700 agents/helperagent/requesthandler.h:1896 ]: [client 19] cannot checkout session. error occurred while starting web application: did not write startup response in time. [ 2013-08-26 16:06:53.9329 30156/7f379e621700 agents/helperagent/requesthandler.h:1162 ]: [client 19] forwarding 187 bytes of application data client. [ 2013-08-26 16:06:53.9329 30156/7f379e621700 agents/helperagent/requesthandler.h:1182 ]: [client 19] managed forward 187 bytes. [ 2013-08-26 16:06:53.9330 30156/7f379e621700 agents/helperagent/requesthandler.h:1162 ]: [client 19] forwarding 3326 bytes of application data client. [ 2013-08-26 16:06:53.9330 30156/7f379e621700 agents/helperagent/requesthandler.h:1182 ]: [client 19] managed forward 3326 bytes. [ 2013-08-26 16:06:53.9330 30156/7f379e621700 agents/helperagent/requesthandler.h:1192 ]: [client 19] client output pipe ended; disconnecting client [ 2013-08-26 16:06:53.9330 30156/7f379e621700 agents/helperagent/requesthandler.h:576 ]: [client 19] disconnected; new client count = 0 [ 2013-08-26 16:06:53.9331 30156/7f379e621700 eventedbufferedinput.h:353 ]: [eventedbufferedinput 0x7f3784002a50 fd=-1, state=live, buffer(0)="", paused=1, socketpaused=1, nexttickinstalled=0, generation=1, error=0] destroyed [ 2013-08-26 16:06:53.9331 30156/7f379e621700 eventedbufferedinput.h:353 ]: [eventedbufferedinput 0x7f3784000980 fd=19, state=live, buffer(0)="", paused=1, socketpaused=1, nexttickinstalled=0, generation=2, error=0] destroyed [ 2013-08-26 16:06:55.0002 30156/7f37a0624700 pool2/pool.h:723 ]: analytics collection time... [ 2013-08-26 16:06:55.0003 30156/7f37a0624700 pool2/pool.h:827 ]: analytics collection done; next analytics collection in 5.000 sec 

i removed logging code referencing stdout in application code. how can app boot properly?

there may multiple reasons why happens.

"debugging application startup problems" phusion passenger wiki explains how can debug startup timeout problems.