eye_processes_stderr.log

gistfile1.txt

First contents of the logfile:
------------------------------

/home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:91:in `block in reload': directory for stderr_path=/usr/local/app/diaspora/log/unicorn-stderr.log not writable (ArgumentError)
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `each'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `reload'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:68:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `<top (required)>'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `load'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `<main>'
/home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:91:in `block in reload': directory for stderr_path=/usr/local/app/diaspora/log/unicorn-stderr.log not writable (ArgumentError)
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `each'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `reload'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:68:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `<top (required)>'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `load'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `<main>'
/home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:91:in `block in reload': directory for stderr_path=/usr/local/app/diaspora/log/unicorn-stderr.log not writable (ArgumentError)
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `each'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:87:in `reload'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/configurator.rb:68:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/lib/unicorn/http_server.rb:100:in `initialize'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `new'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/gems/unicorn-4.9.0/bin/unicorn:126:in `<top (required)>'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `load'
        from /home/diaspora/.rvm/gems/ruby-2.1.5/bin/unicorn:23:in `<main>'


Onscreen output:
------------------------------

diaspora@ct3:~/diaspora$ ./script/server 
WARNING: Project gemset is diaspora but you are using ruby-2.1.5

Starting Diaspora in production mode with 1 Sidekiq worker(s).

I, [2015-06-15T21:05:52.151728 #24741]  INFO -- : [diaspora:__default__] send_command: monitor
I, [2015-06-15T21:05:52.153821 #24741]  INFO -- : [diaspora:__default__] schedule :monitor (reason: monitor by user)
I, [2015-06-15T21:05:52.154456 #24741]  INFO -- : [diaspora:__default__] => monitor  (reason: monitor by user)
I, [2015-06-15T21:05:52.154735 #24741]  INFO -- : [diaspora:__default__] starting async with 0.2s chain monitor []
I, [2015-06-15T21:05:52.154932 #24741]  INFO -- : [diaspora:sidekiq] send_command: monitor
I, [2015-06-15T21:05:52.156268 #24741]  INFO -- : [diaspora:sidekiq] schedule :monitor (reason: monitor by user)
I, [2015-06-15T21:05:52.157506 #24741]  INFO -- : [diaspora:web] schedule :monitor (reason: monitor by user)
I, [2015-06-15T21:05:52.157778 #24741]  INFO -- : [diaspora:sidekiq] => monitor  (reason: monitor by user)
I, [2015-06-15T21:05:52.158520 #24741]  INFO -- : [diaspora:web] => monitor  (reason: monitor by user)
I, [2015-06-15T21:05:52.158634 #24741]  INFO -- : [diaspora:sidekiq] starting async with 0.2s chain monitor []
I, [2015-06-15T21:05:52.158888 #24741]  INFO -- : [diaspora:__default__] <= monitor
I, [2015-06-15T21:05:52.159097 #24741]  INFO -- : [Eye] <= loading: ["/home/diaspora/diaspora/config/eye.rb"]
I, [2015-06-15T21:05:52.159259 #24741]  INFO -- : [diaspora:web] pid_file not found, starting...
I, [2015-06-15T21:05:52.177380 #24741]  INFO -- : [Eye] <= command: load /home/diaspora/diaspora/config/eye.rb (0.147786896s)
I, [2015-06-15T21:05:52.178078 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] schedule :monitor (reason: monitor by user)
I, [2015-06-15T21:05:52.178849 #24741]  INFO -- : [diaspora:web] switch :starting [:unmonitored => :starting] (reason: monitor by user)
I, [2015-06-15T21:05:52.183689 #24741]  INFO -- : [diaspora:web] daemonizing: `bin/bundle exec unicorn -c config/unicorn.rb` with start_grace: 2.5s, env: 'DB=mysql RAILS_ENV=production PORT=', <24888> (in /home/diaspora/diaspora)
I, [2015-06-15T21:05:52.184289 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] => monitor  (reason: monitor by user)
I, [2015-06-15T21:05:52.184527 #24741]  INFO -- : [diaspora:web] sleeping for :start_grace 2.5
I, [2015-06-15T21:05:52.184713 #24741]  INFO -- : [diaspora:sidekiq] <= monitor
I, [2015-06-15T21:05:52.185104 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] process <22969> from pid_file is already running
I, [2015-06-15T21:05:52.186376 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] switch :already_running [:unmonitored => :up] (reason: monitor by user)
I, [2015-06-15T21:05:52.186836 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] <= monitor
E, [2015-06-15T21:05:54.685701 #24741] ERROR -- : [diaspora:web] process <24888> not found, it may have crashed (you should check the process logs ["/home/diaspora/diaspora/log/eye_processes_stdout.log", "/home/diaspora/diaspora/log/eye_processes_stderr.log"])
E, [2015-06-15T21:05:54.685877 #24741] ERROR -- : [diaspora:web] process <24888> failed to start (:not_really_running)
I, [2015-06-15T21:05:54.686337 #24741]  INFO -- : [diaspora:web] switch :crashed [:starting => :down] (reason: monitor by user)
I, [2015-06-15T21:05:54.686929 #24741]  INFO -- : [diaspora:web] schedule :check_crash (reason: crashed)
I, [2015-06-15T21:05:54.687474 #24741]  INFO -- : [diaspora:web] <= monitor
I, [2015-06-15T21:05:54.687924 #24741]  INFO -- : [diaspora:web] => check_crash  (reason: crashed)
W, [2015-06-15T21:05:54.688088 #24741]  WARN -- : [diaspora:web] check crashed: process is down
I, [2015-06-15T21:05:54.688216 #24741]  INFO -- : [diaspora:web] schedule :restore (reason: crashed)
I, [2015-06-15T21:05:54.688715 #24741]  INFO -- : [diaspora:web] <= check_crash
I, [2015-06-15T21:05:54.689102 #24741]  INFO -- : [diaspora:web] => restore  (reason: crashed)
I, [2015-06-15T21:05:54.689269 #24741]  INFO -- : [diaspora:web] pid_file not found, starting...
I, [2015-06-15T21:05:54.689751 #24741]  INFO -- : [diaspora:web] switch :starting [:down => :starting] (reason: crashed)
I, [2015-06-15T21:05:54.692948 #24741]  INFO -- : [diaspora:web] daemonizing: `bin/bundle exec unicorn -c config/unicorn.rb` with start_grace: 2.5s, env: 'DB=mysql RAILS_ENV=production PORT=', <24892> (in /home/diaspora/diaspora)
I, [2015-06-15T21:05:54.693131 #24741]  INFO -- : [diaspora:web] sleeping for :start_grace 2.5
E, [2015-06-15T21:05:57.193630 #24741] ERROR -- : [diaspora:web] process <24892> not found, it may have crashed (you should check the process logs ["/home/diaspora/diaspora/log/eye_processes_stdout.log", "/home/diaspora/diaspora/log/eye_processes_stderr.log"])
E, [2015-06-15T21:05:57.193810 #24741] ERROR -- : [diaspora:web] process <24892> failed to start (:not_really_running)
I, [2015-06-15T21:05:57.194410 #24741]  INFO -- : [diaspora:web] switch :crashed [:starting => :down] (reason: crashed)
I, [2015-06-15T21:05:57.195125 #24741]  INFO -- : [diaspora:web] schedule :check_crash (reason: crashed)
I, [2015-06-15T21:05:57.195680 #24741]  INFO -- : [diaspora:web] <= restore
I, [2015-06-15T21:05:57.196233 #24741]  INFO -- : [diaspora:web] => check_crash  (reason: crashed)
W, [2015-06-15T21:05:57.196390 #24741]  WARN -- : [diaspora:web] check crashed: process is down
I, [2015-06-15T21:05:57.196515 #24741]  INFO -- : [diaspora:web] schedule :restore (reason: crashed)
I, [2015-06-15T21:05:57.197016 #24741]  INFO -- : [diaspora:web] <= check_crash
I, [2015-06-15T21:05:57.197502 #24741]  INFO -- : [diaspora:web] => restore  (reason: crashed)
I, [2015-06-15T21:05:57.197667 #24741]  INFO -- : [diaspora:web] pid_file not found, starting...
I, [2015-06-15T21:05:57.198101 #24741]  INFO -- : [diaspora:web] switch :starting [:down => :starting] (reason: crashed)
I, [2015-06-15T21:05:57.201301 #24741]  INFO -- : [diaspora:web] daemonizing: `bin/bundle exec unicorn -c config/unicorn.rb` with start_grace: 2.5s, env: 'DB=mysql RAILS_ENV=production PORT=', <24896> (in /home/diaspora/diaspora)
I, [2015-06-15T21:05:57.201475 #24741]  INFO -- : [diaspora:web] sleeping for :start_grace 2.5
^CI, [2015-06-15T21:05:58.380386 #24741]  INFO -- : [Eye] => command: stop_all 
I, [2015-06-15T21:05:58.380634 #24741]  INFO -- : [diaspora] send_command break_chain
I, [2015-06-15T21:05:58.380898 #24741]  INFO -- : [diaspora:sidekiq] send_command: break_chain
I, [2015-06-15T21:05:58.381010 #24741]  INFO -- : [diaspora:sidekiq] break chain
I, [2015-06-15T21:05:58.381500 #24741]  INFO -- : [diaspora:__default__] send_command: break_chain
I, [2015-06-15T21:05:58.381572 #24741]  INFO -- : [diaspora:__default__] break chain
I, [2015-06-15T21:05:58.381820 #24741]  INFO -- : [diaspora] send_command stop
I, [2015-06-15T21:05:58.382099 #24741]  INFO -- : [diaspora:sidekiq] send_command: stop
I, [2015-06-15T21:05:58.382194 #24741]  INFO -- : [diaspora:sidekiq] schedule :stop (reason: stop by user)
I, [2015-06-15T21:05:58.382610 #24741]  INFO -- : [diaspora:sidekiq] => stop  (reason: stop by user)
I, [2015-06-15T21:05:58.382701 #24741]  INFO -- : [diaspora:sidekiq] send to all processes stop 
I, [2015-06-15T21:05:58.382879 #24741]  INFO -- : [diaspora:__default__] send_command: stop
I, [2015-06-15T21:05:58.383228 #24741]  INFO -- : [diaspora:__default__] schedule :stop (reason: stop by user)
I, [2015-06-15T21:05:58.383401 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] schedule :stop (reason: stop by user)
I, [2015-06-15T21:05:58.383931 #24741]  INFO -- : [diaspora:__default__] => stop  (reason: stop by user)
I, [2015-06-15T21:05:58.384020 #24741]  INFO -- : [diaspora:__default__] send to all processes stop 
I, [2015-06-15T21:05:58.384117 #24741]  INFO -- : [diaspora] send_command freeze
I, [2015-06-15T21:05:58.384724 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] => stop  (reason: stop by user)
I, [2015-06-15T21:05:58.384906 #24741]  INFO -- : [diaspora:sidekiq] send_command: freeze
I, [2015-06-15T21:05:58.385301 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] switch :stopping [:up => :stopping] (reason: stop by user)
I, [2015-06-15T21:05:58.385626 #24741]  INFO -- : [diaspora:sidekiq] schedule :freeze (reason: freeze by user)
I, [2015-06-15T21:05:58.385785 #24741]  INFO -- : [diaspora:web] schedule :stop (reason: stop by user)
I, [2015-06-15T21:05:58.386051 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] executing stop_signals [:USR1, 0, :TERM, 10, :KILL]
I, [2015-06-15T21:05:58.386248 #24741]  INFO -- : [diaspora:sidekiq] <= stop
I, [2015-06-15T21:05:58.386875 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] send_signal USR1 to <22969>
I, [2015-06-15T21:05:58.387025 #24741]  INFO -- : [diaspora:__default__] <= stop
I, [2015-06-15T21:05:58.387500 #24741]  INFO -- : [diaspora:sidekiq] => freeze  (reason: freeze by user)
I, [2015-06-15T21:05:58.387686 #24741]  INFO -- : [diaspora:__default__] send_command: freeze
I, [2015-06-15T21:05:58.387802 #24741]  INFO -- : [diaspora:sidekiq] send to all processes freeze 
I, [2015-06-15T21:05:58.387928 #24741]  INFO -- : [diaspora:__default__] schedule :freeze (reason: freeze by user)
I, [2015-06-15T21:05:58.388405 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] schedule :freeze (reason: freeze by user)
I, [2015-06-15T21:05:58.388797 #24741]  INFO -- : [diaspora:__default__] => freeze  (reason: freeze by user)
I, [2015-06-15T21:05:58.389038 #24741]  INFO -- : [diaspora:__default__] send to all processes freeze 
I, [2015-06-15T21:05:58.389255 #24741]  INFO -- : [diaspora:sidekiq] <= freeze
I, [2015-06-15T21:05:58.390082 #24741]  INFO -- : [diaspora:web] schedule :freeze (reason: freeze by user)
I, [2015-06-15T21:05:58.391183 #24741]  INFO -- : [diaspora:__default__] <= freeze
I, [2015-06-15T21:05:58.687693 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] send_signal TERM to <22969>
I, [2015-06-15T21:05:59.288397 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] has terminated
I, [2015-06-15T21:05:59.288567 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] sleeping for :stop_grace 0.5
^CE, [2015-06-15T21:05:59.732028 #24741] ERROR -- : [diaspora:web] process <24896> not found, it may have crashed (you should check the process logs ["/home/diaspora/diaspora/log/eye_processes_stdout.log", "/home/diaspora/diaspora/log/eye_processes_stderr.log"])
E, [2015-06-15T21:05:59.732816 #24741] ERROR -- : [diaspora:web] process <24896> failed to start (:not_really_running)
^CI, [2015-06-15T21:05:59.856393 #24741]  INFO -- : [diaspora:web] switch :crashed [:starting => :down] (reason: crashed)
^CI, [2015-06-15T21:05:59.856860 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] switch :stopped [:stopping => :down] (reason: stop by user)
^CI, [2015-06-15T21:05:59.873137 #24741]  INFO -- : [diaspora:web] schedule :check_crash (reason: crashed)
^CI, [2015-06-15T21:06:00.134131 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] delete pid_file: /home/diaspora/diaspora/tmp/pids/sidekiq1.pid
^CI, [2015-06-15T21:06:00.552330 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] switch :unmonitoring [:down => :unmonitored] (reason: stop by user)
I, [2015-06-15T21:06:00.552637 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] <= stop
^CI, [2015-06-15T21:06:00.704194 #24741]  INFO -- : [diaspora:web] <= restore
I, [2015-06-15T21:06:00.859465 #24741]  INFO -- : [diaspora:web] => stop  (reason: stop by user)
^CI, [2015-06-15T21:06:00.984485 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] => freeze  (reason: freeze by user)
W, [2015-06-15T21:06:00.984972 #24741]  WARN -- : [diaspora:web] wrong switch 'Cannot transition state via :stopping from :down'
^CI, [2015-06-15T21:06:01.103016 #24741]  INFO -- : [diaspora:sidekiq:sidekiq1] <= freeze
^CI, [2015-06-15T21:06:01.113924 #24741]  INFO -- : [diaspora:web] switch :unmonitoring [:down => :unmonitored] (reason: stop by user)
I, [2015-06-15T21:06:01.370189 #24741]  INFO -- : [diaspora:web] <= stop
^C/home/diaspora/.rvm/gems/ruby-2.1.5/bin/loader_eye^CI, [2015-06-15T21:06:01.709255 #24741]  INFO -- : [diaspora:web] => freeze  (reason: freeze by user)
I, [2015-06-15T21:06:01.855714 #24741]  INFO -- : [diaspora:web] <= freeze
I, [2015-06-15T21:06:02.003970 #24741]  INFO -- : [diaspora:web] => check_crash  (reason: crashed)
I, [2015-06-15T21:06:02.150316 #24741]  INFO -- : [diaspora:web] <= check_crash
^C^C^C^C^C^C^C^C: SystemStackError
^CI, [2015-06-15T21:06:02.478658 #24741]  INFO -- : [Eye] <= command: stop_all  (4.084021371s)
^C^C

Did you ever find the solution for this?

Hello,
I'm facing the same issue. The problem seems pretty obvious (permissions issue), but sounds to me like a design issue as is /usr/local/app/diaspora/log/unicorn-stderr.log is in the sys file out of diaspora install. Giving permissions to "diaspora" account didn't help much.
Has anyone found a solution to this, please? Is it ok to change the path?