Zammad log is filled by a repeated action occurring every 6 seconds:
I, [2022-06-01T15:51:37.795552 #1350169-7160] INFO -- : Using Zammad's file store as Rails cache store.
I, [2022-06-01T15:51:37.795718 #1350169-7160] INFO -- : Using File as web socket session store.
I, [2022-06-01T15:51:38.195765 #1350169-7160] INFO -- : Setting.set('product_logo', "27ad73322e96990eb351803af90d2c44.png")
I, [2022-06-01T15:51:40.081320 #1350169-7160] INFO -- : Setting.set('models_searchable', ["Chat::Session", "KnowledgeBase::Answer::Translation", "Organization", "Ticket", "User"])
I, [2022-06-01T15:51:43.472829 #1350230-7160] INFO -- : Using Zammad's file store as Rails cache store.
I, [2022-06-01T15:51:43.472968 #1350230-7160] INFO -- : Using File as web socket session store.
I, [2022-06-01T15:51:43.869807 #1350230-7160] INFO -- : Setting.set('product_logo', "27ad73322e96990eb351803af90d2c44.png")
I, [2022-06-01T15:51:45.798615 #1350230-7160] INFO -- : Setting.set('models_searchable', ["Chat::Session", "KnowledgeBase::Answer::Translation", "Organization", "Ticket", "User"])
This process is using up to 100% CPU. Log message was not present before the last apt upgrade and started right after the upgrade.
I had to shutdown Zammad because the high cpu usage drained all the AWS burstable capacity.
Looks like this is exactly what’s happening. It’s not a fresh installation, it’s a test server I keep updated via apt and it gets the dev Zammad’s updates nearly daily.
I did not make any custom changes, or at least I do not remeber as it was installed more than two years ago. There must be something in that renaming causing script/background-services.rb to crash. It’s been working fine until yesterday’s update. I just tried today’s update (5.2.0-alpha-1654107415.b3639fb8.bullseye) but it did not solve the issue. Too bad I do not know Ruby so I cannot help. I will monitor the next updates for a solution, in case I’ll start a new fresh one. Thanks for your help.
Jun 2 12:06:24 systemd[1]: Started zammad.service.
Jun 2 12:06:24 systemd[1]: Started zammad-web.service.
Jun 2 12:06:24 systemd[1]: Started zammad-web-1.service.
Jun 2 12:06:24 systemd[1]: Started zammad-websocket.service.
Jun 2 12:06:24 systemd[1]: Started zammad-websocket-1.service.
Jun 2 12:06:24 systemd[1]: Started zammad-worker.service.
Jun 2 12:06:24 systemd[1]: Started zammad-worker-1.service.
Jun 2 12:06:27 zammad-web-1.service[1864364]: => Booting Puma
Jun 2 12:06:27 zammad-web-1.service[1864364]: => Rails 6.1.6 application starting in production
Jun 2 12:06:27 zammad-web-1.service[1864364]: => Run `bin/rails server --help` for more startup options
Jun 2 12:06:32 zammad-web-1.service[1864364]: Puma starting in single mode...
Jun 2 12:06:32 zammad-web-1.service[1864364]: * Version 4.3.12 (ruby 3.0.4-p208), codename: Mysterious Traveller
Jun 2 12:06:32 zammad-web-1.service[1864364]: * Min threads: 5, max threads: 30
Jun 2 12:06:32 zammad-web-1.service[1864364]: * Environment: production
Jun 2 12:06:32 zammad-web-1.service[1864364]: * Listening on tcp://127.0.0.1:3000
Jun 2 12:06:32 zammad-web-1.service[1864364]: Use Ctrl-C to stop
Jun 2 12:06:33 zammad-worker-1.service[1864369]: bundler: failed to load command: script/background-services.rb (script/background-services.rb)
Jun 2 12:06:33 zammad-worker-1.service[1864369]: /opt/zammad/lib/background_services/cli.rb:4:in `<class:BackgroundServices>': uninitialized constant Thor (NameError)
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/lib/background_services/cli.rb:3:in `<main>'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bootsnap-1.12.0/lib/bootsnap/load_path_cache/core_ext/kernel_require.rb:30:in `require'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bootsnap-1.12.0/lib/bootsnap/load_path_cache/core_ext/kernel_require.rb:30:in `require'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/zeitwerk-2.5.4/lib/zeitwerk/kernel.rb:27:in `require'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from script/background-services.rb:16:in `<top (required)>'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli/exec.rb:58:in `load'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli/exec.rb:58:in `kernel_load'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli/exec.rb:23:in `run'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli.rb:479:in `exec'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/vendor/thor/lib/thor/command.rb:27:in `run'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/vendor/thor/lib/thor/invocation.rb:127:in `invoke_command'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/vendor/thor/lib/thor.rb:392:in `dispatch'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli.rb:31:in `dispatch'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/vendor/thor/lib/thor/base.rb:485:in `start'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/cli.rb:25:in `start'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/exe/bundle:49:in `block in <top (required)>'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/lib/bundler/friendly_errors.rb:103:in `with_friendly_errors'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/vendor/bundle/ruby/3.0.0/gems/bundler-2.2.33/exe/bundle:37:in `<top (required)>'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/bin/bundle:121:in `load'
Jun 2 12:06:33 zammad-worker-1.service[1864369]: #011from /opt/zammad/bin/bundle:121:in `<main>'
Jun 2 12:06:33 systemd[1]: zammad-worker-1.service: Main process exited, code=exited, status=1/FAILURE
Jun 2 12:06:33 systemd[1]: zammad-worker-1.service: Failed with result 'exit-code'.
Jun 2 12:06:33 systemd[1]: zammad-worker-1.service: Consumed 5.228s CPU time.