Passenger error page - SpawnPreparer: Permission denied on CentOS Linux 6.4, Apache 2, Passenger 4
3,429 views
Skip to first unread message
Phillip
Aug 4, 2013, 8:20:07 AM8/4/13
to
Originally titled "Cannot execute "/home/....../2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)"
On a new server installation, I am getting '"...SpawnPreparer": Permission denied (errno=13)'on the Passenger error page.
Ruby and Rails are installed in the home directory of a non-root user and all directories are shown as world executable as described in Step 4:loosen permissions of Phusion Passenger users guide, Apache version.
I have read Debugging application startup problems · phusion/passenger Wiki https://github.com/phusion/passenger/wiki/Debugging-application-startup-problems
On a new server installation, I am getting '"...SpawnPreparer": Permission denied (errno=13)'on the Passenger error page.
Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)
- Application root
- /var/www/testapp
- Environment (value of RAILS_ENV, RACK_ENV, WSGI_ENV and PASSENGER_ENV)
- production
- Ruby interpreter command
/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby
- User and groups
Unknown
- Environment variables
Unknown
- Ulimits
Unknown
Ruby and Rails are installed in the home directory of a non-root user and all directories are shown as world executable as described in Step 4:loosen permissions of Phusion Passenger users guide, Apache version.
I have read Debugging application startup problems · phusion/passenger Wiki https://github.com/phusion/passenger/wiki/Debugging-application-startup-problems
The article pertains to messages:
An error occurred while starting up the preloader. It sent an unknown response type "".
An error occurred while starting up the preloader: it did not write a startup response in time.
An error occurred while starting the web application: it did not write a handshake message in time.
An error occurred while starting the web application. It sent an unknown response type "".
The log shows that Apache and Passenger are starting, but Passenger spawn is unsuccessful.
Log: stopping/starting Apache and a call to testapp application
An error occurred while starting up the preloader. It sent an unknown response type "".
An error occurred while starting up the preloader: it did not write a startup response in time.
An error occurred while starting the web application: it did not write a handshake message in time.
An error occurred while starting the web application. It sent an unknown response type "".
but I am getting
Cannot checkout session. An error occured while starting up the preloader.
Cannot checkout session. An error occured while starting up the preloader.
The test for stout redirection yields no output from /etc/bashrc; neither a file nor log message are created.
The log shows that Apache and Passenger are starting, but Passenger spawn is unsuccessful.
Log: stopping/starting Apache and a call to testapp application
Log: stopping/starting Apache and a call to testapp application[Wed Jul 31 21:40:42 2013] [notice] caught SIGTERM, shutting down
[Thu Aug 01 06:22:23 2013] [notice] suEXEC mechanism enabled (wrapper: /usr/sbin/suexec) [ 2013-08-01 06:22:23.9672 7307/2ad9d0819560 agents/Watchdog/Main.cpp:452 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nobody', 'default_python' => 'python', 'default_ruby' => '/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby', 'default_user' => 'nobody', 'log_level' => '0', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_pid' => '7306', 'web_server_type' => 'apache', 'web_server_worker_gid' => '48', 'web_server_worker_uid' => '48' } [ 2013-08-01 06:22:23.9728 7310/2b1c380a7600 agents/HelperAgent/Main.cpp:597 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.7306/generation-0/request [ 2013-08-01 06:22:23.9818 7315/2ad5f3591520 agents/LoggingAgent/Main.cpp:330 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.7306/generation-0/logging [ 2013-08-01 06:22:23.9821 7307/2ad9d0819560 agents/Watchdog/Main.cpp:635 ]: All Phusion Passenger agents started! [Thu Aug 01 06:22:24 2013] [notice] Digest: generating secret for digest authentication ... [Thu Aug 01 06:22:24 2013] [notice] Digest: done [ 2013-08-01 06:22:24.0996 7329/2b9b7221d560 agents/Watchdog/Main.cpp:452 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nobody', 'default_python' => 'python', 'default_ruby' => '/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby', 'default_user' => 'nobody', 'log_level' => '0', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_pid' => '7327', 'web_server_type' => 'apache', 'web_server_worker_gid' => '48', 'web_server_worker_uid' => '48' } [ 2013-08-01 06:22:24.1058 7332/2b7f789b9600 agents/HelperAgent/Main.cpp:597 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.7327/generation-0/request [ 2013-08-01 06:22:24.1115 7337/2b5cfed1e520 agents/LoggingAgent/Main.cpp:330 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.7327/generation-0/logging [ 2013-08-01 06:22:24.1118 7329/2b9b7221d560 agents/Watchdog/Main.cpp:635 ]: All Phusion Passenger agents started! [Thu Aug 01 06:22:24 2013] [notice] Apache/2.2.15 (Unix) DAV/2 Phusion_Passenger/4.0.10 PHP/5.3.3 mod_ssl/2.2.15 OpenSSL/1.0.0-fips mod_perl/2.0.4 Perl/v5.10.1 configured -- resuming normal operations
[ 2013-08-01 06:23:15.5792 7332/2b7fa4225700 Pool2/Spawner.h:159 ]: [App 7389 stderr] Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13) [ 2013-08-01 06:23:15.5829 7332/2b7f98a82700 Pool2/Implementation.cpp:777 ]: Could not spawn process for group /var/www/testapp#default: An error occured while starting up the preloader. in 'void Passenger::ApplicationPool2::SmartSpawner::handleErrorResponse(Passenger::ApplicationPool2::SmartSpawner::StartupDetails&)' (SmartSpawner.h:455) in 'std::string Passenger::ApplicationPool2::SmartSpawner::negotiatePreloaderStartup(Passenger::ApplicationPool2::SmartSpawner::StartupDetails&)' (SmartSpawner.h:582) in 'void Passenger::ApplicationPool2::SmartSpawner::startPreloader()' (SmartSpawner.h:206) in 'virtual Passenger::ApplicationPool2::ProcessPtr Passenger::ApplicationPool2::SmartSpawner::spawn(const Passenger::ApplicationPool2::Options&)' (SmartSpawner.h:744) in 'void Passenger::ApplicationPool2::Group::spawnThreadRealMain(const Passenger::ApplicationPool2::SpawnerPtr&, const Passenger::ApplicationPool2::Options&, unsigned int)' (Implementation.cpp:710) [ 2013-08-01 06:23:15.5831 7332/2b7f98a00700 agents/HelperAgent/RequestHandler.h:1892 ]: [Client 20] Cannot checkout session. An error occured while starting up the preloader. Error page: Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)Phillip
Aug 4, 2013, 8:17:08 AM8/4/13
to
Here is a log with PassengerLogLevel 3
excerpt:
[ 2013-08-02 21:25:01.6243 25868/2ae198a82700 Pool2/SmartSpawner.h:256 ]: Preloader process forked for appRoot=/var/www/testapp: PID 25918[ 2013-08-02 21:25:01.6252 25868/2ae198a82700 Pool2/Spawner.h:730 ]: [App 25918 stdout] !> Error
[ 2013-08-02 21:25:01.6253 25868/2ae198a82700 Pool2/Spawner.h:730 ]: [App 25918 stdout] !>
full log; the first line shows where I shut down Apache at 06:27:42 before restarting at 21:24:43
[Fri Aug 02 06:27:42 2013] [notice] caught SIGTERM, shutting down[Fri Aug 02 21:24:43 2013] [notice] suEXEC mechanism enabled (wrapper: /usr/sbin/suexec)[ 2013-08-02 21:24:43.2442 25842/2b630e1da6a0 apache2/Hooks.cpp:1267 ]: Initializing Phusion Passenger...[ 2013-08-02 21:24:43.2876 25843/2b3f0da99560 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.2876 25843/2b3f0da99560 agents/Watchdog/Main.cpp:610 ]: Starting Watchdog...[ 2013-08-02 21:24:43.2877 25843/2b3f0da99560 agents/Watchdog/Main.cpp:452 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nobody', 'default_python' => 'python', 'default_ruby' => '/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby', 'default_user' => 'nobody', 'log_level' => '3', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_pid' => '25842', 'web_server_type' => 'apache', 'web_server_worker_gid' => '48', 'web_server_worker_uid' => '48' }[ 2013-08-02 21:24:43.3924 25846/2b593fa83600 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.3925 25846/2b593fa83600 agents/HelperAgent/Main.cpp:590 ]: Starting PassengerHelperAgent...[ 2013-08-02 21:24:43.3959 25846/2b593fa83600 agents/HelperAgent/Main.cpp:597 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.25842/generation-0/request[ 2013-08-02 21:24:43.4523 25852/2b85540c3520 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.4525 25852/2b85540c3520 agents/LoggingAgent/Main.cpp:342 ]: Starting PassengerLoggingAgent...[ 2013-08-02 21:24:43.4621 25852/2b85540c3520 agents/LoggingAgent/Main.cpp:330 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.25842/generation-0/logging[ 2013-08-02 21:24:43.4624 25843/2b3f0da99560 agents/Watchdog/Main.cpp:635 ]: All Phusion Passenger agents started![ 2013-08-02 21:24:43.5160 25842/2b630e1da6a0 apache2/Hooks.cpp:1548 ]: Shutting down Phusion Passenger...[ 2013-08-02 21:24:43.5169 25846/2b593fa83600 agents/HelperAgent/Main.cpp:565 ]: Received command to exit gracefully. Waiting until 5 seconds after all clients have disconnected...[ 2013-08-02 21:24:43.5172 25843/2b3f0da99560 agents/Watchdog/Main.cpp:646 ]: Web server exited gracefully; gracefully shutting down all agents...[Fri Aug 02 21:24:43 2013] [notice] Digest: generating secret for digest authentication ...[Fri Aug 02 21:24:43 2013] [notice] Digest: done[ 2013-08-02 21:24:43.5646 25863/2b630e1da6a0 apache2/Hooks.cpp:1267 ]: Initializing Phusion Passenger...[ 2013-08-02 21:24:43.5689 25865/2b7c3b33a560 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.5690 25865/2b7c3b33a560 agents/Watchdog/Main.cpp:610 ]: Starting Watchdog...[ 2013-08-02 21:24:43.5690 25865/2b7c3b33a560 agents/Watchdog/Main.cpp:452 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nobody', 'default_python' => 'python', 'default_ruby' => '/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby', 'default_user' => 'nobody', 'log_level' => '3', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_pid' => '25863', 'web_server_type' => 'apache', 'web_server_worker_gid' => '48', 'web_server_worker_uid' => '48' }[ 2013-08-02 21:24:43.5731 25868/2ae17ad31600 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.5732 25868/2ae17ad31600 agents/HelperAgent/Main.cpp:590 ]: Starting PassengerHelperAgent...[ 2013-08-02 21:24:43.5748 25868/2ae17ad31600 agents/HelperAgent/Main.cpp:597 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.25863/generation-0/request[ 2013-08-02 21:24:43.5794 25873/2ab8e04e7520 agents/Base.cpp:1575 ]: Random seed: 1375503883[ 2013-08-02 21:24:43.5797 25873/2ab8e04e7520 agents/LoggingAgent/Main.cpp:342 ]: Starting PassengerLoggingAgent...[ 2013-08-02 21:24:43.5804 25873/2ab8e04e7520 agents/LoggingAgent/Main.cpp:330 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.25863/generation-0/logging[ 2013-08-02 21:24:43.5808 25865/2b7c3b33a560 agents/Watchdog/Main.cpp:635 ]: All Phusion Passenger agents started![Fri Aug 02 21:24:43 2013] [notice] Apache/2.2.15 (Unix) DAV/2 Phusion_Passenger/4.0.10 PHP/5.3.3 mod_ssl/2.2.15 OpenSSL/1.0.0-fips mod_perl/2.0.4 Perl/v5.10.1 configured -- resuming normal operations[ 2013-08-02 21:24:46.3970 25846/2b593fac7700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:24:46.3971 25846/2b593fac7700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.603 sec[ 2013-08-02 21:24:46.5760 25868/2ae17ad75700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:24:46.5761 25868/2ae17ad75700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.424 sec[ 2013-08-02 21:24:48.3991 25846/2b593fb08700 Pool2/Pool.h:608 ]: Garbage collection time...[ 2013-08-02 21:24:48.3991 25846/2b593fb08700 Pool2/Pool.h:657 ]: Garbage collection done; next garbage collect in 300.000 sec[ 2013-08-02 21:24:48.5181 25852/2b85540c3520 agents/LoggingAgent/Main.cpp:353 ]: Logging agent exiting with code 0.[ 2013-08-02 21:24:48.5431 25846/2b593fa83600 agents/HelperAgent/Main.cpp:570 ]: It's now 5 seconds after all clients have disconnected. Proceeding with graceful exit.[ 2013-08-02 21:24:48.5432 25846/2b593fa83600 agents/HelperAgent/Main.cpp:493 ]: Shutting down helper agent...[ 2013-08-02 21:24:48.5676 25846/2b593fa83600 agents/HelperAgent/Main.cpp:500 ]: Destroying application pool...[ 2013-08-02 21:24:48.5738 25846/2b593fa83600 agents/HelperAgent/Main.cpp:512 ]: All threads have been shut down.[ 2013-08-02 21:24:48.5771 25868/2ae17adb6700 Pool2/Pool.h:608 ]: Garbage collection time...[ 2013-08-02 21:24:48.5772 25868/2ae17adb6700 Pool2/Pool.h:657 ]: Garbage collection done; next garbage collect in 300.000 sec[ 2013-08-02 21:24:48.5775 25846/2b593fa83600 agents/HelperAgent/Main.cpp:607 ]: Helper agent exiting with code 0.[ 2013-08-02 21:24:48.5912 25862/2b3f0da99560 agents/Watchdog/Main.cpp:338 ]: All Phusion Passenger agent processes have exited. Forcing all subprocesses to shut down.[ 2013-08-02 21:24:51.0013 25868/2ae17ad75700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:24:51.0014 25868/2ae17ad75700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.999 sec[ 2013-08-02 21:24:56.0016 25868/2ae17ad75700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:24:56.0017 25868/2ae17ad75700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.998 sec[ 2013-08-02 21:24:58.5807 25873/2ab8e04e7520 agents/LoggingAgent/LoggingServer.h:744 ]: Flushing all sinks[ 2013-08-02 21:25:01.0008 25868/2ae17ad75700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:25:01.0009 25868/2ae17ad75700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.999 sec[ 2013-08-02 21:25:01.5996 25868/2ae198a00700 EventedBufferedInput.h:337 ]: [EventedBufferedInput 0x2ae19c000f20 fd=-1, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=1, error=0] created[ 2013-08-02 21:25:01.5997 25868/2ae198a00700 EventedBufferedInput.h:337 ]: [EventedBufferedInput 0x2ae19c003230 fd=-1, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=1, error=0] created[ 2013-08-02 21:25:01.5997 25868/2ae198a00700 EventedBufferedInput.h:361 ]: [EventedBufferedInput 0x2ae19c000f20 fd=-1, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=1, error=0] reset()[ 2013-08-02 21:25:01.5997 25868/2ae198a00700 EventedBufferedInput.h:381 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=2, error=0] start()[ 2013-08-02 21:25:01.5998 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1300 ]: [Client 20] New client accepted; new client count = 1[ 2013-08-02 21:25:01.5998 25868/2ae198a00700 EventedBufferedInput.h:146 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(0)="", paused=0, socketPaused=0, nextTickInstalled=0, generation=2, error=0] onReadable[ 2013-08-02 21:25:01.5999 25868/2ae198a00700 EventedBufferedInput.h:187 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(0)="", paused=0, socketPaused=0, nextTickInstalled=0, generation=2, error=0] read 1340 bytes[ 2013-08-02 21:25:01.5999 25868/2ae198a00700 EventedBufferedInput.h:235 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(1340)="O0dQuC5HtkIq0u1-----------------------x6aSu50Hd2dk7rleRBzNmsRobHO1270:SERVER_SOFTWARE\x00Apache/2.2.15 (CentOS)\x00SERVER_PROTOCOL\x00HTTP/1.1\x00SERVER_NAME\x00zzzzzz.com\x00SERVER_ADMIN\x00root@localhost\x00SERVER_ADDR\x0001.02.03.456\x00SERVER_PORT\x0080\x00REMOTE_ADDR\x0010.09.08.765\x00REMOTE_PORT\x0060291\x00REQUEST_METHOD\x00GET\x00QUERY_STRING\x00\x00DOCUMENT_ROOT\x00/var/www/testapp/public\x00REQUEST_URI\x00/\x00SCRIPT_NAME\x00\x00PATH_INFO\x00/\x00HTTP_HOST\x00zzzzzz.com\x00HTTP_USER_AGENT\x00Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/536.29.13 (KHTML, like Gecko) Version/6.0.4 Safari/536.29.13\x00HTTP_ACCEPT\x00text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8\x00HTTP_ACCEPT_LANGUAGE\x00en-us\x00HTTP_ACCEPT_ENCODING\x00gzip, deflate\x00HTTP_COOKIE\x00__utma=238063489.293323918.1327082150.1327082150.1327082150.1; __qca=P0-1983428902-1327082149214; fl=25\x00HTTP_CONNECTION\x00keep-alive\x00PASSENGER_STATUS_LINE\x00false\x00PASSENGER_APP_ROOT\x00/var/www/testapp\x00PASSENGER_APP_GROUP_NAME\x00/var/www/testapp\x00PASSENGER_RUBY\x00/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby\x00PASSENGER_ENV\x00production\x00PASSENGER_SPAWN_METHOD\x00smart\x00PASSENGER_USER\x00\x00PASSENGER_GROUP\x00\x00PASSENGER_APP_TYPE\x00rack\x00PASSENGER_MIN_INSTANCES\x001\x00PASSENGER_MAX_PRELOADER_IDLE_TIME\x00-1\x00PASSENGER_DEBUGGER\x00false\x00PASSENGER_SHOW_VERSION_IN_HEADER\x00true\x00PASSENGER_MAX_REQUESTS\x000\x00PASSENGER_STAT_THROTTLE_RATE\x000\x00PASSENGER_RESTART_DIR\x00\x00PASSENGER_FRIENDLY_ERROR_PAGES\x00true\x00,", paused=0, socketPaused=0, nextTickInstalled=0, generation=2, error=0] processBuffer[ 2013-08-02 21:25:01.6000 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1315 ]: [Client 20] Event: onClientInputData[ 2013-08-02 21:25:01.6000 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1336 ]: [Client 20] Processing client data: "O0dQuC5HtkIq0u1-----------------------x6aSu50Hd2dk7rleRBzNmsRobHO1270:SERVER_SOFTWARE\x00Apache/2.2.15 (CentOS)\x00SERVER_PROTOCOL\x00HTTP/1.1\x00SERVER_NAME\x00zzzzzz.com\x00SERVER_ADMIN\x00root@localhost\x00SERVER_ADDR\x0001.02.03.456\x00SERVER_PORT\x0080\x00REMOTE_ADDR\x0010.09.08.765\x00REMOTE_PORT\x0060291\x00REQUEST_METHOD\x00GET\x00QUERY_STRING\x00\x00DOCUMENT_ROOT\x00/var/www/testapp/public\x00REQUEST_URI\x00/\x00SCRIPT_NAME\x00\x00PATH_INFO\x00/\x00HTTP_HOST\x00zzzzzz.com\x00HTTP_USER_AGENT\x00Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/536.29.13 (KHTML, like Gecko) Version/6.0.4 Safari/536.29.13\x00HTTP_ACCEPT\x00text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8\x00HTTP_ACCEPT_LANGUAGE\x00en-us\x00HTTP_ACCEPT_ENCODING\x00gzip, deflate\x00HTTP_COOKIE\x00__utma=238063489.293323918.1327082150.1327082150.1327082150.1; __qca=P0-1983428902-1327082149214; fl=25\x00HTTP_CONNECTION\x00keep-alive\x00PASSENGER_STATUS_LINE\x00false\x00PASSENGER_APP_ROOT\x00/var/www/testapp\x00PASSENGER_APP_GROUP_NAME\x00/var/www/testapp\x00PASSENGER_RUBY\x00/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby\x00PASSENGER_ENV\x00production\x00PASSENGER_SPAWN_METHOD\x00smart\x00PASSENGER_USER\x00\x00PASSENGER_GROUP\x00\x00PASSENGER_APP_TYPE\x00rack\x00PASSENGER_MIN_INSTANCES\x001\x00PASSENGER_MAX_PRELOADER_IDLE_TIME\x00-1\x00PASSENGER_DEBUGGER\x00false\x00PASSENGER_SHOW_VERSION_IN_HEADER\x00true\x00PASSENGER_MAX_REQUESTS\x000\x00PASSENGER_STAT_THROTTLE_RATE\x000\x00PASSENGER_RESTART_DIR\x00\x00PASSENGER_FRIENDLY_ERROR_PAGES\x00true\x00,"[ 2013-08-02 21:25:01.6077 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1508 ]: [Client 20] Given connect password: "O0dQuC5HtkIq0u1-----------------------x6aSu50Hd2dk7rleRBzNmsRobHO"[ 2013-08-02 21:25:01.6077 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1510 ]: [Client 20] Connect password is correct; reading header[ 2013-08-02 21:25:01.6078 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1358 ]: [Client 20] Processed client data: consumed 64 bytes[ 2013-08-02 21:25:01.6078 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1336 ]: [Client 20] Processing client data: "1270:SERVER_SOFTWARE\x00Apache/2.2.15 (CentOS)\x00SERVER_PROTOCOL\x00HTTP/1.1\x00SERVER_NAME\x00zzzzzz.com\x00SERVER_ADMIN\x00root@localhost\x00SERVER_ADDR\x0001.02.03.456\x00SERVER_PORT\x0080\x00REMOTE_ADDR\x0010.09.08.765\x00REMOTE_PORT\x0060291\x00REQUEST_METHOD\x00GET\x00QUERY_STRING\x00\x00DOCUMENT_ROOT\x00/var/www/testapp/public\x00REQUEST_URI\x00/\x00SCRIPT_NAME\x00\x00PATH_INFO\x00/\x00HTTP_HOST\x00zzzzzz.com\x00HTTP_USER_AGENT\x00Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/536.29.13 (KHTML, like Gecko) Version/6.0.4 Safari/536.29.13\x00HTTP_ACCEPT\x00text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8\x00HTTP_ACCEPT_LANGUAGE\x00en-us\x00HTTP_ACCEPT_ENCODING\x00gzip, deflate\x00HTTP_COOKIE\x00__utma=238063489.293323918.1327082150.1327082150.1327082150.1; __qca=P0-1983428902-1327082149214; fl=25\x00HTTP_CONNECTION\x00keep-alive\x00PASSENGER_STATUS_LINE\x00false\x00PASSENGER_APP_ROOT\x00/var/www/testapp\x00PASSENGER_APP_GROUP_NAME\x00/var/www/testapp\x00PASSENGER_RUBY\x00/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby\x00PASSENGER_ENV\x00production\x00PASSENGER_SPAWN_METHOD\x00smart\x00PASSENGER_USER\x00\x00PASSENGER_GROUP\x00\x00PASSENGER_APP_TYPE\x00rack\x00PASSENGER_MIN_INSTANCES\x001\x00PASSENGER_MAX_PRELOADER_IDLE_TIME\x00-1\x00PASSENGER_DEBUGGER\x00false\x00PASSENGER_SHOW_VERSION_IN_HEADER\x00true\x00PASSENGER_MAX_REQUESTS\x000\x00PASSENGER_STAT_THROTTLE_RATE\x000\x00PASSENGER_RESTART_DIR\x00\x00PASSENGER_FRIENDLY_ERROR_PAGES\x00true\x00,"[ 2013-08-02 21:25:01.6080 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1767 ]: [Client 20] Valid SCGI header; not buffering request body; checking out session[ 2013-08-02 21:25:01.6080 25868/2ae198a00700 EventedBufferedInput.h:367 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(1340)="O0dQuC5HtkIq0u1-----------------------x6aSu50Hd2dk7rleRBzNmsRobHO1270:SERVER_SOFTWARE\x00Apache/2.2.15 (CentOS)\x00SERVER_PROTOCOL\x00HTTP/1.1\x00SERVER_NAME\x00zzzzzz.com\x00SERVER_ADMIN\x00root@localhost\x00SERVER_ADDR\x0001.02.03.456\x00SERVER_PORT\x0080\x00REMOTE_ADDR\x0010.09.08.765\x00REMOTE_PORT\x0060291\x00REQUEST_METHOD\x00GET\x00QUERY_STRING\x00\x00DOCUMENT_ROOT\x00/var/www/testapp/public\x00REQUEST_URI\x00/\x00SCRIPT_NAME\x00\x00PATH_INFO\x00/\x00HTTP_HOST\x00zzzzzz.com\x00HTTP_USER_AGENT\x00Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/536.29.13 (KHTML, like Gecko) Version/6.0.4 Safari/536.29.13\x00HTTP_ACCEPT\x00text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8\x00HTTP_ACCEPT_LANGUAGE\x00en-us\x00HTTP_ACCEPT_ENCODING\x00gzip, deflate\x00HTTP_COOKIE\x00__utma=238063489.293323918.1327082150.1327082150.1327082150.1; __qca=P0-1983428902-1327082149214; fl=25\x00HTTP_CONNECTION\x00keep-alive\x00PASSENGER_STATUS_LINE\x00false\x00PASSENGER_APP_ROOT\x00/var/www/testapp\x00PASSENGER_APP_GROUP_NAME\x00/var/www/testapp\x00PASSENGER_RUBY\x00/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby\x00PASSENGER_ENV\x00production\x00PASSENGER_SPAWN_METHOD\x00smart\x00PASSENGER_USER\x00\x00PASSENGER_GROUP\x00\x00PASSENGER_APP_TYPE\x00rack\x00PASSENGER_MIN_INSTANCES\x001\x00PASSENGER_MAX_PRELOADER_IDLE_TIME\x00-1\x00PASSENGER_DEBUGGER\x00false\x00PASSENGER_SHOW_VERSION_IN_HEADER\x00true\x00PASSENGER_MAX_REQUESTS\x000\x00PASSENGER_STAT_THROTTLE_RATE\x000\x00PASSENGER_RESTART_DIR\x00\x00PASSENGER_FRIENDLY_ERROR_PAGES\x00true\x00,", paused=0, socketPaused=0, nextTickInstalled=0, generation=2, error=0] stop()[ 2013-08-02 21:25:01.6081 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1852 ]: [Client 20] Checking out session: appRoot=/var/www/testapp[ 2013-08-02 21:25:01.6082 25868/2ae198a00700 Pool2/Pool.h:941 ]: asyncGet(appRoot=/var/www/testapp)[ 2013-08-02 21:25:01.6082 25868/2ae198a00700 Pool2/Pool.h:963 ]: Spawning new SuperGroup[ 2013-08-02 21:25:01.6085 25868/2ae198a00700 Pool2/Pool.h:967 ]: asyncGet() finished[ 2013-08-02 21:25:01.6085 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1358 ]: [Client 20] Processed client data: consumed 1276 bytes[ 2013-08-02 21:25:01.6086 25868/2ae17adb6700 Pool2/Pool.h:608 ]: Garbage collection time...[ 2013-08-02 21:25:01.6085 25868/2ae198a00700 EventedBufferedInput.h:251 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(1340)="O0dQuC5HtkIq0u1-----------------------x6aSu50Hd2dk7rleRBzNmsRobHO1270:SERVER_SOFTWARE\x00Apache/2.2.15 (CentOS)\x00SERVER_PROTOCOL\x00HTTP/1.1\x00SERVER_NAME\x00zzzzzz.com\x00SERVER_ADMIN\x00root@localhost\x00SERVER_ADDR\x0001.02.03.456\x00SERVER_PORT\x0080\x00REMOTE_ADDR\x0010.09.08.765\x00REMOTE_PORT\x0060291\x00REQUEST_METHOD\x00GET\x00QUERY_STRING\x00\x00DOCUMENT_ROOT\x00/var/www/testapp/public\x00REQUEST_URI\x00/\x00SCRIPT_NAME\x00\x00PATH_INFO\x00/\x00HTTP_HOST\x00zzzzzz.com\x00HTTP_USER_AGENT\x00Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/536.29.13 (KHTML, like Gecko) Version/6.0.4 Safari/536.29.13\x00HTTP_ACCEPT\x00text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8\x00HTTP_ACCEPT_LANGUAGE\x00en-us\x00HTTP_ACCEPT_ENCODING\x00gzip, deflate\x00HTTP_COOKIE\x00__utma=238063489.293323918.1327082150.1327082150.1327082150.1; __qca=P0-1983428902-1327082149214; fl=25\x00HTTP_CONNECTION\x00keep-alive\x00PASSENGER_STATUS_LINE\x00false\x00PASSENGER_APP_ROOT\x00/var/www/testapp\x00PASSENGER_APP_GROUP_NAME\x00/var/www/testapp\x00PASSENGER_RUBY\x00/home/uzer/.rbenv/versions/2.0.0-p247/bin/ruby\x00PASSENGER_ENV\x00production\x00PASSENGER_SPAWN_METHOD\x00smart\x00PASSENGER_USER\x00\x00PASSENGER_GROUP\x00\x00PASSENGER_APP_TYPE\x00rack\x00PASSENGER_MIN_INSTANCES\x001\x00PASSENGER_MAX_PRELOADER_IDLE_TIME\x00-1\x00PASSENGER_DEBUGGER\x00false\x00PASSENGER_SHOW_VERSION_IN_HEADER\x00true\x00PASSENGER_MAX_REQUESTS\x000\x00PASSENGER_STAT_THROTTLE_RATE\x000\x00PASSENGER_RESTART_DIR\x00\x00PASSENGER_FRIENDLY_ERROR_PAGES\x00true\x00,", paused=1, socketPaused=1, nextTickInstalled=0, generation=2, error=0] Consumed 1340 bytes[ 2013-08-02 21:25:01.6086 25868/2ae17adb6700 Pool2/Pool.h:657 ]: Garbage collection done; next garbage collect in 300.000 sec[ 2013-08-02 21:25:01.6218 25868/2ae198a41700 Pool2/Implementation.cpp:180 ]: Initializing SuperGroup /var/www/testapp in the background...[ 2013-08-02 21:25:01.6219 25868/2ae198a41700 Pool2/Implementation.cpp:211 ]: Initialization of SuperGroup /var/www/testapp almost done; grabbed lock[ 2013-08-02 21:25:01.6222 25868/2ae198a41700 Pool2/Group.h:1003 ]: Requested spawning of new process for group /var/www/testapp#default[ 2013-08-02 21:25:01.6224 25868/2ae198a41700 Pool2/Group.h:727 ]: No session checked out yet: group is spawning or restarting[ 2013-08-02 21:25:01.6225 25868/2ae198a41700 Pool2/Implementation.cpp:245 ]: Done initializing SuperGroup /var/www/testapp[ 2013-08-02 21:25:01.6226 25868/2ae198a82700 Pool2/SmartSpawner.h:734 ]: Spawning new process: appRoot=/var/www/testapp[ 2013-08-02 21:25:01.6226 25868/2ae198a82700 Pool2/SmartSpawner.h:210 ]: Spawning new preloader: appRoot=/var/www/testapp[ 2013-08-02 21:25:01.6243 25868/2ae198a82700 Pool2/SmartSpawner.h:256 ]: Preloader process forked for appRoot=/var/www/testapp: PID 25918[ 2013-08-02 21:25:01.6252 25868/2ae198a82700 Pool2/Spawner.h:730 ]: [App 25918 stdout] !> Error[ 2013-08-02 21:25:01.6253 25868/2ae198a82700 Pool2/Spawner.h:730 ]: [App 25918 stdout] !> [ 2013-08-02 21:25:01.6258 25868/2ae198ca8700 Pool2/Spawner.h:159 ]: [App 25918 stderr] Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)[ 2013-08-02 21:25:01.6410 25868/2ae198a82700 Pool2/Implementation.cpp:777 ]: Could not spawn process for group /var/www/testapp#default: An error occured while starting up the preloader. in 'void Passenger::ApplicationPool2::SmartSpawner::handleErrorResponse(Passenger::ApplicationPool2::SmartSpawner::StartupDetails&)' (SmartSpawner.h:455) in 'std::string Passenger::ApplicationPool2::SmartSpawner::negotiatePreloaderStartup(Passenger::ApplicationPool2::SmartSpawner::StartupDetails&)' (SmartSpawner.h:582) in 'void Passenger::ApplicationPool2::SmartSpawner::startPreloader()' (SmartSpawner.h:206) in 'virtual Passenger::ApplicationPool2::ProcessPtr Passenger::ApplicationPool2::SmartSpawner::spawn(const Passenger::ApplicationPool2::Options&)' (SmartSpawner.h:744) in 'void Passenger::ApplicationPool2::Group::spawnThreadRealMain(const Passenger::ApplicationPool2::SpawnerPtr&, const Passenger::ApplicationPool2::Options&, unsigned int)' (Implementation.cpp:710)
[ 2013-08-02 21:25:01.6410 25868/2ae198a82700 Pool2/Group.h:446 ]: Enabling all DISABLING processes with result DR_ERROR[ 2013-08-02 21:25:01.6411 25868/2ae198a82700 Pool2/Implementation.cpp:796 ]: Spawn loop done[ 2013-08-02 21:25:01.6412 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1892 ]: [Client 20] Cannot checkout session. An error occured while starting up the preloader.Error page:Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)
[ 2013-08-02 21:25:01.6557 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1162 ]: [Client 20] Forwarding 151 bytes of application data to client.[ 2013-08-02 21:25:01.6557 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1182 ]: [Client 20] Managed to forward 151 bytes.[ 2013-08-02 21:25:01.6558 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1162 ]: [Client 20] Forwarding 3415 bytes of application data to client.[ 2013-08-02 21:25:01.6558 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1182 ]: [Client 20] Managed to forward 3415 bytes.[ 2013-08-02 21:25:01.6558 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:1192 ]: [Client 20] Client output pipe ended; disconnecting client[ 2013-08-02 21:25:01.6559 25868/2ae198a00700 agents/HelperAgent/RequestHandler.h:576 ]: [Client 20] Disconnected; new client count = 0[ 2013-08-02 21:25:01.6559 25868/2ae198a00700 EventedBufferedInput.h:353 ]: [EventedBufferedInput 0x2ae19c003230 fd=-1, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=1, error=0] destroyed[ 2013-08-02 21:25:01.6560 25868/2ae198a00700 EventedBufferedInput.h:353 ]: [EventedBufferedInput 0x2ae19c000f20 fd=20, state=LIVE, buffer(0)="", paused=1, socketPaused=1, nextTickInstalled=0, generation=2, error=0] destroyed[ 2013-08-02 21:25:06.0011 25868/2ae17ad75700 Pool2/Pool.h:723 ]: Analytics collection time...[ 2013-08-02 21:25:06.0012 25868/2ae17ad75700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.999 secPhillip
Aug 3, 2013, 9:20:49 AM8/3/13
to
Hello world! An otherwise empty application folder containing only public/index.html displays properly. Also works with original app with an index.html file.
[Sat Aug 03 06:13:45 2013] [notice] Graceful restart requested, doing restart
[ 2013-08-03 06:13:45.0394 1123/2af1fe9c36a0 apache2/Hooks.cpp:1548 ]: Shutting down Phusion Passenger...
[ 2013-08-03 06:13:45.0410 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:565 ]: Received command to exit gracefully. Waiting until 5 seconds after all clients have disconnected...
[ 2013-08-03 06:13:45.0411 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:570 ]: It's now 5 seconds after all clients have disconnected. Proceeding with graceful exit.
[ 2013-08-03 06:13:45.0411 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:493 ]: Shutting down helper agent...
[ 2013-08-03 06:13:45.0415 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:500 ]: Destroying application pool...
[ 2013-08-03 06:13:45.0416 1125/2b54ea0b0560 agents/Watchdog/Main.cpp:646 ]: Web server exited gracefully; gracefully shutting down all agents...
[ 2013-08-03 06:13:45.0538 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:512 ]: All threads have been shut down.
[ 2013-08-03 06:13:45.0540 1128/2ad8f98b2600 agents/HelperAgent/Main.cpp:607 ]: Helper agent exiting with code 0.
httpd: Could not reliably determine the server's fully qualified domain name, using host1.net for ServerName
[Sat Aug 03 06:13:45 2013] [warn] NameVirtualHost 1.2.3.4:0 has no VirtualHosts
[Sat Aug 03 06:13:45 2013] [notice] Digest: generating secret for digest authentication ...
[Sat Aug 03 06:13:45 2013] [notice] Digest: done
[ 2013-08-03 06:13:45.0681 1123/2af1fe9c36a0 apache2/Hooks.cpp:1267 ]: Initializing Phusion Passenger...
[ 2013-08-03 06:13:45.0731 1547/2b3300b95560 agents/Base.cpp:1575 ]: Random seed: 1375535625
[ 2013-08-03 06:13:45.0732 1547/2b3300b95560 agents/Watchdog/Main.cpp:610 ]: Starting Watchdog...
[ 2013-08-03 06:13:45.0733 1547/2b3300b95560 agents/Watchdog/Main.cpp:452 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nobody', 'default_python' => 'python', 'default_ruby' => '/home/garf13ld/.rbenv/versions/2.0.0-p247/bin/ruby', 'default_user' => 'nobody', 'log_level' => '2', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/home/garf13ld/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'union_station_gateway_port' => '443', 'user_switching' => 'true', 'web_server_pid' => '1123', 'web_server_type' => 'apache', 'web_server_worker_gid' => '48', 'web_server_worker_uid' => '48' }
[ 2013-08-03 06:13:45.0770 1550/2b039562d600 agents/Base.cpp:1575 ]: Random seed: 1375535625
[ 2013-08-03 06:13:45.0771 1550/2b039562d600 agents/HelperAgent/Main.cpp:590 ]: Starting PassengerHelperAgent...
[ 2013-08-03 06:13:45.0785 1550/2b039562d600 agents/HelperAgent/Main.cpp:597 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.1123/generation-1/request
[ 2013-08-03 06:13:45.0838 1555/2abce8065520 agents/Base.cpp:1575 ]: Random seed: 1375535625
[ 2013-08-03 06:13:45.0840 1555/2abce8065520 agents/LoggingAgent/Main.cpp:342 ]: Starting PassengerLoggingAgent...
[ 2013-08-03 06:13:45.0850 1555/2abce8065520 agents/LoggingAgent/Main.cpp:330 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.1123/generation-1/logging
[ 2013-08-03 06:13:45.0852 1547/2b3300b95560 agents/Watchdog/Main.cpp:635 ]: All Phusion Passenger agents started!
[Sat Aug 03 06:13:45 2013] [notice] Apache/2.2.15 (Unix) DAV/2 Phusion_Passenger/4.0.10 PHP/5.3.3 mod_ssl/2.2.15 OpenSSL/1.0.0-fips mod_perl/2.0.4 Perl/v5.10.1 configured -- resuming normal operations
[ 2013-08-03 06:13:48.0796 1550/2b0395671700 Pool2/Pool.h:723 ]: Analytics collection time...
[ 2013-08-03 06:13:48.0797 1550/2b0395671700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.920 sec
[ 2013-08-03 06:13:50.0415 1134/2b3f238e1520 agents/LoggingAgent/Main.cpp:353 ]: Logging agent exiting with code 0.
[ 2013-08-03 06:13:50.0645 1546/2b54ea0b0560 agents/Watchdog/Main.cpp:338 ]: All Phusion Passenger agent processes have exited. Forcing all subprocesses to shut down.
[ 2013-08-03 06:13:50.0804 1550/2b03956b2700 Pool2/Pool.h:608 ]: Garbage collection time...
[ 2013-08-03 06:13:50.0805 1550/2b03956b2700 Pool2/Pool.h:657 ]: Garbage collection done; next garbage collect in 300.000 sec
[ 2013-08-03 06:13:53.0013 1550/2b0395671700 Pool2/Pool.h:723 ]: Analytics collection time...
[ 2013-08-03 06:13:53.0014 1550/2b0395671700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.999 sec
[ 2013-08-03 06:13:58.0011 1550/2b0395671700 Pool2/Pool.h:723 ]: Analytics collection time...
[ 2013-08-03 06:13:58.0012 1550/2b0395671700 Pool2/Pool.h:827 ]: Analytics collection done; next analytics collection in 4.999 sec
[ 2013-08-03 06:14:00.0851 1555/2abce8065520 agents/LoggingAgent/LoggingServer.h:744 ]: Flushing all sinks
[ 2013-08-03 06:14:03.0009 1550/2b0395671700 Pool2/Pool.h:723 ]: Analytics collection time...
Sebastian Palucha
Aug 9, 2013, 7:22:11 AM8/9/13
to phusion-...@googlegroups.com
Hi Phillip,
Just an idea. Could this be related to SeLinux?
You can check status with
sestatus
and switch it to permissive mode with
setenforce 0
Sebastian
On Thursday, August 1, 2013 4:08:44 PM UTC+1, Phillip wrote:
Originally titled "Cannot execute "/home/....../2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)"
On a new server installation, I am getting '"...SpawnPreparer": Permission denied (errno=13)'on the Passenger error page.
Cannot execute "/home/uzer/.rbenv/versions/2.0.0-p247/lib/ruby/gems/2.0.0/gems/passenger-4.0.10/buildout/agents/SpawnPreparer": Permission denied (errno=13)
- Application root
- /var/www/testapp
- Environment (value of RAILS_ENV, RACK_ENV, WSGI_ENV and PASSENGER_ENV)
- production
- Ruby interpreter command
- User and groups
- Environment variables
- Ulimits
Ruby and Rails are installed in the home directory of a non-root user and all directories are shown as world executable as described in Step 4:loosen permissions of Phusion Passenger users guide, Apache version.
I have read Debugging application startup problems · phusion/passenger Wiki https://github.com/phusion/passenger/wiki/Debugging-application-startup-problems
The article pertains to messages:
An error occurred while starting up the preloader. It sent an unknown response type "".
An error occurred while starting up the preloader: it did not write a startup response in time.
An error occurred while starting the web application: it did not write a handshake message in time.
An error occurred while starting the web application. It sent an unknown response type "".
but I am getting
Cannot checkout session. An error occured while starting up the preloader.
The test for stout redirection yields no output from /etc/bashrc; neither a file nor log message are created.
The log shows that Apache and Passenger are starting, but Passenger spawn is unsuccessful.
Log: stopping/starting Apache and a call to testapp application
Phillip
Aug 10, 2013, 7:45:39 AM8/10/13
to
I can't try it at the moment because I rolled back Ruby to 1.9.3 and Passenger to 3.0.12 to get things going.
With regard to SeLinux, I had tried this and got errors:
When I made the above (version) changes, I started getting more useful error messages and ended up changing ownership of config.ru to the Passenger process owner. Now I'm into trivial start-up issues and will be up and running soon.
chcon -R -h -t httpd_sys_content_t /path/to/your/rails/app
I've filed your suggestion away because I will eventually get back to making Ruby 2 and Passenger 4 work.
Thanks for looking at this.
Thanks for looking at this.
Reply all
Reply to author
Forward
0 new messages