Redmine stops responding when trying to open a subversion repository

Added by Ronie Henrich almost 7 years ago

Hi,

Since I upgraded Redmine from 1.0.2 to 1.1.0, it stops responding everytime I try to open a subversion repository. I upgraded it today to version 1.1.1, but it did not solve this issue.

It shows nothing in its logs, neither in Apache error log, so I issued a kill SIGABRT to get the folowing full trace:
  • apache-2.2.16
    • APACHE2_MPMS="worker"
  • passenger-3.0.2
  • rails-2.3.5
  • redmine-1.1.1
  • subversion-1.6.13
SignalException in RepositoriesController#show

SIGABRT

RAILS_ROOT: /var/www/redmine
Application Trace | Framework Trace | Full Trace

/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:440:in `install_useful_signal_handlers'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `call'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `read'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `info'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:193:in `call'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:193:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:191:in `popen'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:191:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:177:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:53:in `info'
/var/www/redmine/app/models/repository/subversion.rb:44:in `fetch_changesets'
/var/www/redmine/app/controllers/repositories_controller.rb:77:in `show'

/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:440:in `install_useful_signal_handlers'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/associations/association_proxy.rb:217:in `send'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/associations/association_proxy.rb:217:in `method_missing'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:1331:in `send'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:1331:in `perform_action_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:617:in `call_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
/usr/lib/ruby/gems/1.8/gems/activesupport-2.3.5/lib/active_support/core_ext/benchmark.rb:17:in `ms'
/usr/lib/ruby/1.8/benchmark.rb:308:in `realtime'
/usr/lib/ruby/gems/1.8/gems/activesupport-2.3.5/lib/active_support/core_ext/benchmark.rb:17:in `ms'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/rescue.rb:160:in `perform_action_without_flash'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/flash.rb:146:in `perform_action'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:532:in `send'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:532:in `process_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:606:in `process'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:391:in `process'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:386:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/routing/route_set.rb:437:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:87:in `dispatch'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:121:in `_call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:130:in `build_middleware_stack'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:29:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:29:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:9:in `cache'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:28:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/connection_adapters/abstract/connection_pool.rb:361:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/string_coercion.rb:25:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/head.rb:9:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/methodoverride.rb:24:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/params_parser.rb:15:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/session/cookie_store.rb:93:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/failsafe.rb:26:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `synchronize'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:114:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/reloader.rb:34:in `run'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:108:in `call'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:513:in `accept_and_process_next_request'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:321:in `start_request_handler'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:99:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/utils.rb:479:in `safe_fork'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:84:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:230:in `spawn_rails_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:132:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:357:in `__send__'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/helper-scripts/passenger-spawn-server:99

/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:440:in `install_useful_signal_handlers'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `call'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `read'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:54:in `info'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:193:in `call'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:193:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:191:in `popen'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:191:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/abstract_adapter.rb:177:in `shellout'
/var/www/redmine/lib/redmine/scm/adapters/subversion_adapter.rb:53:in `info'
/var/www/redmine/app/models/repository/subversion.rb:44:in `fetch_changesets'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/associations/association_proxy.rb:217:in `send'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/associations/association_proxy.rb:217:in `method_missing'
/var/www/redmine/app/controllers/repositories_controller.rb:77:in `show'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:1331:in `send'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:1331:in `perform_action_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:617:in `call_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:610:in `perform_action_without_benchmark'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
/usr/lib/ruby/gems/1.8/gems/activesupport-2.3.5/lib/active_support/core_ext/benchmark.rb:17:in `ms'
/usr/lib/ruby/1.8/benchmark.rb:308:in `realtime'
/usr/lib/ruby/gems/1.8/gems/activesupport-2.3.5/lib/active_support/core_ext/benchmark.rb:17:in `ms'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/benchmarking.rb:68:in `perform_action_without_rescue'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/rescue.rb:160:in `perform_action_without_flash'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/flash.rb:146:in `perform_action'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:532:in `send'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:532:in `process_without_filters'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/filters.rb:606:in `process'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:391:in `process'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/base.rb:386:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/routing/route_set.rb:437:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:87:in `dispatch'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:121:in `_call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:130:in `build_middleware_stack'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:29:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:29:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/connection_adapters/abstract/query_cache.rb:34:in `cache'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:9:in `cache'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/query_cache.rb:28:in `call'
/usr/lib/ruby/gems/1.8/gems/activerecord-2.3.5/lib/active_record/connection_adapters/abstract/connection_pool.rb:361:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/string_coercion.rb:25:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/head.rb:9:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/methodoverride.rb:24:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/params_parser.rb:15:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/session/cookie_store.rb:93:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/failsafe.rb:26:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `call'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `synchronize'
/usr/lib/ruby/gems/1.8/gems/rack-1.0.1/lib/rack/lock.rb:11:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:114:in `call'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/reloader.rb:34:in `run'
/usr/lib/ruby/gems/1.8/gems/actionpack-2.3.5/lib/action_controller/dispatcher.rb:108:in `call'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/rack/request_handler.rb:96:in `process_request'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:513:in `accept_and_process_next_request'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_request_handler.rb:274:in `main_loop'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:321:in `start_request_handler'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:99:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/utils.rb:479:in `safe_fork'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/classic_rails/application_spawner.rb:84:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:230:in `spawn_rails_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:132:in `spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/spawn_manager.rb:275:in `handle_spawn_application'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:357:in `__send__'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:357:in `server_main_loop'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/lib/phusion_passenger/abstract_server.rb:206:in `start_synchronously'
/usr/lib/ruby/gems/1.8/gems/passenger-3.0.2/helper-scripts/passenger-spawn-server:99

Request

Parameters:

{"id"=>"svnrepo"}

Show session dump

--- 

Response

Headers:

{"Content-Type"=>"",
 "Cache-Control"=>"no-cache"}
  • ENVIRONMENT
RubyGems Environment:
  - RUBYGEMS VERSION: 1.3.7
  - RUBY VERSION: 1.8.7 (2010-08-16 patchlevel 302) [i686-linux]
  - INSTALLATION DIRECTORY: /usr/lib/ruby/gems/1.8
  - RUBY EXECUTABLE: /usr/bin/ruby18
  - EXECUTABLE DIRECTORY: /usr/bin
  - RUBYGEMS PLATFORMS:
    - ruby
    - x86-linux
  - GEM PATHS:
     - /usr/lib/ruby/gems/1.8
     - /root/.gem/ruby/1.8
  - GEM CONFIGURATION:
     - :update_sources => true
     - :verbose => true
     - :benchmark => false
     - :backtrace => false
     - :bulk_threshold => 1000
  - REMOTE SOURCES:
     - http://rubygems.org/

*** LOCAL GEMS ***

actionmailer (2.3.5)
actionpack (2.3.5)
activeldap (1.0.9)
activerecord (2.3.5)
activeresource (2.3.5)
activesupport (2.3.5)
daemon_controller (0.2.5)
fastthread (1.0.7)
file-tail (1.0.5)
hoe (2.6.1)
i18n (0.4.2)
json_pure (1.4.6)
ncurses (0.9.1)
net-ssh (2.0.23)
passenger (3.0.2)
pg (0.9.0)
rack (1.2.1, 1.0.1)
rails (2.3.5)
rake (0.8.7)
RedCloth (4.2.3)
rmagick (2.13.1)
rubyforge (2.0.4)
spruz (0.1.5)

Does anybody else have this problem?

Thanks!

Replies (4)

RE: Redmine stops responding when trying to open a subversion repository - Added by Ronie Henrich almost 7 years ago

More details about this issue:

  • postgresql-8.4.4
  • PassengerSpawnMethod conservative
    • Redmine stopped working with PassengerSpawnMethod set to smart or smart-lv2 after I upgraded postgresql from 8.2.15 to 8.4.4

After starting apache and accessing Redmine the first time, I get the folowing processes related to Redmine:

apache   27426     1 61 21:52 ?        00:00:06 Rails: /var/www/redmine
postgres 27427 11862  0 21:52 ?        00:00:00 postgres: postgres redmine 127.0.0.1(33619) idle

Right after I click on the Repository tab, one more Redmine/rails proccess is created:

apache   27426     1 16 21:52 ?        00:00:07 Rails: /var/www/redmine
postgres 27427 11862  0 21:52 ?        00:00:00 postgres: postgres redmine 127.0.0.1(33619) idle
apache   27442 27426  0 21:53 ?        00:00:00 Rails: /var/www/redmine
So, to be able to access the repository tab, I have to do this workaround everytime I start/restart Apache:
  1. Kill both Redmine/rails processes (kill -9 27442 27426);
  2. Open Redmine but not click on the Repository tab;
  3. Restart Redmine (touch /var/www/redmine/tmp/restart.txt);
  4. Open again Redmine but not click on the Repository tab;
  5. Now I can open the Repository tab;

Any ideas on how to fix it to be able to run Redmine without the workaround above?

RE: Redmine stops responding when trying to open a subversion repository - Added by Andy Bolstridge almost 7 years ago

It shows that its gone wrong accessing the svn repo - you can add some debugging lines to show what is being fetched from there - it could be that it is trying to sync the changeset cache with what's in svn.

I added this to the environment and it started logging svn commands:

config.logger = Logger.new(config.log_path, 1, 1048576)
config.logger.level = Logger::DEBUG

RE: Redmine stops responding when trying to open a subversion repository - Added by Ronie Henrich almost 7 years ago

  • I added this to Redmine's environment.rb to start logging svn commands:
    config.logger = Logger.new(config.log_path, 1, 1048576)
    config.logger.level = Logger::DEBUG
    
  • After starting Apache, Redmine hangs right after I click on a project's repository tab, and I got this in production.log:
    Processing RepositoriesController#show (for 200.101.77.88 at 2011-02-09 02:28:36) [GET]
      Parameters: {"action"=>"show", "id"=>"myproject", "controller"=>"repositories"}
      ^[[4;36;1mSQL (0.6ms)^[[0m   ^[[0;1mSELECT max("settings".updated_on) AS max_updated_on FROM "settings" ^[[0m
      ^[[4;35;1mUser Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "users" WHERE ("users"."id" = 6) AND (users.status = 1) AND ( ("users"."type" = 'User' OR "users"."type" = 'AnonymousUse$
      ^[[4;36;1mProject Load (1.1ms)^[[0m   ^[[0;1mSELECT * FROM "projects" WHERE ("projects"."identifier" = E'myproject') LIMIT 1^[[0m
      ^[[4;35;1mRepository Load (1.2ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories".project_id = 4) LIMIT 1^[[0m
      ^[[4;36;1mEnabledModule Load (0.6ms)^[[0m   ^[[0;1mSELECT name FROM "enabled_modules" WHERE ("enabled_modules".project_id = 4) ^[[0m
      ^[[4;35;1mSetting Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "settings" WHERE ("settings"."name" = E'autofetch_changesets') LIMIT 1^[[0m
    Shelling out: svn --config-dir /var/svn/.subversion info --xml 'https://redmine.foo.com.br/svn/myproject/' --no-auth-cache --non-interactive
    
  • After doing the workaround (described in my previous comment/post), I was be able to access the project's repository tab, and I got this in production.log:
    Processing RepositoriesController#show (for 200.101.77.88 at 2011-02-09 02:33:09) [GET]
      Parameters: {"action"=>"show", "id"=>"myproject", "controller"=>"repositories"}
      ^[[4;36;1mSQL (0.6ms)^[[0m   ^[[0;1mSELECT max("settings".updated_on) AS max_updated_on FROM "settings" ^[[0m
      ^[[4;35;1mUser Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "users" WHERE ("users"."id" = 6) AND (users.status = 1) AND ( ("users"."type" = 'User' OR "users"."type" = 'AnonymousUse$
      ^[[4;36;1mProject Load (0.8ms)^[[0m   ^[[0;1mSELECT * FROM "projects" WHERE ("projects"."identifier" = E'myproject') LIMIT 1^[[0m
      ^[[4;35;1mRepository Load (1.2ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories".project_id = 4) LIMIT 1^[[0m
      ^[[4;36;1mEnabledModule Load (0.7ms)^[[0m   ^[[0;1mSELECT name FROM "enabled_modules" WHERE ("enabled_modules".project_id = 4) ^[[0m
      ^[[4;35;1mSetting Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "settings" WHERE ("settings"."name" = E'autofetch_changesets') LIMIT 1^[[0m
    Shelling out: svn --config-dir /var/svn/.subversion info --xml 'https://redmine.foo.com.br/svn/myproject/' --no-auth-cache --non-interactive
      ^[[4;36;1mChangeset Load (2.3ms)^[[0m   ^[[0;1mSELECT * FROM "changesets" WHERE ("changesets".repository_id = 43) ORDER BY changesets.committed_on DESC, changesets.id DESC LI$
    Shelling out: svn --config-dir /var/svn/.subversion list --xml 'https://redmine.foo.com.br/svn/myproject/'@HEAD --no-auth-cache --non-interactive
    Found 2 entries in the repository for 'https://redmine.foo.com.br/svn/myproject/'
    Shelling out: svn --config-dir /var/svn/.subversion log --xml -r HEAD:1 --no-auth-cache --non-interactive --limit 10 'https://redmine.foo.com.br/svn/myproject/'
      ^[[4;35;1mChangeset Load (2.2ms)^[[0m   ^[[0mSELECT * FROM "changesets" WHERE ("changesets"."revision" IN (E'976',E'975',E'974',E'973',E'972',E'971',E'970',E'969',E'968',E'96$
      ^[[4;36;1mUser Load (0.9ms)^[[0m   ^[[0;1mSELECT * FROM "users" WHERE ("users"."id" = 6) AND ( ("users"."type" = 'User' OR "users"."type" = 'AnonymousUser' ) ) ^[[0m
    Shelling out: svn --config-dir /var/svn/.subversion --version
    Shelling out: svn --config-dir /var/svn/.subversion proplist --verbose --xml 'https://redmine.foo.com.br/svn/myproject/'@HEAD --no-auth-cache --non-interactive
    Rendering template within layouts/base
    Rendering repositories/show
    Rendered repositories/_navigation (19.6ms)
    Rendered repositories/_breadcrumbs (7.7ms)
      ^[[4;35;1mChangeset Load (1.1ms)^[[0m   ^[[0mSELECT * FROM "changesets" WHERE ("changesets"."revision" = E'976') AND ("changesets".repository_id = 43) ORDER BY changesets.com$
      ^[[4;36;1mRepository Load (0.6ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "users" WHERE ("users"."id" = 6) AND ( ("users"."type" = 'User' OR "users"."type" = 'AnonymousUser' ) ) ^[[0m
      ^[[4;36;1mSetting Load (0.7ms)^[[0m   ^[[0;1mSELECT * FROM "settings" WHERE ("settings"."name" = E'user_format') LIMIT 1^[[0m
      ^[[4;35;1mChangeset Load (1.0ms)^[[0m   ^[[0mSELECT * FROM "changesets" WHERE ("changesets"."revision" = E'930') AND ("changesets".repository_id = 43) ORDER BY changesets.com$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "users" WHERE ("users"."id" = 6) AND ( ("users"."type" = 'User' OR "users"."type" = 'AnonymousUser' ) ) ^[[0m
    Rendered repositories/_dir_list_content (48.8ms)
    Rendered repositories/_dir_list (52.4ms)
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mUserPreference Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "user_preferences" WHERE ("user_preferences".user_id = 6) LIMIT 1^[[0m
      ^[[4;36;1mSetting Load (0.6ms)^[[0m   ^[[0;1mSELECT * FROM "settings" WHERE ("settings"."name" = E'time_format') LIMIT 1^[[0m
      ^[[4;35;1mSetting Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "settings" WHERE ("settings"."name" = E'cache_formatted_text') LIMIT 1^[[0m
      ^[[4;36;1mIssue Load Including Associations (3.6ms)^[[0m   ^[[0;1mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."sub$
      ^[[4;35;1mIssuePriority Load (0.8ms)^[[0m   ^[[0mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enum$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;36;1mIssue Load Including Associations (3.6ms)^[[0m   ^[[0;1mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."sub$
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enumerations.posi$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."subject" AS t0_r3, "issues"."desc$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enumerations.po$
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;36;1mIssue Load Including Associations (3.6ms)^[[0m   ^[[0;1mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."sub$
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enumerations.posi$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;36;1mIssue Load Including Associations (3.7ms)^[[0m   ^[[0;1mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."sub$
      ^[[4;35;1mCACHE (0.0ms)^[[0m   ^[[0mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enumerations.posi$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "repositories" WHERE ("repositories"."id" = 43) ^[[0m
      ^[[4;35;1mIssue Load Including Associations (3.6ms)^[[0m   ^[[0mSELECT "issues"."id" AS t0_r0, "issues"."tracker_id" AS t0_r1, "issues"."project_id" AS t0_r2, "issues"."subje$
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "enumerations" WHERE ("enumerations"."id" = 7) AND ( ("enumerations"."type" = 'IssuePriority' ) ) ORDER BY enumerations.po$
    Rendered repositories/_revisions (596.2ms)
      ^[[4;35;1mToken Load (0.7ms)^[[0m   ^[[0mSELECT * FROM "tokens" WHERE ("tokens".user_id = 6 AND (action='feeds')) LIMIT 1^[[0m
      ^[[4;36;1mProject Load (1.6ms)^[[0m   ^[[0;1mSELECT "projects".* FROM "projects" INNER JOIN "members" ON "projects".id = "members".project_id WHERE (("members".user_id = 6) A$
      ^[[4;35;1mSQL (1.1ms)^[[0m   ^[[0mSELECT count(*) AS count_all FROM "projects" WHERE (((projects.status=1) AND (projects.id != 4)) AND (projects."lft" <= 28 AND projects."rgt$
      ^[[4;36;1mProject Load (0.9ms)^[[0m   ^[[0;1mSELECT * FROM "projects" WHERE (((projects.status=1) AND (projects.id != 4)) AND (projects."lft" <= 28 AND projects."rgt" >= 29))$
      ^[[4;35;1mProject Load (0.9ms)^[[0m   ^[[0mSELECT * FROM "projects" WHERE (projects."lft" <= 28 AND projects."rgt" >= 29) ORDER BY "lft" LIMIT 1^[[0m
      ^[[4;36;1mCACHE (0.0ms)^[[0m   ^[[0;1mSELECT * FROM "projects" WHERE (projects."lft" <= 28 AND projects."rgt" >= 29) ORDER BY "lft" LIMIT 1^[[0m
      ^[[4;35;1mSQL (3.3ms)^[[0m   ^[[0mSELECT count(DISTINCT "versions".id) AS count_all FROM "versions" LEFT OUTER JOIN "projects" ON "projects".id = "versions".project_id WHERE $
      ^[[4;36;1mWiki Load (1.1ms)^[[0m   ^[[0;1mSELECT * FROM "wikis" WHERE ("wikis".project_id = 4) LIMIT 1^[[0m
      ^[[4;35;1mSQL (1.1ms)^[[0m   ^[[0mSELECT count(*) AS count_all FROM "boards" WHERE ("boards".project_id = 4) ^[[0m
    Completed in 3178ms (View: 847, DB: 45) | 200 OK [https://redmine.foo.com.br/projects/myproject/repository]
    
  • Any ideas why Redmine's repository tab hangs right after starting Apache but works fine after killing Redmine's processes and restarting only Redmine?
  • NOTE: This problem started after I upgraded Redmine from 1.0.2 to 1.1.0

Thanks!

(1-4/4)