Gentoo Websites Logo
Go to: Gentoo Home Documentation Forums Lists Bugs Planet Store Wiki Get Gentoo!
Bug 396013 - gnome-base/gnome-session-3.2.1 in DEBUG mode flooding /var/log/messages and ~/.xsession_errors
Summary: gnome-base/gnome-session-3.2.1 in DEBUG mode flooding /var/log/messages and ~...
Status: RESOLVED FIXED
Alias: None
Product: Gentoo Linux
Classification: Unclassified
Component: [OLD] GNOME (show other bugs)
Hardware: All Linux
: Normal normal
Assignee: Gentoo Linux Gnome Desktop Team
URL: https://qa.mandriva.com/show_bug.cgi?...
Whiteboard:
Keywords:
Depends on:
Blocks:
 
Reported: 2011-12-25 20:21 UTC by Azpegath
Modified: 2013-01-08 19:57 UTC (History)
2 users (show)

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


Attachments
Parts of the .xsession_errors (debug_output.log,45.47 KB, text/plain)
2011-12-25 20:22 UTC, Azpegath
Details
DEBUG messages in syslog (messages_gnome_session,25.73 KB, text/plain)
2012-01-29 21:32 UTC, Sebastián Magrí
Details
emerge --info gdm (emerge-info-gdm.txt,6.26 KB, text/plain)
2012-01-29 22:10 UTC, Sebastián Magrí
Details
greeter log (0-greeter.log,30.11 KB, text/plain)
2012-01-29 23:04 UTC, Azpegath
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Azpegath 2011-12-25 20:21:04 UTC
It seems like gnome-session (among some others) are flooding /var/log/messages and/or ~/.xsession_errors with debug printouts. I've linked to the same issue in Mandrivas bug database, even though the issue was fixed 2,5 years ago in their case.

root@skare$ tail -n 512 ~/.xsession-errors
/etc/gdm/Xsession: Beginning session setup...
** (gnome-session:6010): DEBUG: GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
** (gnome-session:6010): DEBUG: GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
** (gnome-session:6010): DEBUG: GsmXsmpServer: accept_ice_connection()
** (gnome-session:6010): DEBUG: GsmXsmpServer: auth_ice_connection()
** (gnome-session:6010): DEBUG: GsmXSMPClient: Setting up new connection
** (gnome-session:6010): DEBUG: GsmXSMPClient: New client '0x12f7140 []'
** (gnome-session:6010): DEBUG: GsmStore: Adding object id /org/gnome/SessionManager/Client2 to store
** (gnome-session:6010): DEBUG: GsmManager: Client added: /org/gnome/SessionManager/Client2
** (gnome-session:6010): DEBUG: GsmXSMPClient: Initializing client 0x12f7140 []
** (gnome-session:6010): DEBUG: GsmXSMPClient: Client '0x12f7140 []' received RegisterClient(10e7215f19c54e6581129248405239561900000060100018)
** (gnome-session:6010): DEBUG: GsmManager: Adding new client 10e7215f19c54e6581129248405239561900000060100018 to session
** (gnome-session:6010): DEBUG: GsmManager: ending phase WINDOW_MANAGER
...
...
etc

peter@skare$ eix gnome-session
[I] gnome-base/gnome-session
     Available versions:  2.32.1-r2 2.32.1-r3 (~)3.2.1 {debug doc elibc_FreeBSD ipv6}
     Installed versions:  3.2.1(12.02.18 2011-12-20)(-doc -elibc_FreeBSD -ipv6)


Reproducible: Always

Steps to Reproduce:
1. Not sure what creates the printouts.



peter@skare$ sudo emerge --info
Lösenord: 
Portage 2.1.10.41 (default/linux/amd64/10.0/desktop/gnome, gcc-4.5.3, glibc-2.14.1-r1, 3.1.5-gentoo x86_64)
=================================================================
System uname: Linux-3.1.5-gentoo-x86_64-Intel-R-_Core-TM-_i7_CPU_920_@_2.67GHz-with-gentoo-2.1
Timestamp of tree: Sun, 25 Dec 2011 14:00:01 +0000
ccache version 3.1.6 [disabled]
app-shells/bash:          4.2_p20
dev-java/java-config:     2.1.11-r3
dev-lang/python:          2.7.2-r3, 3.1.4-r3, 3.2.2
dev-util/ccache:          3.1.6
dev-util/cmake:           2.8.6-r4
dev-util/pkgconfig:       0.26
sys-apps/baselayout:      2.1
sys-apps/openrc:          0.9.7
sys-apps/sandbox:         2.5
sys-devel/autoconf:       2.13, 2.68
sys-devel/automake:       1.9.6-r3, 1.10.3, 1.11.1-r1
sys-devel/binutils:       2.22-r1
sys-devel/gcc:            4.5.3-r2
sys-devel/gcc-config:     1.5-r2
sys-devel/libtool:        2.4.2
sys-devel/make:           3.82-r3
sys-kernel/linux-headers: 3.1 (virtual/os-headers)
sys-libs/glibc:           2.14.1-r1
Repositories: gentoo x11 multimedia sunrise voyageur qting-edge portage_overlay
ACCEPT_KEYWORDS="amd64 ~amd64"
ACCEPT_LICENSE="*"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-march=native -O2 -pipe"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc /usr/share/config /usr/share/gnupg/qualified.txt"
CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/dconf /etc/env.d /etc/env.d/java/ /etc/fonts/fonts.conf /etc/gconf /etc/gentoo-release /etc/php/apache2-php5.3/ext-active/ /etc/php/apache2-php5.4/ext-active/ /etc/php/cgi-php5.3/ext-active/ /etc/php/cgi-php5.4/ext-active/ /etc/php/cli-php5.3/ext-active/ /etc/php/cli-php5.4/ext-active/ /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo /etc/texmf/language.dat.d /etc/texmf/language.def.d /etc/texmf/updmap.d /etc/texmf/web2c"
CXXFLAGS="-march=native -O2 -pipe"
DISTDIR="/usr/portage/distfiles"
EMERGE_DEFAULT_OPTS="--jobs=9 --load-average=19 --keep-going"
FEATURES="assume-digests binpkg-logs distlocks ebuild-locks fixlafiles news parallel-fetch protect-owned sandbox sfperms splitdebug strict unknown-features-warn unmerge-logs unmerge-orphans userfetch"
FFLAGS=""
GENTOO_MIRRORS="ftp://trumpetti.atm.tut.fi/gentoo/ http://ftp.ntua.gr/pub/linux/gentoo/ ftp://ftp.linux.ee/pub/gentoo/distfiles/ http://ftp.uoi.gr/mirror/OS/gentoo/"
LANG="en_US.UTF-8"
LC_ALL="sv_SE.UTF-8"
LDFLAGS="-Wl,--as-needed"
LINGUAS="en sv"
MAKEOPTS="-j9"
PKGDIR="/usr/portage/packages"
PORTAGE_CONFIGROOT="/"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --compress --force --whole-file --delete --stats --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY="/var/lib/layman/x11 /var/lib/layman/multimedia /var/lib/layman/sunrise /var/lib/layman/voyageur /var/lib/layman/qting-edge /usr/local/portage"
SYNC="rsync://rsync.gentoo.org/gentoo-portage"
USE="3dnow 3dnowext 64bit X a52 aac aalib acl acpi alsa amazon amd64 amr amrnb amrwb apng asf aspell autoipd avahi avi bash-completion bidi binary-drivers bineditor bluetooth bookmarks branding bzip2 cairo ccache cdda cdr cg cgi cleartype cli clutter cmake colord consolekit cover coverart cracklib crypt css cue cups curl custom-optimization cxx daap dbus debugger device-mapper devil dga dirac divx dmx dns dri dts dvd dvdr dvdread emboss encode evo exif fakevim fam fastcgi fat fbcon ffmpeg firefox flac fontconfig fortran freeimage ftp fuse gaim gd gdbm gdu ggi gif git glib glut gmail gmailtimestamps gmedia gnome gnome-keyring gnome-online-accounts gnutls gpm gstreamer gtk gtk2 gtk3 gtkhtml gvim gzip h224 h281 h323 howl-compat hpijs iconv icq icu idn imap inotify introspection iphone ipod jabber java java6 jingle jpeg jpeg2k kde kdrive lame lcms ldap libnotify libsexy libssh2 libv4l libv4l2 lm_sensors mad madwifi mbox md5sum mdnsresponder-compat mh midi mikmod mime minizip mmx mmxext mng moc modplug modules mozembed mozilla moznocompose moznoirc moznomail moznoroaming mozsha1 mozsvg mp2 mp3 mp4 mp4live mpeg msn mudflap multilib multimedia musepack musicbrainz nas nautilus ncurses nemesi new-hpcups nls nptl nptlonly nsplugin nx ofa ogg openal opencore-amr opengl openmp openssl opensslcrypt optimization pam pango pcntl pcre pdf pdflib perl php pidgin pipechan png pnm policykit pop ppds pppd private-headers pulseaudio pvr python python3 qml qt3support qt4 qtscript quicktime radio raw rdesktop readline realmedia reiser4 reiserfs rtc samba sasl schroedinger scintilla sdl secure-delete session sid sip sndfile socialweb sockets spell sql sqlite srt sse sse2 sse4.1 sse4.2 ssh ssl ssse3 startup-notification subtitles subversion svg sysfs tcpd telepathy teletext tga theora threads tiff transcode truetype udev unicode upnp usb v4l v4l2 vaapi vdpau video videos vim vim-pager vim-syntax vim-with-x vorbis vte wav wavpack webdav webdav-neon webdav-serf webkit wifi wma wma-fixed wmf wmp x264 xanim xcb xcomposite xdmcp xext xine xinerama xml xorg xulrunner xv xvid xvmc zeroconf zip zlib zoran" ALSA_CARDS="emu10k1 hda_intel" ALSA_PCM_PLUGINS="adpcm alaw asym copy dmix dshare dsnoop empty extplug file hooks iec958 ioplug ladspa lfloat linear meter mmap_emul mulaw multi null plug rate route share shm softvol" APACHE2_MODULES="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 stage tables krita karbon braindump" 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 ubx" INPUT_DEVICES="evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LINGUAS="en sv" PHP_TARGETS="php5-3" RUBY_TARGETS="ruby18" USERLAND="GNU" VIDEO_CARDS="radeon" 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, INSTALL_MASK, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS
Comment 1 Azpegath 2011-12-25 20:22:38 UTC
Created attachment 296927 [details]
Parts of the .xsession_errors
Comment 2 Sebastián Magrí 2012-01-29 13:11:03 UTC
I can confirm this bug.
Comment 3 Alexandre Rostovtsev (RETIRED) gentoo-dev 2012-01-29 20:24:13 UTC
If you are launching /usr/bin/gnome-session with the --debug argument, this is expected behavior.

To check what command line was used to launch gnome-session, you can use the following:

# for p in $(pgrep gnome-session); \
  do cat /proc/${p}/cmdline | sed -e 's:\o000: :' -e 's:$:\n:' ; done
Comment 4 Sebastián Magrí 2012-01-29 20:34:49 UTC
Hi. Using your line to check the gnome-session's command line gives:

$ for p in $(pgrep gnome-session); \
>  do cat /proc/${p}/cmdline | sed -e 's:\o000: :' -e 's:$:\n:' ; done
gnome-session 

Should it be related with compile time flags?
Comment 5 Alexandre Rostovtsev (RETIRED) gentoo-dev 2012-01-29 21:05:45 UTC
(In reply to comment #4)
> Should it be related with compile time flags?

No; as far as I can tell, at least for gnome-session-3.2.1, debug mode should be controlled purely by the --debug flag at runtime.

Please attach your *complete* ~/.xsession_errors log file (compress it if it's too large; also, you may want to check whether there is any private/confidential data in it.)
Comment 6 Sebastián Magrí 2012-01-29 21:32:15 UTC
Created attachment 300313 [details]
DEBUG messages in syslog

In my case, the messages are going into /var/log/messages. While it doesn't makes sense for an user session, it seems to be related to the gnome-shell process started by GDM.

I'm attaching a grep from the /var/log/messages file from my last login.
Comment 7 Alexandre Rostovtsev (RETIRED) gentoo-dev 2012-01-29 21:54:04 UTC
(In reply to comment #6)

Then your issue is different from the OP. Gdm launches its greeter using /usr/bin/gnome-session -f --debug, which is again as intended, because it makes it feasible to diagnose strange gdm login problems.

The issue is that all logs from gdm's gnome-session are supposed to be going to /var/log/gdm/:0-greeter.log, not to /var/log/messages.

Please attach "emerge --info gdm". Are you using systemd (and if so, what version)? Which system logger are you using (e.g. app-admin/syslog-ng)? Have you customized its configuration in any way?
Comment 8 Sebastián Magrí 2012-01-29 22:10:08 UTC
Created attachment 300315 [details]
emerge --info gdm

I'm using systemd and syslog-ng.

Attached you'll find the emerge --info gdm.

The latest snippet of emerge --info systemd is the following:

sys-apps/systemd-38-r1 was built with the following:
USE="acl gtk lzma (multilib) pam plymouth tcpd -audit -cryptsetup (-selinux)"
Comment 9 Alexandre Rostovtsev (RETIRED) gentoo-dev 2012-01-29 22:26:28 UTC
This might be either a systemd bug or a misconfiguration in gdm's /lib/systemd/system/gdm.service file.

Added systemd team to the CC list, maybe they can help figure this out.
Comment 10 Azpegath 2012-01-29 23:03:51 UTC
I'm starting Gnome via GDM as well.

root@skare$ emerge --info gdm
=================================================================
                        Package Settings
=================================================================

gnome-base/gdm-3.2.1.1-r2 was built with the following:
USE="consolekit fallback gnome-keyring gnome-shell introspection (multilib) tcpd xinerama xklavier -accessibility -debug -fprint -ipv6 (-selinux) -smartcard -test"


root@skare$ for p in $(pgrep gnome-session); \
>   do cat /proc/${p}/cmdline | sed -e 's:\o000: :' -e 's:$:\n:' ; done
gnome-session 

root@skare$ ll /var/log/gdm/\:0-greeter.log
-rw-r--r-- 1 gdm gdm 31K 29 jan 18.31 /var/log/gdm/:0-greeter.log

Seems like it's printing to the :0-greeter.log as well, I've attached the output from it.
Comment 11 Azpegath 2012-01-29 23:04:41 UTC
Created attachment 300321 [details]
greeter log
Comment 12 Alexandre Rostovtsev (RETIRED) gentoo-dev 2012-01-29 23:27:57 UTC
(In reply to comment #10)(In reply to comment #11)

Gdm's gnome-session should be printing verbose debug messages to /var/log/gdm/:0-greeter.log; that is not a problem, it's things working as expected.

However, that verbose debug output should not be going to /var/log/messages. If you are seeing the same debug messages in /var/log/messages, please reply to the questions that I asked in the last paragraph of comment #7.
Comment 13 bwcknr 2012-06-07 21:52:59 UTC
As far as I can tell, it's intended behaviour of gdm to execute gnome-session in debug mode.

The problem is in gnome-session, which sends its debug output not only to stderr, but also to syslog.

There also is an open bug at https://bugzilla.gnome.org/show_bug.cgi?id=672801
Comment 14 Azpegath 2013-01-08 19:57:30 UTC
This seems to be fixed now, proposing to close it.