On 2019-09-18 12:52:32 +0200, Christian Göttsche wrote:
> Please share your current setup:
> Debian version: (old-stable | stable | testing | unstable)
unstable
> Logrotate version: (logrotate --version)
logrotate 3.15.1
Default mail command: /usr/bin/mail
Default compress command: /bin/gzip
Default uncompress command: /bin/gunzip
Default compress extension: .gz
Default state file path: /var/lib/logrotate/status
ACL support: yes
SELinux support: yes
> Please share your cronjob config '/etc/cron.daily/logrotate'
#!/bin/sh
# skip in favour of systemd timer
if [ -d /run/systemd/system ]; then
exit 0
fi
# this cronjob persists removals (but not purges)
if [ ! -x /usr/sbin/logrotate ]; then
exit 0
fi
/usr/sbin/logrotate /etc/logrotate.conf
EXITVALUE=$?
if [ $EXITVALUE != 0 ]; then
/usr/bin/logger -t logrotate "ALERT exited abnormally with [$EXITVALUE]"
fi
exit $EXITVALUE
(This is the default one.)
> Are you using systemd?
Yes, this machine does.
> If yes, please share 'systemctl status logrotate.timer' and
> 'journalctl -u logrotate' (the most recent part of it).
● logrotate.timer - Daily rotation of log files
Loaded: loaded (/lib/systemd/system/logrotate.timer; enabled; vendor preset:
enabled)
Active: active (waiting) since Thu 2019-09-19 10:25:10 CEST; 32min ago
Trigger: Fri 2019-09-20 00:00:00 CEST; 13h left
Docs: man:logrotate(8)
man:logrotate.conf(5)
Sep 19 10:25:10 cventin systemd[1]: Started Daily rotation of log files.
[...]
Sep 16 00:00:01 cventin systemd[1]: Starting Rotate log files...
Sep 16 00:00:02 cventin systemd[1]: logrotate.service: Succeeded.
Sep 16 00:00:02 cventin systemd[1]: Started Rotate log files.
Sep 17 00:00:01 cventin systemd[1]: Starting Rotate log files...
Sep 17 00:00:03 cventin systemd[1]: logrotate.service: Succeeded.
Sep 17 00:00:03 cventin systemd[1]: Started Rotate log files.
Sep 18 00:00:01 cventin systemd[1]: Starting Rotate log files...
Sep 18 00:00:01 cventin logrotate[16206]: error: Compressing program wrote
following message to stderr when compressing log /var/log/apache2/error.log.1:
Sep 18 00:00:01 cventin logrotate[16206]: gzip: stdin: warning: file timestamp
out of range for gzip format
Sep 18 00:00:01 cventin logrotate[16206]: error: failed to compress log
/var/log/apache2/error.log.1
Sep 18 00:00:02 cventin systemd[1]: logrotate.service: Main process exited,
code=exited, status=1/FAILURE
Sep 18 00:00:02 cventin systemd[1]: logrotate.service: Failed with result
'exit-code'.
Sep 18 00:00:02 cventin systemd[1]: Failed to start Rotate log files.
Sep 19 00:00:01 cventin systemd[1]: Starting Rotate log files...
Sep 19 00:00:02 cventin logrotate[19728]: error: Compressing program wrote
following message to stderr when compressing log /var/log/apache2/error.log.1:
Sep 19 00:00:02 cventin logrotate[19728]: gzip: stdin: warning: file timestamp
out of range for gzip format
Sep 19 00:00:02 cventin logrotate[19728]: error: failed to compress log
/var/log/apache2/error.log.1
Sep 19 00:00:02 cventin systemd[1]: logrotate.service: Main process exited,
code=exited, status=1/FAILURE
Sep 19 00:00:02 cventin systemd[1]: logrotate.service: Failed with result
'exit-code'.
Sep 19 00:00:02 cventin systemd[1]: Failed to start Rotate log files.
> > It seems really broken, worse than expected. First, I did not get
> > any error via cron, and the error logs did not change:
> >
> > -rw-r----- 1 root adm 306 1904-01-01 00:00:00 error.log
> > -rw-r----- 1 root adm 440 1904-01-01 00:00:00 error.log.1
> > -rw-r----- 1 root adm 271 2019-09-16 00:00:02 error.log.2.gz
> > -rw-r----- 1 root adm 269 2019-09-15 00:00:02 error.log.3.gz
> > -rw-r----- 1 root adm 271 2019-09-14 00:00:02 error.log.4.gz
> > -rw-r----- 1 root adm 271 2019-09-13 00:00:01 error.log.5.gz
> > [...]
>
> That the logs do not change is expected, as gzip failed;
> if you using systemd you should see some error messages in the journal.
Yes, this is the case. But cron should have sent a mail with these
error messages, and I haven't received such a mail.
> > Worse, this seemed to have interrupted the rotation of the
> > access logs (or is this a different bug?):
> >
> > -rw-r----- 1 root adm 308 2019-09-17 16:39:00 access.log
> > -rw-r----- 1 root adm 156 2019-09-16 16:31:06 access.log.2.gz
> > -rw-r----- 1 root adm 157 2019-09-13 12:36:03 access.log.3.gz
> > -rw-r----- 1 root adm 155 2019-09-12 12:25:14 access.log.4.gz
> > -rw-r----- 1 root adm 156 2019-09-10 10:33:38 access.log.5.gz
> > [...]
>
> This is indeed unexpected, in my tests logrotate continues to rotate
> other logs. (see https://paste.debian.net/1101399/)
It is only access.log that it doesn't rotate. After last night:
-rw-r----- 1 root adm 308 2019-09-17 16:39:00 access.log
-rw-r----- 1 root adm 156 2019-09-16 16:31:06 access.log.3.gz
-rw-r----- 1 root adm 157 2019-09-13 12:36:03 access.log.4.gz
-rw-r----- 1 root adm 155 2019-09-12 12:25:14 access.log.5.gz
-rw-r----- 1 root adm 156 2019-09-10 10:33:38 access.log.6.gz
--
Vincent Lefèvre <[email protected]> - Web: <https://www.vinc17.net/>
100% accessible validated (X)HTML - Blog: <https://www.vinc17.net/blog/>
Work: CR INRIA - computer arithmetic / AriC project (LIP, ENS-Lyon)
