[Rspamd-Users] Upgrade from 1.8.3 to 1.9.0 broke ( Debian 9.8 )

[Sophie Loewenthal]

On 4 Apr 2019, at 21:00, B. Reino <reinob at bbmk.org> wrote:
> 
> On Thu, 4 Apr 2019, Sophie Loewenthal wrote:
> 
>> [...]
>> And started rspamd and only got this each time:
>> 
>> # > /var/log/rspamd/rspamd.log && /etc/init.d/rspamd restart && cat /var/log/rspamd/rspamd.log
>> 2019-04-04 06:39:02 #19652(main) <e83c3e>; main; rspamd_term_handler: catch termination signal, waiting for children
>> 2019-04-04 06:39:02 #19680(hs_helper) <e83c3e>; main; rspamd_worker_term_handler: terminating after receiving signal Terminated
>> 2019-04-04 06:39:02 #19652(main) <e83c3e>; main; wait_for_workers: hs_helper process 19680 terminated softly
>> 2019-04-04 06:39:11 #19678(controller) <h53tjj>; lua; neural.lua:810: check ANN tRFANN591E4369F1C86ED0260
>> 2019-04-04 06:39:11 #19678(controller) <h53tjj>; lua; neural.lua:822: no need to learn ANN tRFANN591E4369F1C86ED0260 28 learn vectors (1000 required)
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: terminate worker rspamd_proxy(19677) with SIGKILL
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: terminate worker controller(19678) with SIGKILL
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: terminate worker normal(19679) with SIGKILL
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: rspamd_proxy process 19677 terminated hardly
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: controller process 19678 terminated hardly
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; wait_for_workers: normal process 19679 terminated hardly
>> 2019-04-04 06:39:12 #19652(main) <e83c3e>; main; main: terminating…
> 
> From the looks of it, your "/etc/init.d/rspamd restart" stops rspamd but somehow fails to even try to start it.
> 
> Perhaps it's safer to stop/kill it, and then /etc/init.d/rspamd start
> 
> (BTW why /etc/init.d/rspamd instead of systemctl restart rspamd?,
> you are (should be, with debian) using systemd..)
> 
> The systemd unit effectively runs
> /usr/bin/rspamd -c /etc/rspamd/rspamd.conf -f
> 
> under the _rspamd user, which should amount to:
> 
> $ sudo -u _rspamd /usr/bin/rspamd -c /etc/rspamd/rspamd.conf -f
> 
> If you run the above, hopefully you will get something which we can work with..
> 
> Good luck.


Hi, 

Thanks for replying and many thanks for the command.

# sudo -u _rspamd /usr/bin/rspamd -c /etc/rspamd/rspamd.conf -f
run’s fine as expected without errors.

I added my original actions.conf file with a single # in it,: Ok it’s the metrics.

# sudo -u _rspamd /usr/bin/rspamd -c /etc/rspamd/rspamd.conf -f
2019-04-04 20:01:32 #15595(main) lua; lua_cfg_transform.lua:287: unknown element in actions section: metrics
…
2019-04-04 20:01:32 #15595(main) <migus3>; cfg; rspamd_config_action_from_ucl: action metrics has no threshold being set and it is not a no threshold action
2019-04-04 20:01:32 #15595(main) <migus3>; cfg; rspamd_config_read: rcl parse error: invalid action definition for: 'metrics'

Next removed file and created a new one with a single # in it, and realised part of the problem was the permissions being 0640.
 # sudo -u _rspamd /usr/bin/rspamd -c /etc/rspamd/rspamd.conf -f
2019-04-04 20:03:23 #15640(main) cfg; rspamd_config_read: failed to load config: ucl parser error: cannot open file /etc/rspamd/local.d/actions.conf: Permission denied

This error message does not appear in the logs, because likelt it dies afore it starts writing logs.

Rspamd works again with the actions.conf. Unsure why the permissions changed, but I am sure this was caused by me.

Sadly, the actions.conf no longer has any effect on the headers. The contents is :
# cat actions.conf 
reject = 14;
add_header = 6;
rewrite_subject = 6;
subject = "***SPAM*** %s";
greylist = null; 


And incoming emails have no rspamd headers at all.

Best, Soph.


P.S
Debian 9 seems to run well with both :)
root         1     0  0 Mar26 ?        00:01:08 /sbin/init
root       579     1  0 Mar26 ?        00:02:03 /lib/systemd/systemd-journald
root       622     1  0 Mar26 ?        00:00:02 /lib/systemd/systemd-udevd
root       885     1  0 Mar26 ?        00:00:22 /lib/systemd/systemd-logind


So glad it’s there.