[SoaS] SoaS Fedora 27 beta fails to launch Sugar session

[Frederick Grose]

Tested with Fedora-SoaS-Live-x86_64-27-20171023.n.0.iso (similar failure
on Fedora-SoaS-Live-x86_64-27_Beta-1.5.iso)

The image boots to the liveuser login screen but fails to launch into the
Sugar session.

journalctl -ab -o short-monotonic > soas27bootjournal.txt

https://gist.github.com/FGrose/97d834d362c7e1e3f690b97612c673dd


The lightdm lines look suspicious to me...

​$ grep lightdm /home/fgrose/Documents/soas27bootjournal.txt

[   37.710959] localhost-live audit[1]: SERVICE_START pid=1 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0
msg='unit=lightdm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=?
addr=? terminal=? res=success'
[   39.271418] localhost-live lightdm[1324]: g_object_unref: assertion
'G_IS_OBJECT (object)' failed
[   39.328065] localhost-live audit[1378]: USER_AUTH pid=1378 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:authentication grantors=pam_permit acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   39.332199] localhost-live audit[1378]: USER_ACCT pid=1378 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   39.334772] localhost-live lightdm[1324]: g_object_unref: assertion
'G_IS_OBJECT (object)' failed
[   39.335508] localhost-live audit[1378]: CRED_ACQ pid=1378 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_permit acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   39.360390] localhost-live audit[1378]: USER_ROLE_CHANGE pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='pam:
default-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
selected-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   39.921638] localhost-live audit[1378]: USER_START pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_open
grantors=pam_selinux,pam_loginuid,pam_limits,pam_selinux,pam_keyinit,pam_namespace,pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_lastlog
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   39.921686] localhost-live audit[1378]: USER_LOGIN pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='op=login
id=1000 exe="/usr/sbin/lightdm" hostname=localhost-live addr=?
terminal=/dev/tty1 res=success'
[   39.922024] localhost-live lightdm[1378]:
pam_unix(lightdm-autologin:session): session opened for user liveuser by
(uid=0)
[   43.517645] localhost-live audit[1378]: USER_LOGOUT pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='op=logout
id=1000 exe="/usr/sbin/lightdm" hostname=localhost-live addr=?
terminal=/dev/tty1 res=success'
[   43.520204] localhost-live lightdm[1378]:
pam_unix(lightdm-autologin:session): session closed for user liveuser
[   43.520493] localhost-live audit[1378]: USER_END pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_close
grantors=pam_selinux,pam_loginuid,pam_limits,pam_selinux,pam_keyinit,pam_namespace,pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_lastlog
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   43.520540] localhost-live audit[1378]: CRED_DISP pid=1378 uid=0
auid=1000 ses=1 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_permit acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   44.222881] localhost-live lightdm[1324]: g_object_unref: assertion
'G_IS_OBJECT (object)' failed
[   44.234779] localhost-live audit[1612]: CRED_ACQ pid=1612 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_env,pam_permit acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   44.234876] localhost-live lightdm[1612]:
pam_unix(lightdm-greeter:session): session opened for user lightdm by
(uid=0)
[   44.238478] localhost-live systemd[1]: Created slice User Slice of
lightdm.
[   44.241141] localhost-live systemd-logind[1144]: New session c1 of user
lightdm.
[   44.241653] localhost-live systemd[1]: Started Session c1 of user
lightdm.
[   44.248085] localhost-live audit[1615]: USER_ACCT pid=1615 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0
msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="lightdm"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   44.266694] localhost-live systemd[1615]:
pam_unix(systemd-user:session): session opened for user lightdm by (uid=0)
[   44.267034] localhost-live audit[1615]: USER_START pid=1615 uid=0
auid=995 ses=3 subj=system_u:system_r:init_t:s0 msg='op=PAM:session_open
grantors=pam_selinux,pam_selinux,pam_loginuid,pam_keyinit,pam_limits,pam_systemd,pam_unix
acct="lightdm" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=?
res=success'
[   44.289427] localhost-live audit[1612]: USER_START pid=1612 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_open grantors=pam_unix,pam_systemd acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   44.337944] localhost-live dbus-daemon[1624]: [session uid=995 pid=1624]
Activating via systemd: service name='org.a11y.Bus'
unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=995 pid=1622
comm="/usr/sbin/lightdm-gtk-greeter "
label="system_u:system_r:xdm_t:s0-s0:c0.c1023")
[   44.358236] localhost-live at-spi-bus-launcher[1626]: dbus-daemon[1637]:
Activating service name='org.a11y.atspi.Registry' requested by ':1.0'
(uid=995 pid=1622 comm="/usr/sbin/lightdm-gtk-greeter "
label="system_u:system_r:xdm_t:s0-s0:c0.c1023")
[   51.474278] localhost-live lightdm[1647]: gkr-pam: no password is
available for user
[   51.474871] localhost-live audit[1647]: USER_AUTH pid=1647 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:authentication grantors=pam_unix,pam_gnome_keyring
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   51.476192] localhost-live audit[1647]: USER_ACCT pid=1647 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   59.085261] localhost-live systemd[1]: session-c1.scope: Killing process
1612 (lightdm) with signal SIGTERM.
[   59.085898] localhost-live systemd[1]: session-c1.scope: Killing process
1622 (lightdm-gtk-gre) with signal SIGTERM.
[   59.086182] localhost-live systemd[1]: Stopping Session c1 of user
lightdm.
[   59.093314] localhost-live lightdm[1612]:
pam_unix(lightdm-greeter:session): session closed for user lightdm
[   59.094007] localhost-live audit[1612]: USER_END pid=1612 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_close grantors=pam_unix,pam_systemd acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   59.094106] localhost-live audit[1612]: CRED_DISP pid=1612 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_env,pam_permit acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   59.094894] localhost-live systemd[1]: Stopped Session c1 of user
lightdm.
[   59.095526] localhost-live lightdm[1324]: g_object_unref: assertion
'G_IS_OBJECT (object)' failed
[   59.096416] localhost-live audit[1647]: CRED_ACQ pid=1647 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_unix,pam_gnome_keyring acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   59.115920] localhost-live systemd[1]: Removed slice User Slice of
lightdm.
[   59.120161] localhost-live audit[1647]: USER_ROLE_CHANGE pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='pam:
default-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
selected-context=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   59.256293] localhost-live lightdm[1647]: pam_unix(lightdm:session):
session opened for user liveuser by (uid=0)
[   59.256543] localhost-live audit[1647]: USER_START pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_open
grantors=pam_selinux,pam_loginuid,pam_selinux,pam_keyinit,pam_namespace,pam_gnome_keyring,pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_lastlog,pam_lastlog
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   59.257170] localhost-live audit[1647]: USER_LOGIN pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='op=login
id=1000 exe="/usr/sbin/lightdm" hostname=localhost-live addr=?
terminal=/dev/tty1 res=success'
[   59.580890] localhost-live audit[1647]: USER_LOGOUT pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023 msg='op=logout
id=1000 exe="/usr/sbin/lightdm" hostname=localhost-live addr=?
terminal=/dev/tty1 res=success'
[   59.582745] localhost-live lightdm[1647]: pam_unix(lightdm:session):
session closed for user liveuser
[   59.583040] localhost-live audit[1647]: USER_END pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_close
grantors=pam_selinux,pam_loginuid,pam_selinux,pam_keyinit,pam_namespace,pam_gnome_keyring,pam_keyinit,pam_limits,pam_systemd,pam_unix,pam_lastlog,pam_lastlog
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   59.583096] localhost-live audit[1647]: CRED_DISP pid=1647 uid=0
auid=1000 ses=4 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_unix,pam_gnome_keyring acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   60.264070] localhost-live lightdm[1324]: g_object_unref: assertion
'G_IS_OBJECT (object)' failed
[   60.274063] localhost-live audit[1829]: CRED_ACQ pid=1829 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:setcred grantors=pam_env,pam_permit acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   60.274188] localhost-live lightdm[1829]:
pam_unix(lightdm-greeter:session): session opened for user lightdm by
(uid=0)
[   60.277834] localhost-live systemd[1]: Created slice User Slice of
lightdm.
[   60.280142] localhost-live systemd-logind[1144]: New session c2 of user
lightdm.
[   60.280521] localhost-live systemd[1]: Started Session c2 of user
lightdm.
[   60.285900] localhost-live audit[1832]: USER_ACCT pid=1832 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0
msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="lightdm"
exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   60.302292] localhost-live systemd[1832]:
pam_unix(systemd-user:session): session opened for user lightdm by (uid=0)
[   60.302614] localhost-live audit[1832]: USER_START pid=1832 uid=0
auid=995 ses=6 subj=system_u:system_r:init_t:s0 msg='op=PAM:session_open
grantors=pam_selinux,pam_selinux,pam_loginuid,pam_keyinit,pam_limits,pam_systemd,pam_unix
acct="lightdm" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=?
res=success'
[   60.324684] localhost-live audit[1829]: USER_START pid=1829 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:session_open grantors=pam_unix,pam_systemd acct="lightdm"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
[   60.349692] localhost-live dbus-daemon[1841]: [session uid=995 pid=1841]
Activating via systemd: service name='org.a11y.Bus'
unit='at-spi-dbus-bus.service' requested by ':1.1' (uid=995 pid=1839
comm="/usr/sbin/lightdm-gtk-greeter "
label="system_u:system_r:xdm_t:s0-s0:c0.c1023")
[   60.374242] localhost-live at-spi-bus-launcher[1843]: dbus-daemon[1854]:
Activating service name='org.a11y.atspi.Registry' requested by ':1.0'
(uid=995 pid=1839 comm="/usr/sbin/lightdm-gtk-greeter "
label="system_u:system_r:xdm_t:s0-s0:c0.c1023")
[   60.924180] localhost-live lightdm[1864]: gkr-pam: no password is
available for user
[   60.924564] localhost-live audit[1864]: USER_AUTH pid=1864 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:authentication grantors=pam_unix,pam_gnome_keyring
acct="liveuser" exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0
res=success'
[   60.926038] localhost-live audit[1864]: USER_ACCT pid=1864 uid=0
auid=4294967295 ses=4294967295 subj=system_u:system_r:xdm_t:s0-s0:c0.c1023
msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="liveuser"
exe="/usr/sbin/lightdm" hostname=? addr=? terminal=:0 res=success'
​
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sugarlabs.org/archive/soas/attachments/20171023/0a00fc85/attachment-0001.html>