[SOLVED] Could not acquire name on session bus / Failed to connect to.

PTBM133A4X · 2017-08-26 20:16:45

I'm using MATE desktop at the moment but I'm also having an issue on XFCE.

With a fully updated system I get as far a the LightDM login window but when I type in the password, I am greeted with a dialog box saying:

"Could not acquire name on session bus"

When I close that window, another one opens saying:

"Could not connect to session bus: Failed to connect to socket /tmp/dbus-{random stuff here]: Connection refused"

When I try to login in to XFCE I get something similar:

"Unable to contact settings server
Failed to connect to socket /tmp/dbus-{random stuff here]: Connection refused"

From reading other posts, one suggested command was:

[john@jupiter64 ~]$ printenv DBUS_SESSION_BUS_ADDRESS
unix:path=/run/user/1000/bus

What I found was that downgrading systemd to 232-8 fixes the problem but there's obviously something else going on.

Any advice would be appreciated.

Last edited by PTBM133A4X (2017-08-27 15:09:43)

loqs · 2017-08-26 20:56:11

Please post the contents of /var/log/lightdm/lightdm.log and the journal for a boot where the issue occurs.

PTBM133A4X wrote:

"Unable to contact settings server
Failed to connect to socket /tmp/dbus-{random stuff here]: Connection refused"

Please do not paraphrase like that. How did you arrive upon system 232-8? Which other versions have you tested?
Edit:
Also the output of

$ ls /etc/X11/xinit/xinitrc.d/

Last edited by loqs (2017-08-26 21:02:51)

PTBM133A4X · 2017-08-26 21:46:27

loqs wrote:

Please post the contents of /var/log/lightdm/lightdm.log and the journal for a boot where the issue occurs.

[+0.00s] DEBUG: Logging to /var/log/lightdm/lightdm.log
[+0.00s] DEBUG: Starting Light Display Manager 1.22.0, UID=0 PID=557
[+0.00s] DEBUG: Loading configuration dirs from /usr/share/lightdm/lightdm.conf.d
[+0.00s] DEBUG: Loading configuration dirs from /usr/local/share/lightdm/lightdm.conf.d
[+0.00s] DEBUG: Loading configuration dirs from /etc/xdg/lightdm/lightdm.conf.d
[+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf
[+0.00s] DEBUG:   [SeatDefaults] is now called [Seat:*], please update this configuration
[+0.00s] DEBUG: Registered seat module local
[+0.00s] DEBUG: Registered seat module xremote
[+0.00s] DEBUG: Registered seat module unity
[+0.00s] DEBUG: Using D-Bus name org.freedesktop.DisplayManager
[+0.39s] DEBUG: Monitoring logind for seats
[+0.39s] DEBUG: New seat added from logind: seat0
[+0.39s] DEBUG: Seat seat0: Loading properties from config section Seat:*
[+0.39s] DEBUG: Seat seat0: Starting
[+0.39s] DEBUG: Seat seat0: Creating greeter session
[+0.39s] DEBUG: Seat seat0: Creating display server of type x
[+0.39s] DEBUG: Could not run plymouth --ping: Failed to execute child process “plymouth” (No such file or directory)
[+0.39s] DEBUG: Using VT 7
[+0.39s] DEBUG: Seat seat0: Starting local X display on VT 7
[+0.39s] DEBUG: XServer 0: Logging to /var/log/lightdm/x-0.log
[+0.39s] DEBUG: XServer 0: Writing X server authority to /run/lightdm/root/:0
[+0.39s] DEBUG: XServer 0: Launching X Server
[+0.39s] DEBUG: Launching process 625: /usr/sbin/X -dpi 109 :0 -seat seat0 -auth /run/lightdm/root/:0 -nolisten tcp vt7 -novtswitch
[+0.39s] DEBUG: XServer 0: Waiting for ready signal from X server :0
[+0.39s] DEBUG: Acquired bus name org.freedesktop.DisplayManager
[+0.39s] DEBUG: Registering seat with bus path /org/freedesktop/DisplayManager/Seat0
[+0.39s] WARNING: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
[+0.39s] DEBUG: Loading user config from /etc/lightdm/users.conf
[+0.39s] DEBUG: User john added
[+1.96s] DEBUG: Got signal 10 from process 625
[+1.96s] DEBUG: XServer 0: Got signal from X server :0
[+1.96s] DEBUG: XServer 0: Connecting to XServer :0
[+2.00s] DEBUG: Launching process 645: xrandr --output DVI-I-2 --primary
[+2.01s] DEBUG: Process 645 exited with return value 0
[+2.01s] DEBUG: Seat seat0: Exit status of xrandr --output DVI-I-2 --primary: 0
[+2.01s] CRITICAL: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
[+2.01s] DEBUG: Seat seat0: Display server ready, starting session authentication
[+2.01s] DEBUG: Session pid=647: Started with service 'lightdm-greeter', username 'lightdm'
[+2.02s] DEBUG: Session pid=647: Authentication complete with return value 0: Success
[+2.02s] DEBUG: Seat seat0: Session authenticated, running command
[+2.02s] DEBUG: Session pid=647: Running command /usr/sbin/lightdm-gtk-greeter
[+2.02s] DEBUG: Creating shared data directory /var/lib/lightdm-data/lightdm
[+2.02s] DEBUG: Session pid=647: Logging to /var/log/lightdm/seat0-greeter.log
[+2.05s] DEBUG: Activating VT 7
[+2.05s] DEBUG: Seat seat0 changes active session to c1
[+2.15s] DEBUG: Greeter connected version=1.22.0 api=1 resettable=false
[+2.49s] DEBUG: Greeter start authentication for john
[+2.49s] DEBUG: Session pid=669: Started with service 'lightdm', username 'john'
[+2.50s] DEBUG: Session pid=669: Got 1 message(s) from PAM
[+2.50s] DEBUG: Prompt greeter with 1 message(s)
[+8.54s] DEBUG: Seat seat0 changes active session to 
[+18.25s] DEBUG: Seat seat0 changes active session to c2
[+85.40s] DEBUG: Seat seat0 changes active session to c1
[+89.02s] DEBUG: Continue authentication
[+89.04s] DEBUG: Session pid=669: Authentication complete with return value 0: Success
[+89.04s] DEBUG: Authenticate result for user john: Success
[+89.04s] DEBUG: User john authorized
[+89.07s] DEBUG: Greeter sets language en_GB.utf8
[+89.15s] DEBUG: Greeter requests session mate
[+89.15s] DEBUG: Seat seat0: Stopping greeter; display server will be re-used for user session
[+89.15s] DEBUG: Session pid=647: Sending SIGTERM
[+89.17s] DEBUG: Greeter closed communication channel
[+89.17s] DEBUG: Session pid=647: Exited with return value 0
[+89.17s] DEBUG: Seat seat0: Session stopped
[+89.17s] DEBUG: Seat seat0: Greeter stopped, running session
[+89.17s] DEBUG: Registering session with bus path /org/freedesktop/DisplayManager/Session0
[+89.17s] CRITICAL: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
[+89.17s] DEBUG: Session pid=669: Running command /etc/lightdm/Xsession mate-session
[+89.17s] DEBUG: Creating shared data directory /var/lib/lightdm-data/john
[+89.17s] DEBUG: Session pid=669: Logging to .xsession-errors
[+89.23s] DEBUG: Activating VT 7
[+89.23s] DEBUG: Activating login1 session c3
[+89.23s] DEBUG: Seat seat0 changes active session to 
[+89.23s] DEBUG: Seat seat0 changes active session to c3
[+89.23s] DEBUG: Session c3 is already active
[+91.49s] DEBUG: Seat seat0 changes active session to c2

I've snipped the kernel boot section to keep the size down. If that's needed I can change this post.

-- Logs begin at Tue 2017-05-09 21:36:56 IST, end at Sat 2017-08-26 22:07:22 IST. --

Aug 26 22:04:48 jupiter64 systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN default-hierarchy=hybrid)
Aug 26 22:04:48 jupiter64 systemd[1]: Detected architecture x86-64.
Aug 26 22:04:48 jupiter64 systemd[1]: Set hostname to <jupiter64>.
Aug 26 22:04:48 jupiter64 systemd-fstab-generator[210]: Failed to create swap unit file /run/systemd/generator/dev-disk-by\x2duuid-6258e656\x2da5f6\x2d4996\x2db9f9\x2d840da14ffd00.swap, as it already exists. Duplicate entry in /etc/fstab?
Aug 26 22:04:48 jupiter64 systemd[206]: /usr/lib/systemd/system-generators/systemd-fstab-generator failed with error code 1.
Aug 26 22:04:48 jupiter64 systemd[1]: Listening on LVM2 metadata daemon socket.
Aug 26 22:04:48 jupiter64 systemd[1]: Listening on Process Core Dump Socket.
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Remote File Systems.
Aug 26 22:04:48 jupiter64 systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Aug 26 22:04:48 jupiter64 systemd[1]: Created slice User and Session Slice.
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Login Prompts.
Aug 26 22:04:48 jupiter64 kernel: loop: module loaded
Aug 26 22:04:48 jupiter64 kernel: vboxdrv: loading out-of-tree module taints kernel.
Aug 26 22:04:48 jupiter64 kernel: vboxdrv: Found 4 processor cores
Aug 26 22:04:48 jupiter64 systemd-journald[223]: Journal started
Aug 26 22:04:48 jupiter64 systemd-journald[223]: Runtime journal (/run/log/journal/cdd5d9173ebd4c71a5718cb3ab3d2b64) is 8.0M, max 800.9M, 792.9M free.
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'loop'
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'snd_pcm_oss'
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Flush Journal to Persistent Storage...
Aug 26 22:04:48 jupiter64 systemd-journald[223]: Time spent on flushing to /var is 64.152ms for 720 entries.
Aug 26 22:04:48 jupiter64 systemd-journald[223]: System journal (/var/log/journal/cdd5d9173ebd4c71a5718cb3ab3d2b64) is 130.0M, max 100.0M, 0B free.
Aug 26 22:04:48 jupiter64 kernel: vboxdrv: TSC mode is Invariant, tentative frequency 3292521277 Hz
Aug 26 22:04:48 jupiter64 kernel: vboxdrv: Successfully loaded version 5.1.26 (interface 0x002a0000)
Aug 26 22:04:48 jupiter64 kernel: VBoxPciLinuxInit
Aug 26 22:04:48 jupiter64 kernel: vboxpci: IOMMU not found (not registered)
Aug 26 22:04:48 jupiter64 kernel: VBoxNetAdp: Successfully started.
Aug 26 22:04:48 jupiter64 kernel: VBoxNetFlt: Successfully started.
Aug 26 22:04:48 jupiter64 systemd[1]: Started Create Static Device Nodes in /dev.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting udev Kernel Device Manager...
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Local File Systems (Pre).
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'vboxdrv'
Aug 26 22:04:48 jupiter64 systemd[1]: Mounting /tmp...
Aug 26 22:04:48 jupiter64 systemd[1]: Mounted /tmp.
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'vboxpci'
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'vboxnetadp'
Aug 26 22:04:48 jupiter64 systemd-modules-load[220]: Inserted module 'vboxnetflt'
Aug 26 22:04:48 jupiter64 systemd[1]: Started Load Kernel Modules.
Aug 26 22:04:48 jupiter64 systemd[1]: Mounting Kernel Configuration File System...
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Apply Kernel Variables...
Aug 26 22:04:48 jupiter64 systemd[1]: Mounted Kernel Configuration File System.
Aug 26 22:04:48 jupiter64 systemd-udevd[241]: invalid key/value pair in file /etc/udev/rules.d/45-opencbm-usb.rules on line 4, starting at character 68 (',')
Aug 26 22:04:48 jupiter64 systemd[1]: Started Apply Kernel Variables.
Aug 26 22:04:48 jupiter64 systemd[1]: Started udev Kernel Device Manager.
Aug 26 22:04:48 jupiter64 systemd[1]: Started Flush Journal to Persistent Storage.
Aug 26 22:04:48 jupiter64 systemd[1]: Started Rebuild Hardware Database.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting udev Coldplug all Devices...
Aug 26 22:04:48 jupiter64 systemd[1]: Started udev Coldplug all Devices.
Aug 26 22:04:48 jupiter64 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input5
Aug 26 22:04:48 jupiter64 kernel: ACPI: Power Button [PWRB]
Aug 26 22:04:48 jupiter64 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input6
Aug 26 22:04:48 jupiter64 kernel: ACPI: Power Button [PWRF]
Aug 26 22:04:48 jupiter64 kernel: wmi: Mapper loaded
Aug 26 22:04:48 jupiter64 kernel: i8042: PNP: No PS/2 controller found.
Aug 26 22:04:48 jupiter64 kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
Aug 26 22:04:48 jupiter64 kernel: r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded
Aug 26 22:04:48 jupiter64 kernel: r8169 0000:0a:00.0 eth0: RTL8168evl/8111evl at 0xffffbc1c419e9000, 8c:89:a5:57:81:d0, XID 0c900800 IRQ 39
Aug 26 22:04:48 jupiter64 kernel: r8169 0000:0a:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
Aug 26 22:04:48 jupiter64 kernel: i8042: PNP: No PS/2 controller found.
Aug 26 22:04:48 jupiter64 kernel: media: Linux media interface: v0.10
Aug 26 22:04:48 jupiter64 kernel: snd_hda_intel 0000:01:00.1: Disabling MSI
Aug 26 22:04:48 jupiter64 kernel: snd_hda_intel 0000:01:00.1: Handle vga_switcheroo audio client
Aug 26 22:04:48 jupiter64 kernel: gameport gameport0: EMU10K1 is pci0000:08:01.1/gameport0, io 0xc020, speed 449kHz
Aug 26 22:04:48 jupiter64 kernel: Linux video capture interface: v2.00
Aug 26 22:04:48 jupiter64 systemd[1]: Found device SanDisk_Ultra_II_960GB Linux\x20swap.
Aug 26 22:04:48 jupiter64 systemd[1]: Activating swap /dev/disk/by-uuid/6258e656-a5f6-4996-b9f9-840da14ffd00...
Aug 26 22:04:48 jupiter64 swapon[364]: swapon: /dev/sda2: insecure permissions 0664, 0660 suggested.
Aug 26 22:04:48 jupiter64 systemd[1]: Found device SanDisk_Ultra_II_960GB Linux\x20filesystem.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting File System Check on /dev/disk/by-uuid/f1eb903e-e757-414b-b05b-58193a62e1cc...
Aug 26 22:04:48 jupiter64 systemd[1]: Activated swap /dev/disk/by-uuid/6258e656-a5f6-4996-b9f9-840da14ffd00.
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Swap.
Aug 26 22:04:48 jupiter64 kernel: Adding 2048280k swap on /dev/sda2.  Priority:-1 extents:1 across:2048280k SSDscFS
Aug 26 22:04:48 jupiter64 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
Aug 26 22:04:48 jupiter64 kernel: RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 163840 ms ovfl timer
Aug 26 22:04:48 jupiter64 kernel: RAPL PMU: hw unit of domain pp0-core 2^-16 Joules
Aug 26 22:04:48 jupiter64 kernel: RAPL PMU: hw unit of domain package 2^-16 Joules
Aug 26 22:04:48 jupiter64 kernel: RAPL PMU: hw unit of domain pp1-gpu 2^-16 Joules
Aug 26 22:04:48 jupiter64 kernel: input: PC Speaker as /devices/platform/pcspkr/input/input7
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1: autoconfig for ALC892: line_outs=4 (0x14/0x15/0x16/0x17/0x0) type:line
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:    hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:    mono: mono_out=0x0
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:    dig-out=0x11/0x1e
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:    inputs:
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:      Front Mic=0x19
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:      Rear Mic=0x18
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_realtek hdaudioC2D1:      Line=0x1a
Aug 26 22:04:48 jupiter64 kernel: ivtv: Start initialization, version 1.4.3
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Initializing card 0
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Autodetected Hauppauge card (cx23415 based)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Unreasonably low latency timer, setting to 64 (was 32)
Aug 26 22:04:48 jupiter64 kernel: AVX version of gcm_enc/dec engaged.
Aug 26 22:04:48 jupiter64 kernel: AES CTR mode by8 optimization enabled
Aug 26 22:04:48 jupiter64 mtp-probe[372]: checking bus 1, device 3: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.4"
Aug 26 22:04:48 jupiter64 mtp-probe[372]: bus: 1, device: 3 was not an MTP device
Aug 26 22:04:48 jupiter64 systemd-fsck[369]: /dev/sda3: clean, 370303/56770560 files, 124042974/227052669 blocks
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card2/input8
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1b.0/sound/card2/input9
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1b.0/sound/card2/input10
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Line Out Front as /devices/pci0000:00/0000:00:1b.0/sound/card2/input11
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Line Out Surround as /devices/pci0000:00/0000:00:1b.0/sound/card2/input12
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Line Out CLFE as /devices/pci0000:00/0000:00:1b.0/sound/card2/input13
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Line Out Side as /devices/pci0000:00/0000:00:1b.0/sound/card2/input14
Aug 26 22:04:48 jupiter64 kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card2/input15
Aug 26 22:04:48 jupiter64 systemd[1]: Started File System Check on /dev/disk/by-uuid/f1eb903e-e757-414b-b05b-58193a62e1cc.
Aug 26 22:04:48 jupiter64 systemd[1]: home.mount: Directory /home to mount over is not empty, mounting anyway.
Aug 26 22:04:48 jupiter64 systemd[1]: Mounting /home...
Aug 26 22:04:48 jupiter64 systemd[1]: Mounted /home.
Aug 26 22:04:48 jupiter64 kernel: EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null)
Aug 26 22:04:48 jupiter64 systemd[1]: Found device SAMSUNG_HD502IJ Steam.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting File System Check on /dev/disk/by-uuid/37afc1f6-f805-4fea-a731-f5af32271e3e...
Aug 26 22:04:48 jupiter64 kernel: tveeprom: Hauppauge model 48135, rev J324, serial# 7205620
Aug 26 22:04:48 jupiter64 kernel: tveeprom: tuner model is Philips FM1246 (idx 24, type 1)
Aug 26 22:04:48 jupiter64 kernel: tveeprom: TV standards PAL(I) (eeprom 0x10)
Aug 26 22:04:48 jupiter64 kernel: tveeprom: audio processor is MSP4418 (idx 25)
Aug 26 22:04:48 jupiter64 kernel: tveeprom: decoder processor is SAA7115 (idx 19)
Aug 26 22:04:48 jupiter64 kernel: tveeprom: has radio, has IR receiver, has no IR transmitter
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Autodetected Hauppauge WinTV PVR-350
Aug 26 22:04:48 jupiter64 systemd-fsck[417]: Steam: clean, 237728/30531584 files, 57157255/122096390 blocks
Aug 26 22:04:48 jupiter64 kernel: gpio_ich: GPIO from 436 to 511 on gpio_ich
Aug 26 22:04:48 jupiter64 kernel: iTCO_vendor_support: vendor-support=0
Aug 26 22:04:48 jupiter64 kernel: iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
Aug 26 22:04:48 jupiter64 kernel: iTCO_wdt: Found a Cougar Point TCO device (Version=2, TCOBASE=0x0460)
Aug 26 22:04:48 jupiter64 kernel: iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
Aug 26 22:04:48 jupiter64 kernel: intel_rapl: Found RAPL domain package
Aug 26 22:04:48 jupiter64 kernel: intel_rapl: Found RAPL domain core
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Sound Card.
Aug 26 22:04:48 jupiter64 kernel: saa7115 1-0021: saa7115 found @ 0x42 (ivtv i2c driver #0)
Aug 26 22:04:48 jupiter64 mtp-probe[447]: checking bus 2, device 5: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7.1"
Aug 26 22:04:48 jupiter64 mtp-probe[447]: bus: 2, device: 5 was not an MTP device
Aug 26 22:04:48 jupiter64 kernel: mousedev: PS/2 mouse device common for all mice
Aug 26 22:04:48 jupiter64 mtp-probe[454]: checking bus 2, device 6: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.7/2-1.7.2"
Aug 26 22:04:48 jupiter64 mtp-probe[454]: bus: 2, device: 6 was not an MTP device
Aug 26 22:04:48 jupiter64 systemd-udevd[283]: Process '/usr/bin/alsactl restore 0' failed with exit code 99.
Aug 26 22:04:48 jupiter64 systemd-udevd[270]: Process '/usr/bin/alsactl restore 2' failed with exit code 99.
Aug 26 22:04:48 jupiter64 systemd[1]: Started File System Check on /dev/disk/by-uuid/37afc1f6-f805-4fea-a731-f5af32271e3e.
Aug 26 22:04:48 jupiter64 systemd[1]: Mounting /home/john/Steam...
Aug 26 22:04:48 jupiter64 systemd[1]: Mounted /home/john/Steam.
Aug 26 22:04:48 jupiter64 systemd[1]: Reached target Local File Systems.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Create Volatile Files and Directories...
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Rebuild Dynamic Linker Cache...
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Rebuild Journal Catalog...
Aug 26 22:04:48 jupiter64 systemd[1]: Started Rebuild Journal Catalog.
Aug 26 22:04:48 jupiter64 systemd[1]: Started Create Volatile Files and Directories.
Aug 26 22:04:48 jupiter64 systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Aug 26 22:04:48 jupiter64 systemd[1]: Started Update UTMP about System Boot/Shutdown.
Aug 26 22:04:48 jupiter64 kernel: nvidia: module license 'NVIDIA' taints kernel.
Aug 26 22:04:48 jupiter64 kernel: Disabling lock debugging due to kernel taint
Aug 26 22:04:48 jupiter64 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 242
Aug 26 22:04:48 jupiter64 kernel: nvidia 0000:01:00.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=none:owns=io+mem
Aug 26 22:04:48 jupiter64 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module  384.59  Wed Jul 19 23:53:34 PDT 2017 (using threaded interrupts)
Aug 26 22:04:48 jupiter64 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms  384.59  Wed Jul 19 23:46:42 PDT 2017
Aug 26 22:04:48 jupiter64 kernel: [drm] [nvidia-drm] [GPU ID 0x00000100] Loading driver
Aug 26 22:04:48 jupiter64 kernel: [drm] Initialized nvidia-drm 0.0.0 20160202 for 0000:01:00.0 on minor 0
Aug 26 22:04:48 jupiter64 kernel: EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
Aug 26 22:04:48 jupiter64 kernel: random: crng init done
Aug 26 22:04:48 jupiter64 kernel: snd_hda_codec_hdmi: unknown parameter 'index' ignored
Aug 26 22:04:48 jupiter64 kernel: saa7127 1-0044: saa7127 found @ 0x88 (ivtv i2c driver #0)
Aug 26 22:04:48 jupiter64 kernel: msp3400 1-0040: MSP4418G-A2 found on ivtv i2c driver #0: supports nicam and radio, mode is autodetect and autoselect
Aug 26 22:04:48 jupiter64 kernel: tuner: 1-0061: Tuner -1 found with type(s) Radio TV.
Aug 26 22:04:48 jupiter64 kernel: tuner-simple 1-0061: creating new instance
Aug 26 22:04:48 jupiter64 kernel: tuner-simple 1-0061: type set to 1 (Philips PAL_I (FI1246 and compatibles))
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device video0 for encoder MPG (4096 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device video32 for encoder YUV (2048 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device vbi0 for encoder VBI (1024 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device video24 for encoder PCM (320 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device radio0 for encoder radio
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device video16 for decoder MPG (1024 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device vbi8 for decoder VBI (64 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device vbi16 for decoder VOUT
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Registered device video48 for decoder YUV (1024 kB)
Aug 26 22:04:48 jupiter64 kernel: ivtv0: Initialized card: Hauppauge WinTV PVR-350
Aug 26 22:04:48 jupiter64 kernel: ivtv: End initialization
Aug 26 22:04:48 jupiter64 kernel: ivtv_alsa: ivtv-alsa: module loading...
Aug 26 22:04:48 jupiter64 kernel: ivtv_alsa: ivtv0-alsa: snd_ivtv_init: Instance 0 registered as ALSA card 4
Aug 26 22:04:49 jupiter64 systemd[1]: Started Rebuild Dynamic Linker Cache.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Update is Completed...
Aug 26 22:04:49 jupiter64 systemd[1]: Started Update is Completed.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target System Initialization.
Aug 26 22:04:49 jupiter64 systemd[1]: Listening on D-Bus System Message Bus Socket.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Daily rotation of log files.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Daily locate database update.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Daily Cleanup of Temporary Directories.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Daily man-db cache update.
Aug 26 22:04:49 jupiter64 systemd[1]: Started CUPS Scheduler.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Paths.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Daily verification of password and group files.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Timers.
Aug 26 22:04:49 jupiter64 systemd[1]: Listening on CUPS Scheduler.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Sockets.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Basic System.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Initialize hardware monitoring sensors...
Aug 26 22:04:49 jupiter64 systemd[1]: Started D-Bus System Message Bus.
Aug 26 22:04:49 jupiter64 kernel: f71882fg: Found f71889a chip at 0x290, revision 49
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: Fan: 1 is in duty-cycle mode
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: Fan: 2 is in duty-cycle mode
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: Auto pwm controlled by raw digital data, disabling pwm auto_point sysfs attributes for fan 2
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: Fan: 3 is in duty-cycle mode
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: Auto pwm controlled by raw digital data, disabling pwm auto_point sysfs attributes for fan 3
Aug 26 22:04:49 jupiter64 kernel: f71882fg f71882fg.656: hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
Aug 26 22:04:49 jupiter64 systemd[1]: Started Periodic Command Scheduler.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Entropy Harvesting Daemon.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Wired Static IP Connectivity...
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Login Service...
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Save/Restore Sound Card State...
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Network Manager...
Aug 26 22:04:49 jupiter64 kernel: input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card3/input16
Aug 26 22:04:49 jupiter64 kernel: input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card3/input17
Aug 26 22:04:49 jupiter64 kernel: input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card3/input18
Aug 26 22:04:49 jupiter64 kernel: input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card3/input19
Aug 26 22:04:49 jupiter64 crond[535]: (CRON) INFO (Syslog will be used instead of sendmail.)
Aug 26 22:04:49 jupiter64 crond[535]: (CRON) INFO (RANDOM_DELAY will be scaled with factor 86% if used.)
Aug 26 22:04:49 jupiter64 systemd[1]: Started Save/Restore Sound Card State.
Aug 26 22:04:49 jupiter64 crond[535]: (CRON) INFO (running with inotify support)
Aug 26 22:04:49 jupiter64 systemd[1]: Started Login Service.
Aug 26 22:04:49 jupiter64 systemd-logind[538]: New seat seat0.
Aug 26 22:04:49 jupiter64 systemd[1]: Started Initialize hardware monitoring sensors.
Aug 26 22:04:49 jupiter64 systemd-logind[538]: Watching system buttons on /dev/input/event6 (Power Button)
Aug 26 22:04:49 jupiter64 systemd-logind[538]: Watching system buttons on /dev/input/event5 (Power Button)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.2417] NetworkManager (version 1.8.3dev+18+gf43222d41-1, Arch Linux) is starting... (for the first time)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.2418] Read config: /etc/NetworkManager/NetworkManager.conf (lib: 20-connectivity.conf)
Aug 26 22:04:49 jupiter64 kernel: r8169 0000:0a:00.0 eth0: link down
Aug 26 22:04:49 jupiter64 kernel: r8169 0000:0a:00.0 eth0: link down
Aug 26 22:04:49 jupiter64 kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug 26 22:04:49 jupiter64 systemd[1]: Started Wired Static IP Connectivity.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.2930] manager[0x563a585370c0]: monitoring kernel firmware directory '/usr/lib/firmware'.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.2938] policy: hostname management mode: default
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.2940] dns-mgr[0x563a58540160]: init: dns=dnsmasq, rc-manager=resolvconf, plugin=dnsmasq
Aug 26 22:04:49 jupiter64 systemd[1]: Started Network Manager.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Network.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Network Time Service...
Aug 26 22:04:49 jupiter64 systemd[1]: Started vnStat network traffic monitor.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Permit User Sessions...
Aug 26 22:04:49 jupiter64 systemd[1]: Starting CUPS Scheduler...
Aug 26 22:04:49 jupiter64 systemd[1]: Started Permit User Sessions.
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Light Display Manager...
Aug 26 22:04:49 jupiter64 vnstatd[553]: Info: vnStat daemon 1.17 started. (pid:553 uid:0 gid:0)
Aug 26 22:04:49 jupiter64 vnstatd[553]: Info: Monitoring: eth0 (10 Mbit)
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Unit dbus-org.freedesktop.nm-dispatcher.service not found.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <error> [1503781489.3080] dispatcher: could not get dispatcher proxy! Error calling StartServiceByName for org.freedesktop.nm_dispatcher: GDBus.Error:org.freedesktop.systemd1.NoSuchUnit: Unit dbus-org.freedesktop.nm-dispatcher.service not found.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.3081] settings: loaded plugin keyfile: (c) 2007 - 2016 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Aug 26 22:04:49 jupiter64 ntpd[552]: ntpd 4.2.8p10@1.3728-o Mon Apr 24 18:57:17 UTC 2017 (1): Starting
Aug 26 22:04:49 jupiter64 ntpd[552]: Command line: /usr/bin/ntpd -g -u ntp:ntp
Aug 26 22:04:49 jupiter64 systemd[1]: Started Network Time Service.
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Multi-User System.
Aug 26 22:04:49 jupiter64 ntpd[560]: proto: precision = 0.050 usec (-24)
Aug 26 22:04:49 jupiter64 ntpd[560]: Listen and drop on 0 v6wildcard [::]:123
Aug 26 22:04:49 jupiter64 ntpd[560]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.3201] keyfile: new connection /etc/NetworkManager/system-connections/RT2600 (24915b80-6993-4783-ac62-3d9773d0477d,"RT2600")
Aug 26 22:04:49 jupiter64 ntpd[560]: Listen normally on 2 lo 127.0.0.1:123
Aug 26 22:04:49 jupiter64 ntpd[560]: Listen normally on 3 lo [::1]:123
Aug 26 22:04:49 jupiter64 ntpd[560]: Listening on routing socket on fd #20 for interface updates
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.3293] keyfile: new connection /etc/NetworkManager/system-connections/Sky Hub (e1a84a7e-08d8-4935-a6be-1ba2e634e3d2,"BT")
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Hostname Service...
Aug 26 22:04:49 jupiter64 haveged[536]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 7.1.1 ITV); collect: 128K
Aug 26 22:04:49 jupiter64 haveged[536]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 22/40; sz: 32398/59688
Aug 26 22:04:49 jupiter64 haveged[536]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B):  last entropy estimate 7.99785
Aug 26 22:04:49 jupiter64 haveged[536]: haveged: fills: 0, generated: 0
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.ColorManager' unit='colord.service'
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Manage, Install and Generate Color Profiles...
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Successfully activated service 'org.freedesktop.ColorManager'
Aug 26 22:04:49 jupiter64 systemd[1]: Started Manage, Install and Generate Color Profiles.
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Successfully activated service 'org.freedesktop.hostname1'
Aug 26 22:04:49 jupiter64 systemd[1]: Started Hostname Service.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4731] settings: hostname: using hostnamed
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4731] settings: hostname changed from (none) to "jupiter64"
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4737] dhcp-init: Using DHCP client 'dhclient'
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4737] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4737] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4737] manager: Networking is enabled by state file
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4749] Loaded device plugin: NMBondDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4750] Loaded device plugin: NMBridgeDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4751] Loaded device plugin: NMDummyDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4751] Loaded device plugin: NMEthernetDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4751] Loaded device plugin: NMInfinibandDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4751] Loaded device plugin: NMIPTunnelDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4751] Loaded device plugin: NMMacsecDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4754] Loaded device plugin: NMMacvlanDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4754] Loaded device plugin: NMTunDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4754] Loaded device plugin: NMVethDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4754] Loaded device plugin: NMVlanDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4754] Loaded device plugin: NMVxlanDeviceFactory (internal)
Aug 26 22:04:49 jupiter64 colord[565]: failed to get session [pid 555]: No data available
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4768] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/libnm-device-plugin-wifi.so)
Aug 26 22:04:49 jupiter64 colord[565]: failed to get session [pid 555]: No data available
Aug 26 22:04:49 jupiter64 systemd[1]: Started CUPS Scheduler.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4830] Loaded device plugin: NMTeamFactory (/usr/lib/NetworkManager/libnm-device-plugin-team.so)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4838] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/libnm-device-plugin-adsl.so)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4891] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/libnm-device-plugin-bluetooth.so)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4897] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/libnm-device-plugin-wwan.so)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4906] device (lo): link connected
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4911] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4919] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4930] manager: (eth0): assume: will attempt to assume matching connection 'BT' (e1a84a7e-08d8-4935-a6be-1ba2e634e3d2) (guessed)
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4930] device (eth0): state change: unmanaged -> unavailable (reason 'connection-assumed', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4932] device (eth0): state change: unavailable -> disconnected (reason 'connection-assumed', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4940] device (eth0): Activation: starting connection 'BT' (e1a84a7e-08d8-4935-a6be-1ba2e634e3d2)
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Aug 26 22:04:49 jupiter64 systemd[1]: Starting Authorization Manager...
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4982] device (eth0): state change: disconnected -> prepare (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4985] device (eth0): state change: prepare -> config (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.4986] device (eth0): state change: config -> ip-config (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5012] device (eth0): state change: ip-config -> ip-check (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5017] device (eth0): state change: ip-check -> secondaries (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5019] device (eth0): state change: secondaries -> activated (reason 'none', internal state 'assume')
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5021] manager: NetworkManager state is now CONNECTED_LOCAL
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5083] manager: NetworkManager state is now CONNECTED_SITE
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5084] policy: set 'BT' (eth0) as default for IPv4 routing and DNS
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5086] dns-plugin[0x563a5852f1a0]: starting dnsmasq...
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5114] dns-mgr: Writing DNS information to /usr/bin/resolvconf
Aug 26 22:04:49 jupiter64 polkitd[574]: Started polkitd version 0.114
Aug 26 22:04:49 jupiter64 dnsmasq[578]: started, version 2.77 cachesize 400
Aug 26 22:04:49 jupiter64 dnsmasq[578]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify
Aug 26 22:04:49 jupiter64 dnsmasq[578]: DBus support enabled: connected to system bus
Aug 26 22:04:49 jupiter64 dnsmasq[578]: warning: no upstream servers configured
Aug 26 22:04:49 jupiter64 dnsmasq[578]: cleared cache
Aug 26 22:04:49 jupiter64 systemd-logind[538]: Watching system buttons on /dev/input/event1 (Logitech USB Keyboard)
Aug 26 22:04:49 jupiter64 systemd-logind[538]: Watching system buttons on /dev/input/event2 (Logitech USB Keyboard)
Aug 26 22:04:49 jupiter64 polkitd[574]: Loading rules from directory /etc/polkit-1/rules.d
Aug 26 22:04:49 jupiter64 polkitd[574]: Loading rules from directory /usr/share/polkit-1/rules.d
Aug 26 22:04:49 jupiter64 polkitd[574]: Finished loading, compiling and executing 5 rules
Aug 26 22:04:49 jupiter64 dbus[534]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Aug 26 22:04:49 jupiter64 systemd[1]: Started Authorization Manager.
Aug 26 22:04:49 jupiter64 polkitd[574]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5500] device (eth0): Activation: successful, device activated.
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5515] dnsmasq[0x563a5852f1a0]: dnsmasq appeared as :1.9
Aug 26 22:04:49 jupiter64 NetworkManager[541]: <info>  [1503781489.5516] manager: startup complete
Aug 26 22:04:49 jupiter64 dnsmasq[578]: setting upstream servers from DBus
Aug 26 22:04:49 jupiter64 dnsmasq[578]: using nameserver 192.168.1.1#53(via eth0)
Aug 26 22:04:49 jupiter64 dnsmasq[578]: cleared cache
Aug 26 22:04:49 jupiter64 kernel: ivtv0: Loaded v4l-cx2341x-enc.fw firmware (376836 bytes)
Aug 26 22:04:49 jupiter64 kernel: ivtv0: Loaded v4l-cx2341x-dec.fw firmware (262144 bytes)
Aug 26 22:04:49 jupiter64 systemd[1]: Started Light Display Manager.
Aug 26 22:04:49 jupiter64 lightdm[557]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 26 22:04:49 jupiter64 systemd[1]: Reached target Graphical Interface.
Aug 26 22:04:49 jupiter64 systemd[1]: Startup finished in 12.413s (kernel) + 1.692s (userspace) = 14.106s.
Aug 26 22:04:49 jupiter64 kernel: ivtv0: Encoder revision: 0x02060039
Aug 26 22:04:49 jupiter64 kernel: ivtv0: Decoder revision: 0x02020023
Aug 26 22:04:49 jupiter64 kernel: ivtv0: Loaded v4l-cx2341x-init.mpg firmware (155648 bytes)
Aug 26 22:04:50 jupiter64 kernel: NVRM: Your system is not currently configured to drive a VGA console
                                  on the primary VGA device. The NVIDIA Linux graphics driver
                                  requires the use of a text-mode VGA console. Use of other console
                                  drivers including, but not limited to, vesafb, may result in
                                  corruption and stability problems, and is not supported.
Aug 26 22:04:50 jupiter64 kernel: nvidia-modeset: Allocated GPU:0 (GPU-b3263996-0613-6be5-1bc4-3880073b38ea) @ PCI:0000:01:00.0
Aug 26 22:04:51 jupiter64 lightdm[557]: warning: output DVI-I-2 not found; ignoring
Aug 26 22:04:51 jupiter64 lightdm[557]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Aug 26 22:04:51 jupiter64 lightdm[647]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 26 22:04:51 jupiter64 lightdm[647]: pam_unix(lightdm-greeter:session): session opened for user lightdm by (uid=0)
Aug 26 22:04:51 jupiter64 systemd[1]: Created slice User Slice of lightdm.
Aug 26 22:04:51 jupiter64 systemd[1]: Starting User Manager for UID 620...
Aug 26 22:04:51 jupiter64 systemd-logind[538]: New session c1 of user lightdm.
Aug 26 22:04:51 jupiter64 systemd[1]: Started Session c1 of user lightdm.
Aug 26 22:04:51 jupiter64 systemd[650]: pam_unix(systemd-user:session): session opened for user lightdm by (uid=0)
Aug 26 22:04:51 jupiter64 systemd[650]: Invalid line "env=_JAVA_OPTIONS=-Dawt.useSystemAAFontSettings=on\n": Invalid argument
Aug 26 22:04:51 jupiter64 systemd[650]: Failed to parse returned data: Invalid argument
Aug 26 22:04:51 jupiter64 systemd[650]: Failed to fully start up daemon: Invalid argument
Aug 26 22:04:51 jupiter64 systemd[651]: pam_unix(systemd-user:session): session closed for user lightdm
Aug 26 22:04:51 jupiter64 systemd[1]: Failed to start User Manager for UID 620.
Aug 26 22:04:51 jupiter64 systemd[1]: user@620.service: Unit entered failed state.
Aug 26 22:04:51 jupiter64 lightdm[647]: pam_systemd(lightdm-greeter:session): Failed to create session: Start job for unit user@620.service failed with 'failed'
Aug 26 22:04:51 jupiter64 systemd[1]: user@620.service: Failed with result 'protocol'.
Aug 26 22:04:51 jupiter64 lightdm[647]: Failed to open CK session: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.ConsoleKit was not provided by any .service files
Aug 26 22:04:51 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Aug 26 22:04:51 jupiter64 dbus[534]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service not found.
Aug 26 22:04:51 jupiter64 NetworkManager[541]: <info>  [1503781491.6372] device (eth0): link connected
Aug 26 22:04:51 jupiter64 kernel: r8169 0000:0a:00.0 eth0: link up
Aug 26 22:04:51 jupiter64 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Aug 26 22:04:51 jupiter64 dbus[534]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service'
Aug 26 22:04:51 jupiter64 dbus[534]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service not found.
Aug 26 22:04:54 jupiter64 ntpd[560]: Listen normally on 4 eth0 192.168.1.75:123
Aug 26 22:04:54 jupiter64 ntpd[560]: Listen normally on 5 eth0 [fe80::8e89:a5ff:fe57:81d0%2]:123
Aug 26 22:04:54 jupiter64 ntpd[560]: new interface(s) found: waking up resolver
Aug 26 22:05:01 jupiter64 systemd[1]: Started Getty on tty2.
Aug 26 22:05:02 jupiter64 NetworkManager[541]: <info>  [1503781502.0721] manager: NetworkManager state is now CONNECTED_GLOBAL
Aug 26 22:05:07 jupiter64 login[672]: pam_unix(login:session): session opened for user john by LOGIN(uid=0)
Aug 26 22:05:07 jupiter64 systemd[1]: Created slice User Slice of john.
Aug 26 22:05:07 jupiter64 systemd[1]: Starting User Manager for UID 1000...
Aug 26 22:05:07 jupiter64 systemd-logind[538]: New session c2 of user john.
Aug 26 22:05:07 jupiter64 systemd[673]: pam_unix(systemd-user:session): session opened for user john by (uid=0)
Aug 26 22:05:07 jupiter64 systemd[1]: Started Session c2 of user john.
Aug 26 22:05:07 jupiter64 systemd[673]: Invalid line "env=_JAVA_OPTIONS=-Dawt.useSystemAAFontSettings=on\n": Invalid argument
Aug 26 22:05:07 jupiter64 systemd[673]: Failed to parse returned data: Invalid argument
Aug 26 22:05:07 jupiter64 login[672]: pam_systemd(login:session): Failed to create session: Start job for unit user@1000.service failed with 'failed'
Aug 26 22:05:07 jupiter64 systemd[673]: Failed to fully start up daemon: Invalid argument
Aug 26 22:05:08 jupiter64 login[672]: LOGIN ON tty2 BY john
Aug 26 22:05:07 jupiter64 systemd[1]: Failed to start User Manager for UID 1000.
Aug 26 22:05:07 jupiter64 systemd[1]: user@1000.service: Unit entered failed state.
Aug 26 22:05:07 jupiter64 systemd[1]: user@1000.service: Failed with result 'protocol'.
Aug 26 22:05:47 jupiter64 sudo[702]:     john : TTY=tty2 ; PWD=/home/john ; USER=root ; COMMAND=/usr/bin/cat /var/log/lightdm/lightdm.log
Aug 26 22:05:47 jupiter64 sudo[702]: pam_unix(sudo:session): session opened for user root by john(uid=0)
Aug 26 22:05:47 jupiter64 sudo[702]: pam_unix(sudo:session): session closed for user root
Aug 26 22:06:18 jupiter64 lightdm[669]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Aug 26 22:06:18 jupiter64 lightdm[647]: pam_unix(lightdm-greeter:session): session closed for user lightdm
Aug 26 22:06:18 jupiter64 lightdm[557]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Aug 26 22:06:18 jupiter64 lightdm[669]: pam_tally(lightdm:setcred): Tally underflowed for user john
Aug 26 22:06:18 jupiter64 lightdm[669]: pam_unix(lightdm:session): session opened for user john by (uid=0)
Aug 26 22:06:18 jupiter64 systemd-logind[538]: Removed session c1.
Aug 26 22:06:18 jupiter64 systemd[1]: Removed slice User Slice of lightdm.
Aug 26 22:06:18 jupiter64 systemd-logind[538]: New session c3 of user john.
Aug 26 22:06:18 jupiter64 systemd[1]: Started Session c3 of user john.
Aug 26 22:06:19 jupiter64 mate-session[712]: WARNING: Could not make bus activated clients aware of DISPLAY=:0 environment variable: Could not connect: Connection refused
Aug 26 22:06:19 jupiter64 mate-session[712]: WARNING: Could not make bus activated clients aware of MATE_DESKTOP_SESSION_ID=this-is-deprecated environment variable: Could not connect: Connection refused
Aug 26 22:06:19 jupiter64 mate-session[712]: dconf-WARNING: failed to commit changes to dconf: Could not connect: Connection refused
Aug 26 22:06:19 jupiter64 mate-session[712]: WARNING: Could not make bus activated clients aware of SESSION_MANAGER=local/jupiter64:@/tmp/.ICE-unix/712,unix/jupiter64:/tmp/.ICE-unix/712 environment variable: Could not connect: Connection refused
Aug 26 22:06:27 jupiter64 sudo[724]:     john : TTY=tty2 ; PWD=/home/john ; USER=root ; COMMAND=/usr/bin/cat /var/log/lightdm/lightdm.log
Aug 26 22:06:27 jupiter64 sudo[724]: pam_unix(sudo:session): session opened for user root by john(uid=0)
Aug 26 22:06:28 jupiter64 sudo[724]: pam_unix(sudo:session): session closed for user root
Aug 26 22:07:01 jupiter64 sudo[729]:     john : TTY=tty2 ; PWD=/home/john ; USER=root ; COMMAND=/usr/bin/cat /var/log/lightdm/lightdm.log
Aug 26 22:07:01 jupiter64 sudo[729]: pam_unix(sudo:session): session opened for user root by john(uid=0)
Aug 26 22:07:01 jupiter64 sudo[729]: pam_unix(sudo:session): session closed for user root
Aug 26 22:07:22 jupiter64 sudo[738]:     john : TTY=tty2 ; PWD=/home/john ; USER=root ; COMMAND=/usr/bin/chown john:users Desktop/lightdm.log
Aug 26 22:07:22 jupiter64 sudo[738]: pam_unix(sudo:session): session opened for user root by john(uid=0)
Aug 26 22:07:22 jupiter64 sudo[738]: pam_unix(sudo:session): session closed for user root

loqs wrote:

Please do not paraphrase like that.

It's a random filename!

loqs wrote:

How did you arrive upon system 232-8? Which other versions have you tested?

Guesswork really. Went through pacman log to see what changed around the time this issue started.

[john@jupiter64 lightdm]$ pacman-history systemd
[2015-02-17 22:31]  Update systemd  (218-1 -> 218-2)
[2015-04-19 20:28]  Update systemd  (218-2 -> 219-5)
[2015-04-24 00:03]  Update systemd  (219-5 -> 219-6)
[2015-06-30 16:38]  Update systemd  (219-6 -> 221-2)
[2015-07-23 21:58]  Update systemd  (221-2 -> 222-1)
[2015-08-08 20:09]  Update systemd  (222-1 -> 224-1)
[2015-09-05 22:30]  Update systemd  (224-1 -> 225-1)
[2015-09-20 23:12]  Update systemd  (225-1 -> 226-1)
[2015-10-04 00:36]  Update systemd  (226-1 -> 226-3)
[2015-10-20 15:59]  Update systemd  (226-3 -> 227-1)
[2015-12-06 22:11]  Update systemd  (227-1 -> 228-3)
[2016-02-19 23:02]  Update systemd  (228-3 -> 229-2)
[2016-03-10 15:50]  Update systemd  (229-2 -> 229-3)
[2016-06-04 23:37]  Update systemd  (229-3 -> 230-3)
[2016-06-13 00:14]  Update systemd  (230-3 -> 230-4)
[2016-06-29 13:15]  Update systemd  (230-4 -> 230-5)
[2016-07-21 17:55]  Update systemd  (230-5 -> 230-7)
[2016-08-01 22:40]  Update systemd  (230-7 -> 231-1)
[2016-10-07 21:55]  Update systemd  (231-1 -> 231-3)
[2016-10-20 23:17]  Update systemd  (231-3 -> 231-4)
[2016-12-08 01:27]  Update systemd  (231-4 -> 232-4)
[2016-12-22 20:22]  Update systemd  (232-4 -> 232-6)
[2017-01-12 23:04]  Update systemd  (232-6 -> 232-7)
[2017-01-31 23:44]  Update systemd  (232-7 -> 232-8)
[2017-07-10 23:22]  Update systemd  (232-8 -> 233.75-3)
[2017-07-11 20:33]  downgraded systemd (233.75-3 -> 232-8)
[2017-08-10 00:54]  Update systemd  (232-8 -> 234.11-6)
[2017-08-09 02:01]  downgraded systemd (234.11-6 -> 232-8)
[2017-08-13 00:52]  Update systemd  (232-8 -> 234.11-6)
[2017-08-13 00:57]  downgraded systemd (234.11-6 -> 232-8)
[2017-08-14 21:22]  Update systemd  (232-8 -> 234.11-6)
[2017-08-14 22:03]  downgraded systemd (234.11-6 -> 232-8)
[2017-08-14 22:14]  Update systemd  (232-8 -> 234.11-6)
[2017-08-14 22:24]  downgraded systemd (234.11-6 -> 232-8)
[2017-08-26 17:41]  Update systemd  (232-8 -> 234.11-8)
[2017-08-26 17:48]  downgraded systemd (234.11-8 -> 232-8)
[2017-08-26 22:03]  Update systemd  (232-8 -> 234.11-8)
[2017-08-26 22:10]  downgraded systemd (234.11-8 -> 232-8)

Also the output of

$ ls /etc/X11/xinit/xinitrc.d/

[john@jupiter64 lightdm]$ ls /etc/X11/xinit/xinitrc.d/
40-libcanberra-gtk-module.sh  50-systemd-user.sh

[john@jupiter64 lightdm]$ cat /etc/X11/xinit/xinitrc.d/50-systemd-user.sh 

#!/bin/sh

systemctl --user import-environment DISPLAY XAUTHORITY

if which dbus-update-activation-environment >/dev/null 2>&1; then
        dbus-update-activation-environment DISPLAY XAUTHORITY
fi
[john@jupiter64 lightdm]$

loqs · 2017-08-26 22:12:06

Aug 26 22:05:07 jupiter64 systemd[1]: Started Session c2 of user john.
Aug 26 22:05:07 jupiter64 systemd[673]: Invalid line "env=_JAVA_OPTIONS=-Dawt.useSystemAAFontSettings=on\n": Invalid argument
Aug 26 22:05:07 jupiter64 systemd[673]: Failed to parse returned data: Invalid argument
Aug 26 22:05:07 jupiter64 login[672]: pam_systemd(login:session): Failed to create session: Start job for unit user@1000.service failed with 'failed'
Aug 26 22:05:07 jupiter64 systemd[673]: Failed to fully start up daemon: Invalid argument
Aug 26 22:05:08 jupiter64 login[672]: LOGIN ON tty2 BY john
Aug 26 22:05:07 jupiter64 systemd[1]: Failed to start User Manager for UID 1000.
Aug 26 22:05:07 jupiter64 systemd[1]: user@1000.service: Unit entered failed state.
Aug 26 22:05:07 jupiter64 systemd[1]: user@1000.service: Failed with result 'protocol'.

That would seem a possible candidate if the systemd can not create the user session it would make sense there would be issues connecting to that session bus.
From the first post

$ printenv DBUS_SESSION_BUS_ADDRESS
unix:path=/run/user/1000/bus

Was that with or without systemd downgraded?

PTBM133A4X · 2017-08-26 22:15:59

loqs wrote:

$ printenv DBUS_SESSION_BUS_ADDRESS
unix:path=/run/user/1000/bus
Was that with or without systemd downgraded?

~~That was with latest systemd.~~

Last edited by PTBM133A4X (2017-08-26 22:27:40)

PTBM133A4X · 2017-08-26 22:26:05

Correction: I just upgraded to latest systemd and tried this command from tty2 after the login failed. The result was blank (nothing).

Last edited by PTBM133A4X (2017-08-26 22:26:35)

seth · 2017-08-27 14:38:06

https://bbs.archlinux.org/viewtopic.php?id=227895&p=2 ?

PTBM133A4X · 2017-08-27 14:58:04

Thank you seth !!

That single quote character in /etc/environment was the problem. I've no idea how that would prevent a systemd update working as it works just fine with 232-8. Anyway - it's fixed

Now for the weird thing about this... I was doing some reading last night regarding:

Aug 26 22:05:07 jupiter64 systemd[673]: Invalid line "env=_JAVA_OPTIONS=-Dawt.useSystemAAFontSettings=on\n": Invalid argument

and I noticed that I only had one quote mark. After reading the Arch wiki here I removed the single quote but didn't bother trying to upgrade systemd and reboot. I didn't think that was the actual problem but would just get rid of the error in the journal.

So should I have a quote at the start and end as in the wiki or leave it as is?

Thanks again for pointing me to the solution and thanks also to loqs for taking time to help me out.

seth · 2017-08-27 15:40:07

You should put it in single quotes; we don't so far know what causes the malformed line - it might re-break things it deems in need of adjustment.
I'm not sure whether the quotes are actually required here or might ultimately break things (if something reads them as part of the variable rather than shell escapes...)

The reason that the systemd update broke things is that systemd gained a new feature to imitate pam_env - and that has (had?) a bug for dead sure.

PTBM133A4X · 2017-08-27 15:43:37

Thanks very much seth. Appreciate the help.

Arch Linux

#1 2017-08-26 20:16:45

[SOLVED] Could not acquire name on session bus / Failed to connect to.

#2 2017-08-26 20:56:11

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#3 2017-08-26 21:46:27

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#4 2017-08-26 22:12:06

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#5 2017-08-26 22:15:59

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#6 2017-08-26 22:26:05

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#7 2017-08-27 14:38:06

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#8 2017-08-27 14:58:04

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#9 2017-08-27 15:40:07

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

#10 2017-08-27 15:43:37

Re: [SOLVED] Could not acquire name on session bus / Failed to connect to.

Board footer