Gentoo Websites Logo
Go to: Gentoo Home Documentation Forums Lists Bugs Planet Store Wiki Get Gentoo!
Bug 525086 - app-admin/ulogd - ulogd dies after rotating logs
Summary: app-admin/ulogd - ulogd dies after rotating logs
Status: RESOLVED INVALID
Alias: None
Product: Gentoo Linux
Classification: Unclassified
Component: [OLD] Server (show other bugs)
Hardware: All Linux
: Normal normal (vote)
Assignee: Coacher
URL:
Whiteboard:
Keywords: PATCH
Depends on:
Blocks:
 
Reported: 2014-10-11 21:27 UTC by Marcin Mirosław
Modified: 2014-10-13 09:41 UTC (History)
1 user (show)

See Also:
Package list:
Runtime testing required: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Marcin Mirosław 2014-10-11 21:27:55 UTC
After nightly rotating logs ulogd stops works.
# cat /etc/logrotate.d/ulogd
/data/ulog/ulogd.log {
        missingok
        daily
        rotate 1825
        postrotate
                /etc/init.d/ulogd reopen_logs > /dev/null
        endscript
}

So I'm doing manually:
# pgrep -a ulogd
9084 /usr/sbin/ulogd --daemon --uid ulogd --pidfile /run/ulogd.pid

# /etc/init.d/ulogd reopen_logs
 * Reopening ulogd logfiles ...            [ ok ]

# pgrep -a ulogd


It looks that when ulogd receives HUP then die.
Comment 1 Marcin Mirosław 2014-10-11 21:30:02 UTC
Portage 2.2.8-r2 (default/linux/amd64/13.0, gcc-4.7.3, glibc-2.19-r1, 3.14.14-gentoo x86_64)
=================================================================
System uname: Linux-3.14.14-gentoo-x86_64-Intel-R-_Xeon-R-_CPU_3065_@_2.33GHz-with-gentoo-2.2
KiB Mem:     2052476 total,    865384 free
KiB Swap:    2097148 total,   2097148 free
Timestamp of tree: Wed, 08 Oct 2014 10:00:01 +0000
ld GNU ld (GNU Binutils) 2.23.2
app-shells/bash:          4.2_p53
dev-lang/perl:            5.18.2-r1
dev-lang/python:          2.7.7, 3.3.5-r1
dev-util/pkgconfig:       0.28-r1
sys-apps/baselayout:      2.2
sys-apps/openrc:          0.12.4
sys-apps/sandbox:         2.6-r1
sys-devel/autoconf:       2.69
sys-devel/automake:       1.12.6, 1.13.4
sys-devel/binutils:       2.23.2
sys-devel/gcc:            4.7.3-r1
sys-devel/gcc-config:     1.7.3
sys-devel/libtool:        2.4.2-r1
sys-devel/make:           4.0-r1
sys-kernel/linux-headers: 3.13 (virtual/os-headers)
sys-libs/glibc:           2.19-r1
Repositories: gentoo
ACCEPT_KEYWORDS="amd64"
ACCEPT_LICENSE="* -@EULA"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-O2 -pipe -march=native"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc"
CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/env.d /etc/gconf /etc/gentoo-release /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo"
CXXFLAGS="-O2 -pipe -march=native"
DISTDIR="/usr/portage/distfiles"
FCFLAGS="-O2 -pipe"
FEATURES="assume-digests binpkg-logs collision-protect config-protect-if-modified distlocks ebuild-locks fixlafiles merge-sync news parallel-fetch preserve-libs protect-owned sandbox sfperms strict unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync"
FFLAGS="-O2 -pipe"
GENTOO_MIRRORS="http://distfiles.gentoo.org"
LANG="en_US.UTF-8"
LC_ALL="en_US.UTF-8"
LDFLAGS="-Wl,-O1 -Wl,--as-needed"
MAKEOPTS="-j3"
PKGDIR="/usr/portage/packages"
PORTAGE_CONFIGROOT="/"
PORTAGE_RSYNC_EXTRA_OPTS="-O"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --omit-dir-times --compress --force --whole-file --delete --stats --human-readable --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY=""
USE="acl amd64 bash-completion bcmath bzip2 cli cracklib crypt curl cxx device-mapper dri exif fortran ftp glibc-omitfp hpn iconv idn ipv6 mmx modules multilib ncurses nls nptl openmp pam pcre readline session slang sockets sse sse2 sse3 ssl ssse3 unicode zip zlib" ABI_X86="64" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" APACHE2_MODULES="authn_core authz_core socache_shmcb unixd actions alias auth_basic authn_alias authn_anon authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache cgi cgid dav dav_fs dav_lock deflate dir disk_cache env expires ext_filter file_cache filter headers include info log_config logio mem_cache mime mime_magic negotiation rewrite setenvif speling status unique_id userdir usertrack vhost_alias" CALLIGRA_FEATURES="kexi words flow plan sheets stage tables krita karbon braindump author" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf superstar2 timing tsip tripmate tnt ublox ubx" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LIBREOFFICE_EXTENSIONS="presenter-console presenter-minimizer" LINGUAS="en" OFFICE_IMPLEMENTATION="libreoffice" PHP_TARGETS="php5-5" PYTHON_SINGLE_TARGET="python2_7" PYTHON_TARGETS="python2_7 python3_3" RUBY_TARGETS="ruby19 ruby20" USERLAND="GNU" VIDEO_CARDS="fbdev glint intel mach64 mga nouveau nv r128 radeon savage sis tdfx trident vesa via vmware dummy v4l" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account"
Unset:  CPPFLAGS, CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, SYNC, USE_PYTHON
Comment 2 Coacher 2014-10-12 09:46:51 UTC
(In reply to Marcin Mirosław from comment #0)
> So I'm doing manually:
> # pgrep -a ulogd
> 9084 /usr/sbin/ulogd --daemon --uid ulogd --pidfile /run/ulogd.pid
> 
> # /etc/init.d/ulogd reopen_logs
>  * Reopening ulogd logfiles ...            [ ok ]
> 
> # pgrep -a ulogd
> 
> 
> It looks that when ulogd receives HUP then die.

Indeed, SIGHUP is sent on reopen_logs, but ulogd should not die.

Can not reproduce with ulogd-2.0.4 here:
# pgrep -a ulogd
1675 /usr/sbin/ulogd --daemon --uid ulogd --pidfile /run/ulogd.pid
# /etc/init.d/ulogd reopen_logs
 * Reopening ulogd logfiles ...                                                                                                                                              [ ok ]
# pgrep -a ulogd
1675 /usr/sbin/ulogd --daemon --uid ulogd --pidfile /run/ulogd.pid

Please increase ulogd logging verbosity to '1' via 'loglevel' option in ulogd config and perform needed steps to reproduce your problem, then attach related part of ulogd's logfile here (one that is set via option 'logfile' in config).
Comment 3 Coacher 2014-10-12 10:04:02 UTC
(In reply to Marcin Mirosław from comment #0)
> After nightly rotating logs ulogd stops works.
> # cat /etc/logrotate.d/ulogd
> /data/ulog/ulogd.log {
>         missingok
>         daily
>         rotate 1825
>         postrotate
>                 /etc/init.d/ulogd reopen_logs > /dev/null
>         endscript
> }

Also it looks like you are using logrotate configuration that significantly differs from one in tree. Your changes can be the source of your problems. For example, 'create' option could be viable. Please check if this issue is reproducible with logrotate configuration from tree.
Comment 4 Coacher 2014-10-12 10:05:58 UTC
Please also attach output of 'emerge --info ulogd' command.
Comment 5 Marcin Mirosław 2014-10-13 09:41:14 UTC
Even with increased verbosity I can't find anything interesting.
// aplication starts
Mon Oct 13 11:18:37 2014 <5> ulogd.c:843 building new pluginstance stack: 'ulog1:ULOG,base1:BASE,ip2str1:IP2STR,print1:PRINTPKT,emu1:LOGEMU'
Mon Oct 13 11:18:37 2014 <5> ulogd.c:1441 Changing UID / GID
// now ulogs receives SIGHUP
Mon Oct 13 11:20:04 2014 <5> ulogd.c:1260 signal received, calling pluginstances

That's all in logfile.
About changes in logrotate, I agree that my config is missing some options, I'm going to add them but I think that sendig SIGHUP (without rotating logs) shouldn't harm ulogd.

# emerge --info ulogd
Portage 2.2.8-r2 (default/linux/amd64/13.0, gcc-4.7.3, glibc-2.19-r1, 3.14.14-gentoo x86_64)
=================================================================
                        System Settings
=================================================================
System uname: Linux-3.14.14-gentoo-x86_64-Intel-R-_Xeon-R-_CPU_3065_@_2.33GHz-with-gentoo-2.2
KiB Mem:     2052476 total,    358880 free
KiB Swap:    2097148 total,   2097148 free
Timestamp of tree: Wed, 08 Oct 2014 10:00:01 +0000
ld GNU ld (GNU Binutils) 2.23.2
app-shells/bash:          4.2_p53
dev-lang/perl:            5.18.2-r1
dev-lang/python:          2.7.7, 3.3.5-r1
dev-util/pkgconfig:       0.28-r1
sys-apps/baselayout:      2.2
sys-apps/openrc:          0.12.4
sys-apps/sandbox:         2.6-r1
sys-devel/autoconf:       2.69
sys-devel/automake:       1.12.6, 1.13.4
sys-devel/binutils:       2.23.2
sys-devel/gcc:            4.7.3-r1
sys-devel/gcc-config:     1.7.3
sys-devel/libtool:        2.4.2-r1
sys-devel/make:           4.0-r1
sys-kernel/linux-headers: 3.13 (virtual/os-headers)
sys-libs/glibc:           2.19-r1
Repositories: gentoo
ACCEPT_KEYWORDS="amd64"
ACCEPT_LICENSE="* -@EULA"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-O2 -pipe -march=native"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc"
CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/env.d /etc/gconf /etc/gentoo-release /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo"
CXXFLAGS="-O2 -pipe -march=native"
DISTDIR="/usr/portage/distfiles"
FCFLAGS="-O2 -pipe"
FEATURES="assume-digests binpkg-logs collision-protect config-protect-if-modified distlocks ebuild-locks fixlafiles merge-sync news parallel-fetch preserve-libs protect-owned sandbox sfperms strict unknown-features-warn unmerge-logs unmerge-orphans userfetch userpriv usersandbox usersync"
FFLAGS="-O2 -pipe"
GENTOO_MIRRORS="http://distfiles.gentoo.org"
LANG="en_US.UTF-8"
LC_ALL="en_US.UTF-8"
LDFLAGS="-Wl,-O1 -Wl,--as-needed"
MAKEOPTS="-j3"
PKGDIR="/usr/portage/packages"
PORTAGE_CONFIGROOT="/"
PORTAGE_RSYNC_EXTRA_OPTS="-O"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --omit-dir-times --compress --force --whole-file --delete --stats --human-readable --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY=""
USE="acl amd64 bash-completion bcmath bzip2 cli cracklib crypt curl cxx device-mapper dri exif fortran ftp glibc-omitfp hpn iconv idn ipv6 mmx modules multilib ncurses nls nptl openmp pam pcre readline session slang sockets sse sse2 sse3 ssl ssse3 unicode zip zlib" ABI_X86="64" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" APACHE2_MODULES="authn_core authz_core socache_shmcb unixd actions alias auth_basic authn_alias authn_anon authn_dbm authn_default authn_file authz_dbm authz_default authz_groupfile authz_host authz_owner authz_user autoindex cache cgi cgid dav dav_fs dav_lock deflate dir disk_cache env expires ext_filter file_cache filter headers include info log_config logio mem_cache mime mime_magic negotiation rewrite setenvif speling status unique_id userdir usertrack vhost_alias" CALLIGRA_FEATURES="kexi words flow plan sheets stage tables krita karbon braindump author" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf superstar2 timing tsip tripmate tnt ublox ubx" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LIBREOFFICE_EXTENSIONS="presenter-console presenter-minimizer" LINGUAS="en" OFFICE_IMPLEMENTATION="libreoffice" PHP_TARGETS="php5-5" PYTHON_SINGLE_TARGET="python2_7" PYTHON_TARGETS="python2_7 python3_3" RUBY_TARGETS="ruby19 ruby20" USERLAND="GNU" VIDEO_CARDS="fbdev glint intel mach64 mga nouveau nv r128 radeon savage sis tdfx trident vesa via vmware dummy v4l" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account"
Unset:  CPPFLAGS, CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, SYNC, USE_PYTHON

=================================================================
                        Package Settings
=================================================================

app-admin/ulogd-2.0.3 was built with the following:
USE="nfct nflog -dbi -doc -mysql -nfacct -pcap -postgres -sqlite" ABI_X86="64"
CFLAGS="-march=native -O2 -ggdb -pipe"
CXXFLAGS="-march=native -O2 -ggdb -pipe"


Ok, I think I found what makes problem, strace shows:
# strace -f -p 4981 -s 1000
Process 4981 attached
select(7, [6], [], [], NULL)            = ? ERESTARTNOHAND (To be restarted if no handler)
--- SIGHUP {si_signo=SIGHUP, si_code=SI_USER, si_pid=5010, si_uid=0} ---
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2705, ...}) = 0
write(5, "Mon Oct 13 11:36:41 2014 <5> ulogd.c:1260 signal received, calling pluginstances\n", 81) = 81
close(5)                                = 0
munmap(0x7fb680d74000, 4096)            = 0
open("/var/log/ulogd/ulogd.log", O_WRONLY|O_CREAT|O_APPEND, 0666) = -1 EACCES (Permission denied)
write(2, "ERROR: can't open logfile /var/log/ulogd/ulogd.log: Permission denied\n", 70) = 70

After fixing permissions to file /var/log/ulogd/ulogd.log SIGHUP does what it should.
Thanks.