What steps will reproduce the problem? - I can't reproduce this on my development system (which has pretty equal software installed)
- Failure is raised on a production server, that was upgraded to Rails 2.3.18 recently. System ran fine over months with passenger 3.0.?? & Rails 2.3.17.
What is the expected output? What do you see instead? Login to that app - instead I get a passenger error page. Fail-/Logreport is pasted to bottom of this text!
What version of Phusion Passenger are you using? Which version of Rails? On what operating system? - Passenger 4.0.5 - Rails 2.3.18 - Nginx 1.4.1 (as provided by installer) - Ubuntu 12.04
Please provide any additional information below.
[ 2013-06-27 10:26:35.6230 4549/7f601eff2740 agents/Base.cpp:1575 ]: Random seed: 1372321595
[ 2013-06-27 10:26:35.6230 4549/7f601eff2740 agents/Watchdog/Main.cpp:543 ]: Starting Watchdog...
[ 2013-06-27 10:26:35.6231 4549/7f601eff2740 agents/Watchdog/Main.cpp:440 ]: Options: { 'analytics_log_user' => 'nobody', 'default_group' => 'nogroup', 'default_python' => 'python', 'default_ruby' => '/usr/bin/ruby1.8', 'default_user' => 'nobody', 'log_level' => '1', 'max_instances_per_app' => '0', 'max_pool_size' => '6', 'passenger_root' => '/usr/lib/ruby/gems/1.8/gems/passenger-4.0.5', 'pool_idle_time' => '300', 'temp_dir' => '/tmp', 'union_station_gateway_address' => 'gateway.unionstationapp.com', 'user_switching' => 'true', 'web_server_pid' => '4547', 'web_server_type' => 'nginx', 'web_server_worker_gid' => '1000', 'web_server_worker_uid' => '1000' }
[ 2013-06-27 10:26:35.6254 4553/7f053ec35740 agents/Base.cpp:1575 ]: Random seed: 1372321595
[ 2013-06-27 10:26:35.6269 4553/7f053ec35740 agents/HelperAgent/Main.cpp:555 ]: PassengerHelperAgent online, listening at unix:/tmp/passenger.1.0.4547/generation-0/request
[ 2013-06-27 10:26:35.6315 4561/7f6875627740 agents/Base.cpp:1575 ]: Random seed: 1372321595
[ 2013-06-27 10:26:35.6336 4561/7f6875627740 agents/LoggingAgent/Main.cpp:271 ]: PassengerLoggingAgent online, listening at unix:/tmp/passenger.1.0.4547/generation-0/logging
[ 2013-06-27 10:26:35.6337 4549/7f601eff2740 agents/Watchdog/Main.cpp:564 ]: All Phusion Passenger agents started!
[ 2013-06-27 10:26:37.3731 4553/7f0527fff700 agents/HelperAgent/RequestHandler.h:1297 ]: [Client 20] New client accepted; new client count = 1
[ 2013-06-27 10:26:37.3732 4553/7f0527fff700 Pool2/Pool.h:940 ]: Spawning new SuperGroup
[ 2013-06-27 10:26:37.3734 4553/7f0527fff700 Pool2/Pool.h:944 ]: asyncGet() finished
[ 2013-06-27 10:26:37.3734 4553/7f053ebe1700 Pool2/Pool.h:562 ]: Garbage collection time...
[ 2013-06-27 10:26:37.3735 4553/7f053ebe1700 Pool2/Pool.h:634 ]: Garbage collection done; next garbage collect in 300.000 sec
[ 2013-06-27 10:26:37.3736 4553/7f053eb7f700 Pool2/Group.h:1002 ]: Requested spawning of new process for group /opt/openmetrics/mongrel_cluster/webapps/openmetrics#default
[ 2013-06-27 10:26:37.3737 4553/7f053eb7f700 Pool2/Group.h:726 ]: No session checked out yet: group is spawning or restarting
[ 2013-06-27 10:26:37.3738 4553/7f053eb3e700 Pool2/SmartSpawner.h:734 ]: Spawning new process: appRoot=/opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:37.3738 4553/7f053eb3e700 Pool2/SmartSpawner.h:210 ]: Spawning new preloader: appRoot=/opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:37.3741 4553/7f053eb3e700 Pool2/SmartSpawner.h:256 ]: Preloader process forked for appRoot=/opt/openmetrics/mongrel_cluster/webapps/openmetrics: PID 5393
[ 2013-06-27 10:26:37.3795 4553/7f053eb3e700 Pool2/Spawner.h:739 ]: [App 5393 stdout]
[ 2013-06-27 10:26:37.3817 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 5393 stdout] !> I have control 1.0
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] You have control 1.0
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] passenger_root: /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] ruby_libdir: /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] passenger_version: 4.0.5
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] generation_dir: /tmp/passenger.1.0.4547/generation-0
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] app_root: /opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] app_group_name: /opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] app_type: classic-rails
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] start_command: /usr/bin/ruby1.8/usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/helper-scripts/classic-rails-loader.rb
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] process_title: Passenger ClassicRailsApp
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] log_level: 1
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] start_timeout: 90000
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] environment: production
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] base_uri: /
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] spawn_method: smart
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] default_user: nobody
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] default_group: nogroup
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] ruby: /usr/bin/ruby1.8
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] python: python
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] logging_agent_address: unix:/tmp/passenger.1.0.4547/generation-0/logging
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] logging_agent_username: logging
[ 2013-06-27 10:26:37.3818 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] logging_agent_password: KJ0rIdINRTN5WDJtVCojfo7OjPc2rz3eUxHlnOqjTA8LbW9ohmYzVBaHq5Hs6p1F
[ 2013-06-27 10:26:37.3819 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] debugger: false
[ 2013-06-27 10:26:37.3819 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>] analytics: false
[ 2013-06-27 10:26:37.3819 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 5393 stdin >>]
[ 2013-06-27 10:26:39.6749 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 5393 stdout] !> Ready
[ 2013-06-27 10:26:39.6749 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 5393 stdout] !> socket: unix:/tmp/passenger.1.0.4547/generation-0/backends/preloader.5393
[ 2013-06-27 10:26:39.6749 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 5393 stdout] !>
[ 2013-06-27 10:26:39.6749 4553/7f053eafd700 Pool2/Spawner.h:159 ]: [App 5393 stderr] /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib/phusion_passenger/preloader_shared_helpers.rb:109:in select'
[ 2013-06-27 10:26:39.6750 4553/7f053eafd700 Pool2/Spawner.h:159 ]: [App 5393 stderr] : wrong number of arguments (1 for 3) (ArgumentError)
[ 2013-06-27 10:26:39.6750 4553/7f053eafd700 Pool2/Spawner.h:159 ]: [App 5393 stderr] from /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib/phusion_passenger/preloader_shared_helpers.rb:109:in
run_main_loop'
[ 2013-06-27 10:26:39.6750 4553/7f053eafd700 Pool2/Spawner.h:159 ]: [App 5393 stderr] from /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/helper-scripts/classic-rails-preloader.rb:157
[ 2013-06-27 10:26:39.6754 4553/7f053eb3e700 Pool2/SmartSpawner.h:301 ]: Preloader for /opt/openmetrics/mongrel_cluster/webapps/openmetrics started on PID 5393, listening on unix:/tmp/passenger.1.0.4547/generation-0/backends/preloader.5393
[ 2013-06-27 10:26:39.6788 4553/7f053eb3e700 Pool2/SmartSpawner.h:681 ]: An error occurred while spawning a process: An error occurred while starting the application. Unable to connect to the preloader's socket: Cannot connect to Unix socket '/tmp/passenger.1.0.4547/generation-0/backends/preloader.5393': No such file or directory (errno=2)
[ 2013-06-27 10:26:39.6788 4553/7f053eb3e700 Pool2/SmartSpawner.h:682 ]: The application preloader seems to have crashed, restarting it and trying again...
[ 2013-06-27 10:26:39.6987 4553/7f053eb3e700 Pool2/SmartSpawner.h:210 ]: Spawning new preloader: appRoot=/opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:39.6992 4553/7f053eb3e700 Pool2/SmartSpawner.h:256 ]: Preloader process forked for appRoot=/opt/openmetrics/mongrel_cluster/webapps/openmetrics: PID 6310
[ 2013-06-27 10:26:39.7067 4553/7f053eb3e700 Pool2/Spawner.h:739 ]: [App 6310 stdout]
[ 2013-06-27 10:26:39.7100 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 6310 stdout] !> I have control 1.0
[ 2013-06-27 10:26:39.7101 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] You have control 1.0
[ 2013-06-27 10:26:39.7101 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] passenger_root: /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5
[ 2013-06-27 10:26:39.7101 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] ruby_libdir: /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] passenger_version: 4.0.5
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] generation_dir: /tmp/passenger.1.0.4547/generation-0
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] app_root: /opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] app_group_name: /opt/openmetrics/mongrel_cluster/webapps/openmetrics
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] app_type: classic-rails
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] start_command: /usr/bin/ruby1.8/usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/helper-scripts/classic-rails-loader.rb
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] process_title: Passenger ClassicRailsApp
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] log_level: 1
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] start_timeout: 90000
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] environment: production
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] base_uri: /
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] spawn_method: smart
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] default_user: nobody
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] default_group: nogroup
[ 2013-06-27 10:26:39.7102 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] ruby: /usr/bin/ruby1.8
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] python: python
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] logging_agent_address: unix:/tmp/passenger.1.0.4547/generation-0/logging
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] logging_agent_username: logging
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] logging_agent_password: KJ0rIdINRTN5WDJtVCojfo7OjPc2rz3eUxHlnOqjTA8LbW9ohmYzVBaHq5Hs6p1F
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] debugger: false
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>] analytics: false
[ 2013-06-27 10:26:39.7103 4553/7f053eb3e700 Pool2/SmartSpawner.h:356 ]: [App 6310 stdin >>]
[ 2013-06-27 10:26:41.9924 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 6310 stdout] !> Ready
[ 2013-06-27 10:26:41.9924 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 6310 stdout] !> socket: unix:/tmp/passenger.1.0.4547/generation-0/backends/preloader.6310
[ 2013-06-27 10:26:41.9925 4553/7f053eb3e700 Pool2/Spawner.h:731 ]: [App 6310 stdout] !>
[ 2013-06-27 10:26:41.9928 4553/7f053eb3e700 Pool2/SmartSpawner.h:301 ]: Preloader for /opt/openmetrics/mongrel_cluster/webapps/openmetrics started on PID 6310, listening on unix:/tmp/passenger.1.0.4547/generation-0/backends/preloader.6310
[ 2013-06-27 10:26:41.0006 4553/7f053eaec700 Pool2/Implementation.cpp:1174 ]: [App 6310 stderr] /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib/phusion_passenger/preloader_shared_helpers.rb:109:in select': wrong number of arguments (1 for 3) (ArgumentError)
[ 2013-06-27 10:26:41.0007 4553/7f053eaec700 Pool2/Implementation.cpp:1174 ]: [App 6310 stderr] from /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/lib/phusion_passenger/preloader_shared_helpers.rb:109:in
run_main_loop'
[ 2013-06-27 10:26:41.0007 4553/7f053eaec700 Pool2/Implementation.cpp:1174 ]: [App 6310 stderr] from /usr/lib/ruby/gems/1.8/gems/passenger-4.0.5/helper-scripts/classic-rails-preloader.rb:157
[ 2013-06-27 10:26:42.0208 4553/7f053eb3e700 Pool2/Implementation.cpp:774 ]: Could not spawn process for group /opt/openmetrics/mongrel_cluster/webapps/openmetrics#default: An error occurred while starting the application. Unable to connect to the preloader's socket: Cannot connect to Unix socket '/tmp/passenger.1.0.4547/generation-0/backends/preloader.6310': No such file or directory (errno=2)
in 'void Passenger::ApplicationPool2::SmartSpawner::throwPreloaderSpawnException(const string&, Passenger::SpawnException::ErrorKind, Passenger::ApplicationPool2::Spawner::BackgroundIOCapturerPtr&, const DebugDirPtr&)' (SmartSpawner.h:150)
in 'Passenger::ApplicationPool2::SmartSpawner::SpawnResult Passenger::ApplicationPool2::SmartSpawner::sendSpawnCommand(const Passenger::ApplicationPool2::Options&)' (SmartSpawner.h:596)
in 'Passenger::ApplicationPool2::SmartSpawner::SpawnResult Passenger::ApplicationPool2::SmartSpawner::sendSpawnCommandAgain(const Exception&, const Passenger::ApplicationPool2::Options&) [with Exception = Passenger::SpawnException]' (SmartSpawner.h:680)
in 'virtual Passenger::ApplicationPool2::ProcessPtr Passenger::ApplicationPool2::SmartSpawner::spawn(const Passenger::ApplicationPool2::Options&)' (SmartSpawner.h:748)
in 'void Passenger::ApplicationPool2::Group::spawnThreadRealMain(const SpawnerPtr&, const Passenger::ApplicationPool2::Options&, unsigned int)' (Implementation.cpp:707)
[ 2013-06-27 10:26:42.0209 4553/7f053eb3e700 Pool2/Group.h:445 ]: Enabling all DISABLING processes with result DR_ERROR [ 2013-06-27 10:26:42.0209 4553/7f053eb3e700 Pool2/Implementation.cpp:793 ]: Spawn loop done [ 2013-06-27 10:26:42.0211 4553/7f0527fff700 agents/HelperAgent/RequestHandler.h:1885 ]: [Client 20] Cannot checkout session. An error occurred while starting the application. Unable to connect to the preloader's socket: Cannot connect to Unix socket '/tmp/passenger.1.0.4547/generation-0/backends/preloader.6310': No such file or directory (errno=2) [ 2013-06-27 10:26:42.0213 4553/7f0527fff700 agents/HelperAgent/RequestHandler.h:573 ]: [Client 20] Disconnected; new client count = 0
Comment #1
Posted on Jul 2, 2013 by Happy Camel... nevermind. I included ActionView::Helpers in one of my models. That caused this issue.
Comment #2
Posted on Jul 5, 2013 by Grumpy CamelAh yeah, by doing that you overwrote the select() function in the global namespace.
Status: Invalid
Labels:
Type-Defect
Priority-Medium
Nginx