· 6 years ago · Mar 01, 2020, 12:46 PM
1Mar 1 13:30:02 raspberrypi systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
2Mar 1 13:30:02 raspberrypi systemd[1]: Starting rng-tools.service...
3Mar 1 13:30:02 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
4Mar 1 13:30:02 raspberrypi systemd[1]: Starting LSB: Monitoring GPIO shutdown trigger...
5Mar 1 13:30:02 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
6Mar 1 13:30:02 raspberrypi systemd[1]: Starting Phoniebox Startup Sound...
7Mar 1 13:30:02 raspberrypi alsactl[339]: alsactl 1.1.8 daemon started
8Mar 1 13:30:02 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
9Mar 1 13:30:02 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
10Mar 1 13:30:02 raspberrypi systemd[1]: Starting Disk Manager...
11Mar 1 13:30:02 raspberrypi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
12Mar 1 13:30:02 raspberrypi rng-tools[335]: Starting Hardware RNG entropy gatherer daemon: rngd.
13Mar 1 13:30:02 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
14Mar 1 13:30:02 raspberrypi rngd[350]: rngd 2-unofficial-mt.14 starting up...
15Mar 1 13:30:02 raspberrypi thd[334]: Found socket passed from systemd
16Mar 1 13:30:02 raspberrypi systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
17Mar 1 13:30:02 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
18Mar 1 13:30:02 raspberrypi systemd[1]: Starting Check for v3d driver...
19Mar 1 13:30:02 raspberrypi systemd[1]: Started Regular background program processing daemon.
20Mar 1 13:30:02 raspberrypi rngd[350]: entropy feed to the kernel ready
21Mar 1 13:30:02 raspberrypi systemd[1]: Starting System Logging Service...
22Mar 1 13:30:02 raspberrypi systemd[1]: Starting Login Service...
23Mar 1 13:30:02 raspberrypi systemd[1]: Reached target System Time Synchronized.
24Mar 1 13:30:02 raspberrypi cron[360]: (CRON) INFO (pidfile fd = 3)
25Mar 1 13:30:02 raspberrypi systemd[1]: Started Daily man-db regeneration.
26Mar 1 13:30:02 raspberrypi systemd[1]: Started Clean PHP session files every 30 mins.
27Mar 1 13:30:02 raspberrypi systemd[1]: Started Daily apt download activities.
28Mar 1 13:30:02 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
29Mar 1 13:30:02 raspberrypi systemd[1]: Started Daily rotation of log files.
30Mar 1 13:30:02 raspberrypi systemd[1]: Reached target Timers.
31Mar 1 13:30:02 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
32Mar 1 13:30:02 raspberrypi systemd[1]: Started Check for Raspberry Pi EEPROM updates.
33Mar 1 13:30:02 raspberrypi systemd[1]: Started Deferred execution scheduler.
34Mar 1 13:30:02 raspberrypi systemd[1]: Started rng-tools.service.
35Mar 1 13:30:02 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
36Mar 1 13:30:02 raspberrypi cron[360]: (CRON) INFO (Running @reboot jobs)
37Mar 1 13:30:02 raspberrypi avahi-daemon[337]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
38Mar 1 13:30:02 raspberrypi systemd[1]: Started Check for v3d driver.
39Mar 1 13:30:02 raspberrypi avahi-daemon[337]: Successfully dropped root privileges.
40Mar 1 13:30:02 raspberrypi avahi-daemon[337]: avahi-daemon 0.7 starting up.
41Mar 1 13:30:02 raspberrypi mpg123[341]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
42Mar 1 13:30:02 raspberrypi mpg123[341]: #011version 1.25.10; written and copyright by Michael Hipp and others
43Mar 1 13:30:02 raspberrypi mpg123[341]: #011free software (LGPL) without any warranty but with best wishes
44Mar 1 13:30:02 raspberrypi systemd[1]: Reached target Sound Card.
45Mar 1 13:30:02 raspberrypi dhcpcd[349]: dev: loaded udev
46Mar 1 13:30:02 raspberrypi cleanshutd[338]: Starting...
47Mar 1 13:30:02 raspberrypi rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd. [v8.1901.0]
48Mar 1 13:30:02 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.1901.0" x-pid="365" x-info="https://www.rsyslog.com"] start
49Mar 1 13:30:02 raspberrypi kernel: [ 8.002304] 8021q: 802.1Q VLAN Support v1.8
50Mar 1 13:30:02 raspberrypi systemd[1]: Started Login Service.
51Mar 1 13:30:02 raspberrypi udisksd[346]: udisks daemon version 2.8.1 starting
52Mar 1 13:30:03 raspberrypi dhcpcd[349]: forked to background, child pid 422
53Mar 1 13:30:03 raspberrypi systemd[1]: Started System Logging Service.
54Mar 1 13:30:03 raspberrypi systemd[1]: Started LSB: Monitoring GPIO shutdown trigger.
55Mar 1 13:30:03 raspberrypi avahi-daemon[337]: Successfully called chroot().
56Mar 1 13:30:03 raspberrypi avahi-daemon[337]: Successfully dropped remaining capabilities.
57Mar 1 13:30:03 raspberrypi avahi-daemon[337]: No service file found in /etc/avahi/services.
58Mar 1 13:30:03 raspberrypi dphys-swapfile[358]: want /var/swap=100MByte, checking existing: keeping it
59Mar 1 13:30:03 raspberrypi avahi-daemon[337]: Network interface enumeration completed.
60Mar 1 13:30:03 raspberrypi avahi-daemon[337]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 943926480.
61Mar 1 13:30:03 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
62Mar 1 13:30:03 raspberrypi wpa_supplicant[332]: Successfully initialized wpa_supplicant
63Mar 1 13:30:03 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
64Mar 1 13:30:03 raspberrypi systemd[1]: Started WPA supplicant.
65Mar 1 13:30:03 raspberrypi systemd[1]: Reached target Network.
66Mar 1 13:30:03 raspberrypi systemd[1]: Reached target Network is Online.
67Mar 1 13:30:03 raspberrypi systemd[1]: Starting Lighttpd Daemon...
68Mar 1 13:30:03 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
69Mar 1 13:30:03 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
70Mar 1 13:30:03 raspberrypi systemd[1]: Started Phoniebox GPIO Buttons Service.
71Mar 1 13:30:03 raspberrypi mpg123[341]: Directory: /home/pi/RPi-Jukebox-RFID/shared/
72Mar 1 13:30:03 raspberrypi mpg123[341]: Playing MPEG stream 1 of 1: startupsound.mp3 ...
73Mar 1 13:30:03 raspberrypi mpg123[341]: MPEG 1.0 L III vbr 44100 j-s
74Mar 1 13:30:03 raspberrypi systemd[1]: Started Phoniebox Idle Watchdog Service.
75Mar 1 13:30:03 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
76Mar 1 13:30:03 raspberrypi systemd[1]: Starting Samba NMB Daemon...
77Mar 1 13:30:03 raspberrypi systemd[1]: Starting Permit User Sessions...
78Mar 1 13:30:03 raspberrypi systemd[1]: Starting Mopidy music server...
79Mar 1 13:30:03 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
80Mar 1 13:30:03 raspberrypi systemd[1]: Starting The PHP 7.3 FastCGI Process Manager...
81Mar 1 13:30:03 raspberrypi kernel: [ 8.260693] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
82Mar 1 13:30:03 raspberrypi systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
83Mar 1 13:30:03 raspberrypi udisksd[346]: failed to load module crypto: libbd_crypto.so.2: cannot open shared object file: No such file or directory
84Mar 1 13:30:03 raspberrypi udisksd[346]: failed to load module mdraid: libbd_mdraid.so.2: cannot open shared object file: No such file or directory
85Mar 1 13:30:03 raspberrypi systemd[1]: Started Permit User Sessions.
86Mar 1 13:30:03 raspberrypi systemd[1]: Started Mopidy music server.
87Mar 1 13:30:03 raspberrypi systemd[1]: Starting Light Display Manager...
88Mar 1 13:30:03 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
89Mar 1 13:30:03 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
90Mar 1 13:30:03 raspberrypi dhcpcd-run-hooks[461]: wlan0: starting wpa_supplicant
91Mar 1 13:30:03 raspberrypi udisksd[346]: Failed to load the 'mdraid' libblockdev plugin
92Mar 1 13:30:03 raspberrypi udisksd[346]: Failed to load the 'crypto' libblockdev plugin
93Mar 1 13:30:03 raspberrypi dbus-daemon[327]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=346 comm="/usr/lib/udisks2/udisksd ")
94Mar 1 13:30:03 raspberrypi kernel: [ 8.730069] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
95Mar 1 13:30:03 raspberrypi kernel: [ 8.730267] brcmfmac: power management disabled
96Mar 1 13:30:03 raspberrypi systemd[1]: Starting Authorization Manager...
97Mar 1 13:30:03 raspberrypi raspi-config[331]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
98Mar 1 13:30:03 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
99Mar 1 13:30:04 raspberrypi lightdm[465]: 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
100Mar 1 13:30:04 raspberrypi polkitd[467]: started daemon version 0.105 using authority implementation `local' version `0.105'
101Mar 1 13:30:04 raspberrypi dbus-daemon[327]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
102Mar 1 13:30:04 raspberrypi systemd[1]: Started Authorization Manager.
103Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: connected to Access Point `'
104Mar 1 13:30:04 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
105Mar 1 13:30:04 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 173 (plymouthd).
106Mar 1 13:30:04 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 173 (plymouthd).
107Mar 1 13:30:04 raspberrypi systemd[1]: plymouth-quit-wait.service: Succeeded.
108Mar 1 13:30:04 raspberrypi systemd[1]: Started Hold until boot process finishes up.
109Mar 1 13:30:04 raspberrypi dhcpcd[422]: eth0: waiting for carrier
110Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: waiting for carrier
111Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: carrier acquired
112Mar 1 13:30:04 raspberrypi kernel: [ 9.550510] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
113Mar 1 13:30:04 raspberrypi kernel: [ 9.550882] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
114Mar 1 13:30:04 raspberrypi systemd[1]: Started Getty on tty1.
115Mar 1 13:30:04 raspberrypi systemd[1]: Reached target Login Prompts.
116Mar 1 13:30:04 raspberrypi dhcpcd[422]: DUID 00:01:00:01:25:1e:c3:4f:b8:27:eb:de:43:ef
117Mar 1 13:30:04 raspberrypi systemd[1]: plymouth-start.service: Succeeded.
118Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: IAID eb:de:43:ef
119Mar 1 13:30:04 raspberrypi systemd[1]: Started Light Display Manager.
120Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: adding address fe80::319e:d50c:2bb3:9c0a
121Mar 1 13:30:04 raspberrypi avahi-daemon[337]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::319e:d50c:2bb3:9c0a.
122Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: carrier lost
123Mar 1 13:30:04 raspberrypi avahi-daemon[337]: New relevant interface wlan0.IPv6 for mDNS.
124Mar 1 13:30:04 raspberrypi avahi-daemon[337]: Registering new address record for fe80::319e:d50c:2bb3:9c0a on wlan0.*.
125Mar 1 13:30:04 raspberrypi dhcpcd[422]: wlan0: deleting address fe80::319e:d50c:2bb3:9c0a
126Mar 1 13:30:04 raspberrypi avahi-daemon[337]: Withdrawing address record for fe80::319e:d50c:2bb3:9c0a on wlan0.
127Mar 1 13:30:04 raspberrypi avahi-daemon[337]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::319e:d50c:2bb3:9c0a.
128Mar 1 13:30:04 raspberrypi avahi-daemon[337]: Interface wlan0.IPv6 no longer relevant for mDNS.
129Mar 1 13:30:04 raspberrypi systemd[1]: Started Disk Manager.
130Mar 1 13:30:04 raspberrypi udisksd[346]: Acquired the name org.freedesktop.UDisks2 on the system message bus
131Mar 1 13:30:05 raspberrypi systemd[1]: Started The PHP 7.3 FastCGI Process Manager.
132Mar 1 13:30:05 raspberrypi mpg123[341]: [0:02] Decoding of startupsound.mp3 finished.
133Mar 1 13:30:05 raspberrypi systemd[1]: Started Lighttpd Daemon.
134Mar 1 13:30:05 raspberrypi systemd[1]: Started Phoniebox Startup Sound.
135Mar 1 13:30:05 raspberrypi kernel: [ 10.725409] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
136Mar 1 13:30:05 raspberrypi dhcpcd[422]: wlan0: carrier acquired
137Mar 1 13:30:05 raspberrypi dhcpcd[422]: wlan0: connected to Access Point `UPC9326400'
138Mar 1 13:30:05 raspberrypi dhcpcd[422]: wlan0: IAID eb:de:43:ef
139Mar 1 13:30:05 raspberrypi dhcpcd[422]: wlan0: adding address fe80::4aec:f558:8b37:4e36
140Mar 1 13:30:05 raspberrypi dhcpcd[422]: wlan0: probing address 192.168.0.34/24
141Mar 1 13:30:05 raspberrypi avahi-daemon[337]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::4aec:f558:8b37:4e36.
142Mar 1 13:30:05 raspberrypi avahi-daemon[337]: New relevant interface wlan0.IPv6 for mDNS.
143Mar 1 13:30:05 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
144Mar 1 13:30:05 raspberrypi avahi-daemon[337]: Registering new address record for fe80::4aec:f558:8b37:4e36 on wlan0.*.
145Mar 1 13:30:05 raspberrypi systemd-udevd[172]: Process '/sbin/crda' failed with exit code 255.
146Mar 1 13:30:06 raspberrypi python3[432]: Please run RegisterDevice.py first
147Mar 1 13:30:06 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE
148Mar 1 13:30:06 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
149Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: soliciting an IPv6 router
150Mar 1 13:30:06 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart.
151Mar 1 13:30:06 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 1.
152Mar 1 13:30:06 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service.
153Mar 1 13:30:06 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
154Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: Router Advertisement from fe80::3a43:7dff:fec0:8b69
155Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: adding address 2a02:8070:24bc:c500:dac3:209:2d11:95dc/64
156Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: adding route to 2a02:8070:24bc:c500::/64
157Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: requesting DHCPv6 information
158Mar 1 13:30:06 raspberrypi avahi-daemon[337]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::4aec:f558:8b37:4e36.
159Mar 1 13:30:06 raspberrypi avahi-daemon[337]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2a02:8070:24bc:c500:dac3:209:2d11:95dc.
160Mar 1 13:30:06 raspberrypi kernel: [ 11.939429] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
161Mar 1 13:30:06 raspberrypi avahi-daemon[337]: Registering new address record for 2a02:8070:24bc:c500:dac3:209:2d11:95dc on wlan0.*.
162Mar 1 13:30:06 raspberrypi avahi-daemon[337]: Withdrawing address record for fe80::4aec:f558:8b37:4e36 on wlan0.
163Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: fe80::3a43:7dff:fec0:8b69 is reachable again
164Mar 1 13:30:06 raspberrypi dhcpcd[422]: wlan0: adding default route via fe80::3a43:7dff:fec0:8b69
165Mar 1 13:30:07 raspberrypi python3[579]: Please run RegisterDevice.py first
166Mar 1 13:30:07 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE
167Mar 1 13:30:07 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
168Mar 1 13:30:07 raspberrypi kernel: [ 12.743453] Bluetooth: Core ver 2.22
169Mar 1 13:30:07 raspberrypi kernel: [ 12.743536] NET: Registered protocol family 31
170Mar 1 13:30:07 raspberrypi kernel: [ 12.743543] Bluetooth: HCI device and connection manager initialized
171Mar 1 13:30:07 raspberrypi kernel: [ 12.743565] Bluetooth: HCI socket layer initialized
172Mar 1 13:30:07 raspberrypi kernel: [ 12.743578] Bluetooth: L2CAP socket layer initialized
173Mar 1 13:30:07 raspberrypi kernel: [ 12.743699] Bluetooth: SCO socket layer initialized
174Mar 1 13:30:07 raspberrypi kernel: [ 12.769208] Bluetooth: HCI UART driver ver 2.3
175Mar 1 13:30:07 raspberrypi kernel: [ 12.769224] Bluetooth: HCI UART protocol H4 registered
176Mar 1 13:30:07 raspberrypi kernel: [ 12.769317] Bluetooth: HCI UART protocol Three-wire (H5) registered
177Mar 1 13:30:07 raspberrypi kernel: [ 12.769553] Bluetooth: HCI UART protocol Broadcom registered
178Mar 1 13:30:07 raspberrypi btuart[345]: bcm43xx_init
179Mar 1 13:30:07 raspberrypi btuart[345]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd
180Mar 1 13:30:07 raspberrypi btuart[345]: Set BDADDR UART: b8:27:eb:21:bc:10
181Mar 1 13:30:07 raspberrypi btuart[345]: Set Controller UART speed to 921600 bit/s
182Mar 1 13:30:07 raspberrypi btuart[345]: Device setup complete
183Mar 1 13:30:07 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart.
184Mar 1 13:30:07 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 2.
185Mar 1 13:30:07 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
186Mar 1 13:30:07 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service.
187Mar 1 13:30:07 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
188Mar 1 13:30:07 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
189Mar 1 13:30:07 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
190Mar 1 13:30:07 raspberrypi systemd[1]: Starting Bluetooth service...
191Mar 1 13:30:07 raspberrypi systemd[1]: Created slice system-bthelper.slice.
192Mar 1 13:30:08 raspberrypi bluetoothd[633]: Bluetooth daemon 5.50
193Mar 1 13:30:08 raspberrypi systemd[1]: Started Bluetooth service.
194Mar 1 13:30:08 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
195Mar 1 13:30:08 raspberrypi systemd[1]: Reached target Bluetooth.
196Mar 1 13:30:08 raspberrypi bluetoothd[633]: Starting SDP server
197Mar 1 13:30:08 raspberrypi systemd[1]: Started BluezALSA proxy.
198Mar 1 13:30:08 raspberrypi kernel: [ 13.166062] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
199Mar 1 13:30:08 raspberrypi kernel: [ 13.166079] Bluetooth: BNEP filters: protocol multicast
200Mar 1 13:30:08 raspberrypi kernel: [ 13.166106] Bluetooth: BNEP socket layer initialized
201Mar 1 13:30:08 raspberrypi bluetoothd[633]: Bluetooth management interface 1.14 initialized
202Mar 1 13:30:08 raspberrypi dbus-daemon[327]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=633 comm="/usr/lib/bluetooth/bluetoothd ")
203Mar 1 13:30:08 raspberrypi systemd[1]: bthelper@hci0.service: Succeeded.
204Mar 1 13:30:08 raspberrypi bluetoothd[633]: Sap driver initialization failed.
205Mar 1 13:30:08 raspberrypi bluetoothd[633]: sap-server: Operation not permitted (1)
206Mar 1 13:30:08 raspberrypi systemd[1]: Starting Hostname Service...
207Mar 1 13:30:08 raspberrypi bluetoothd[633]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/Source/1
208Mar 1 13:30:08 raspberrypi bluetoothd[633]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/Source/2
209Mar 1 13:30:08 raspberrypi kernel: [ 13.335107] Bluetooth: RFCOMM TTY layer initialized
210Mar 1 13:30:08 raspberrypi kernel: [ 13.335141] Bluetooth: RFCOMM socket layer initialized
211Mar 1 13:30:08 raspberrypi kernel: [ 13.335189] Bluetooth: RFCOMM ver 1.11
212Mar 1 13:30:08 raspberrypi bluetoothd[633]: Failed to set privacy: Rejected (0x0b)
213Mar 1 13:30:08 raspberrypi lightdm[653]: 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
214Mar 1 13:30:08 raspberrypi python3[631]: Please run RegisterDevice.py first
215Mar 1 13:30:08 raspberrypi systemd[1]: Created slice User Slice of UID 1000.
216Mar 1 13:30:08 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000...
217Mar 1 13:30:08 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000.
218Mar 1 13:30:08 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE
219Mar 1 13:30:08 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
220Mar 1 13:30:08 raspberrypi systemd[1]: Starting User Manager for UID 1000...
221Mar 1 13:30:08 raspberrypi dbus-daemon[327]: [system] Successfully activated service 'org.freedesktop.hostname1'
222Mar 1 13:30:08 raspberrypi systemd[1]: Started Hostname Service.
223Mar 1 13:30:09 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart.
224Mar 1 13:30:09 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 3.
225Mar 1 13:30:09 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service.
226Mar 1 13:30:09 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
227Mar 1 13:30:09 raspberrypi systemd[662]: Reached target Timers.
228Mar 1 13:30:09 raspberrypi systemd[662]: Reached target Paths.
229Mar 1 13:30:09 raspberrypi systemd[662]: Listening on GnuPG cryptographic agent and passphrase cache.
230Mar 1 13:30:09 raspberrypi systemd[662]: Starting D-Bus User Message Bus Socket.
231Mar 1 13:30:09 raspberrypi systemd[662]: Listening on GnuPG network certificate management daemon.
232Mar 1 13:30:09 raspberrypi systemd[662]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
233Mar 1 13:30:09 raspberrypi systemd[662]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
234Mar 1 13:30:09 raspberrypi systemd[662]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
235Mar 1 13:30:09 raspberrypi systemd[662]: Listening on D-Bus User Message Bus Socket.
236Mar 1 13:30:09 raspberrypi systemd[662]: Reached target Sockets.
237Mar 1 13:30:09 raspberrypi systemd[662]: Reached target Basic System.
238Mar 1 13:30:09 raspberrypi systemd[1]: Started User Manager for UID 1000.
239Mar 1 13:30:09 raspberrypi systemd[1]: Started Session c1 of user pi.
240Mar 1 13:30:09 raspberrypi systemd[662]: Starting Music Player Daemon...
241Mar 1 13:30:09 raspberrypi lightdm[465]: Error opening audit socket: Protocol not supported
242Mar 1 13:30:09 raspberrypi systemd[662]: Started D-Bus User Message Bus.
243Mar 1 13:30:09 raspberrypi python3[674]: Please run RegisterDevice.py first
244Mar 1 13:30:09 raspberrypi systemd[1]: Started Session c2 of user pi.
245Mar 1 13:30:09 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE
246Mar 1 13:30:09 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
247Mar 1 13:30:10 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart.
248Mar 1 13:30:10 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 4.
249Mar 1 13:30:10 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service.
250Mar 1 13:30:10 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
251Mar 1 13:30:11 raspberrypi python3[712]: Please run RegisterDevice.py first
252Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE
253Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
254Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart.
255Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 5.
256Mar 1 13:30:11 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service.
257Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Start request repeated too quickly.
258Mar 1 13:30:11 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'.
259Mar 1 13:30:11 raspberrypi systemd[1]: Failed to start Phoniebox RFID-Reader Service.
260Mar 1 13:30:11 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=1000 pid=683 comm="/usr/bin/lxsession -s LXDE-pi -e LXDE ")
261Mar 1 13:30:11 raspberrypi systemd[662]: Starting Virtual filesystem service...
262Mar 1 13:30:11 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.Daemon'
263Mar 1 13:30:11 raspberrypi systemd[662]: Started Virtual filesystem service.
264Mar 1 13:30:11 raspberrypi kernel: [ 17.023146] fuse init (API version 7.27)
265Mar 1 13:30:12 raspberrypi systemd[1]: Mounting FUSE Control File System...
266Mar 1 13:30:12 raspberrypi systemd[1]: Mounted FUSE Control File System.
267Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.__main__ Starting Mopidy 3.0.1
268Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.config Loading config from builtin defaults
269Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy.conf
270Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy-local.conf
271Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.config Loading config from file:///etc/mopidy/mopidy.conf
272Mar 1 13:30:12 raspberrypi mopidy[456]: INFO [MainThread] mopidy.config Loading config from command line options
273Mar 1 13:30:12 raspberrypi mopidy[456]: ERROR [MainThread] mopidy.internal.log Loading logging config '/etc/mopidy/logging.conf' failed. 'formatters'
274Mar 1 13:30:12 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
275Mar 1 13:30:13 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=777 comm="pcmanfm --desktop --profile LXDE-pi ")
276Mar 1 13:30:13 raspberrypi systemd[662]: Starting Virtual filesystem service - disk device monitor...
277Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
278Mar 1 13:30:14 raspberrypi systemd[662]: Started Virtual filesystem service - disk device monitor.
279Mar 1 13:30:14 raspberrypi mpd[681]: exception: failed to open log file "/var/log/mpd/mpd.log" (config line 40): Permission denied
280Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=777 comm="pcmanfm --desktop --profile LXDE-pi ")
281Mar 1 13:30:14 raspberrypi systemd[662]: Starting Virtual filesystem service - digital camera monitor...
282Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
283Mar 1 13:30:14 raspberrypi systemd[662]: Started Virtual filesystem service - digital camera monitor.
284Mar 1 13:30:14 raspberrypi systemd[662]: mpd.service: Main process exited, code=exited, status=1/FAILURE
285Mar 1 13:30:14 raspberrypi systemd[662]: mpd.service: Failed with result 'exit-code'.
286Mar 1 13:30:14 raspberrypi systemd[662]: Failed to start Music Player Daemon.
287Mar 1 13:30:14 raspberrypi systemd[662]: Reached target Default.
288Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.7' (uid=1000 pid=777 comm="pcmanfm --desktop --profile LXDE-pi ")
289Mar 1 13:30:14 raspberrypi systemd[662]: Startup finished in 5.523s.
290Mar 1 13:30:14 raspberrypi systemd[662]: Starting Virtual filesystem service - GNOME Online Accounts monitor...
291Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
292Mar 1 13:30:14 raspberrypi systemd[662]: Started Virtual filesystem service - GNOME Online Accounts monitor.
293Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.7' (uid=1000 pid=777 comm="pcmanfm --desktop --profile LXDE-pi ")
294Mar 1 13:30:14 raspberrypi systemd[662]: Starting Virtual filesystem service - Apple File Conduit monitor...
295Mar 1 13:30:14 raspberrypi gvfs-afc-volume-monitor[845]: Volume monitor alive
296Mar 1 13:30:14 raspberrypi mopidy[456]: INFO [MainThread] mopidy.__main__ Enabled extensions: softwaremixer, spotify, local, stream, m3u, http, file, iris, mpd
297Mar 1 13:30:14 raspberrypi mopidy[456]: INFO [MainThread] mopidy.__main__ Disabled extensions: none
298Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
299Mar 1 13:30:14 raspberrypi systemd[662]: Started Virtual filesystem service - Apple File Conduit monitor.
300Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.7' (uid=1000 pid=777 comm="pcmanfm --desktop --profile LXDE-pi ")
301Mar 1 13:30:14 raspberrypi systemd[662]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
302Mar 1 13:30:14 raspberrypi dbus-daemon[692]: [session uid=1000 pid=692] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
303Mar 1 13:30:14 raspberrypi systemd[662]: Started Virtual filesystem service - Media Transfer Protocol monitor.
304Mar 1 13:30:17 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting Mopidy mixer: SoftwareMixer
305Mar 1 13:30:17 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Mixer volume set to 30
306Mar 1 13:30:17 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting Mopidy audio
307Mar 1 13:30:17 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting Mopidy backends: FileBackend, M3UBackend, StreamBackend, SpotifyBackend, LocalBackend
308Mar 1 13:30:17 raspberrypi mopidy[456]: INFO [Audio-2] mopidy.audio.actor Audio output set to "alsasink"
309Mar 1 13:30:19 raspberrypi dhcpcd[422]: wlan0: fe80::3a43:7dff:fec0:8b69 is reachable again
310Mar 1 13:30:19 raspberrypi dhcpcd[422]: wlan0: fe80::3a43:7dff:fec0:8b69 is reachable again
311Mar 1 13:30:21 raspberrypi dhcpcd[422]: wlan0: using static address 192.168.0.34/24
312Mar 1 13:30:21 raspberrypi avahi-daemon[337]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.34.
313Mar 1 13:30:21 raspberrypi dhcpcd[422]: wlan0: adding route to 192.168.0.0/24
314Mar 1 13:30:21 raspberrypi dhcpcd[422]: wlan0: adding default route via 192.168.0.1
315Mar 1 13:30:21 raspberrypi avahi-daemon[337]: New relevant interface wlan0.IPv4 for mDNS.
316Mar 1 13:30:21 raspberrypi avahi-daemon[337]: Registering new address record for 192.168.0.34 on wlan0.IPv4.
317Mar 1 13:30:23 raspberrypi lighttpd[518]: echo: write error: Broken pipe
318Mar 1 13:30:24 raspberrypi mopidy[456]: INFO [SpotifyEventLoop] mopidy_spotify.backend Logged in to Spotify in online mode
319Mar 1 13:30:26 raspberrypi systemd[1]: Started Samba NMB Daemon.
320Mar 1 13:30:26 raspberrypi systemd[1]: Starting Samba SMB Daemon...
321Mar 1 13:30:26 raspberrypi systemd[1]: Started Samba SMB Daemon.
322Mar 1 13:30:26 raspberrypi systemd[1]: Reached target Multi-User System.
323Mar 1 13:30:26 raspberrypi systemd[1]: Reached target Graphical Interface.
324Mar 1 13:30:26 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
325Mar 1 13:30:26 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
326Mar 1 13:30:27 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
327Mar 1 13:30:27 raspberrypi systemd[1]: Startup finished in 1.547s (kernel) + 30.478s (userspace) = 32.026s.
328Mar 1 13:30:28 raspberrypi lighttpd[518]: echo: write error: Broken pipe
329Mar 1 13:30:28 raspberrypi mopidy[456]: INFO [SpotifyBackend-6] mopidy_spotify.web Logged into Spotify Web API as joseiger
330Mar 1 13:30:31 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded.
331Mar 1 13:30:34 raspberrypi lighttpd[518]: echo: write error: Broken pipe
332Mar 1 13:30:45 raspberrypi systemd-timesyncd[278]: Synchronized to time server for the first time [2a01:4f8:0:a101::2:3]:123 (2.debian.pool.ntp.org).
333Mar 1 13:30:47 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded.
334Mar 1 13:30:48 raspberrypi lighttpd[518]: echo: write error: Broken pipe
335Mar 1 13:30:54 raspberrypi lighttpd[518]: echo: write error: Broken pipe
336Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [SpotifyBackend-6] mopidy_spotify.playlists Refreshed 42 Spotify playlists
337Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting Mopidy core
338Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting Mopidy frontends: IrisFrontend, HttpFrontend, MpdFrontend
339Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [IrisFrontend-11] mopidy_iris.core Starting Iris 3.45.1
340Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [HttpFrontend-13] mopidy.http.actor HTTP server running at [::ffff:0.0.0.0]:6680
341Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [MainThread] mopidy_mpd.actor MPD server running at [::ffff:0.0.0.0]:6600
342Mar 1 13:30:56 raspberrypi mopidy[456]: INFO [MainThread] mopidy.commands Starting GLib mainloop
343Mar 1 13:31:00 raspberrypi mopidy[456]: INFO [MpdSession-15] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34710
344Mar 1 13:31:01 raspberrypi mopidy[456]: INFO [MpdSession-16] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34714
345Mar 1 13:31:06 raspberrypi mopidy[456]: INFO [MpdSession-17] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34718
346Mar 1 13:31:07 raspberrypi mopidy[456]: INFO [MpdSession-18] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34722
347Mar 1 13:31:07 raspberrypi mopidy[456]: INFO [MpdSession-19] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34726
348Mar 1 13:31:12 raspberrypi systemd[1]: phoniebox-idle-watchdog.service: Succeeded.
349Mar 1 13:31:13 raspberrypi mopidy[456]: INFO [MpdSession-20] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34730
350Mar 1 13:31:17 raspberrypi mopidy[456]: INFO [MpdSession-21] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34738
351Mar 1 13:31:17 raspberrypi mopidy[456]: INFO [MpdSession-22] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34742
352Mar 1 13:31:17 raspberrypi mopidy[456]: INFO [MpdSession-23] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34746
353Mar 1 13:31:17 raspberrypi mopidy[456]: INFO [MpdSession-24] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34750
354Mar 1 13:31:18 raspberrypi mopidy[456]: INFO [MpdSession-25] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34754
355Mar 1 13:31:18 raspberrypi mopidy[456]: INFO [MpdSession-26] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34758
356Mar 1 13:31:18 raspberrypi mopidy[456]: INFO [MpdSession-27] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34762
357Mar 1 13:31:18 raspberrypi mopidy[456]: INFO [MpdSession-28] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34766
358Mar 1 13:31:19 raspberrypi mopidy[456]: INFO [MpdSession-29] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34770
359Mar 1 13:31:20 raspberrypi mopidy[456]: INFO [MpdSession-31] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34774
360Mar 1 13:31:22 raspberrypi mopidy[456]: INFO [MpdSession-32] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34778
361Mar 1 13:31:24 raspberrypi mopidy[456]: INFO [MpdSession-33] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34782
362Mar 1 13:31:28 raspberrypi mopidy[456]: INFO [MpdSession-34] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34788
363Mar 1 13:31:28 raspberrypi mopidy[456]: INFO [MpdSession-35] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34792
364Mar 1 13:31:28 raspberrypi mopidy[456]: INFO [MpdSession-36] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34796
365Mar 1 13:31:29 raspberrypi mopidy[456]: INFO [MpdSession-38] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34800
366Mar 1 13:31:29 raspberrypi mopidy[456]: INFO [MpdSession-39] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34804
367Mar 1 13:31:29 raspberrypi mopidy[456]: INFO [MpdSession-40] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34808
368Mar 1 13:31:29 raspberrypi mopidy[456]: INFO [MpdSession-41] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34812
369Mar 1 13:31:29 raspberrypi mopidy[456]: INFO [MpdSession-42] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34816
370Mar 1 13:31:31 raspberrypi mopidy[456]: INFO [MpdSession-45] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34820
371Mar 1 13:31:34 raspberrypi mopidy[456]: INFO [MpdSession-46] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34824
372Mar 1 13:31:40 raspberrypi mopidy[456]: INFO [MpdSession-47] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34828
373Mar 1 13:31:43 raspberrypi mopidy[456]: INFO [MpdSession-48] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34832
374Mar 1 13:31:43 raspberrypi mopidy[456]: INFO [MpdSession-49] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34836
375Mar 1 13:31:43 raspberrypi mopidy[456]: INFO [MpdSession-50] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34840
376Mar 1 13:31:43 raspberrypi mopidy[456]: INFO [MpdSession-51] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34844
377Mar 1 13:31:44 raspberrypi mopidy[456]: INFO [MpdSession-52] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34848
378Mar 1 13:31:44 raspberrypi mopidy[456]: INFO [MpdSession-53] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34852
379Mar 1 13:31:45 raspberrypi mopidy[456]: INFO [MpdSession-55] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34856
380Mar 1 13:31:46 raspberrypi mopidy[456]: INFO [MpdSession-56] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34860
381Mar 1 13:31:46 raspberrypi mopidy[456]: INFO [MpdSession-57] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34864
382Mar 1 13:31:47 raspberrypi mopidy[456]: INFO [MpdSession-58] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34868
383Mar 1 13:31:51 raspberrypi mopidy[456]: INFO [MpdSession-59] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34872
384Mar 1 13:31:57 raspberrypi mopidy[456]: INFO [MpdSession-60] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34876
385Mar 1 13:32:04 raspberrypi mopidy[456]: INFO [MpdSession-61] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34880
386Mar 1 13:32:09 raspberrypi mopidy[456]: INFO [MpdSession-63] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34884
387Mar 1 13:32:10 raspberrypi mopidy[456]: INFO [MpdSession-64] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34888
388Mar 1 13:32:13 raspberrypi dhcpcd[422]: wlan0: Router Advertisement from fe80::3a43:7dff:fec0:8b69
389Mar 1 13:32:16 raspberrypi mopidy[456]: INFO [MpdSession-65] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34892
390Mar 1 13:32:22 raspberrypi mopidy[456]: INFO [MpdSession-66] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34896
391Mar 1 13:32:27 raspberrypi mopidy[456]: INFO [MpdSession-67] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34900
392Mar 1 13:32:28 raspberrypi lighttpd[518]: No LSB modules are available.
393Mar 1 13:32:28 raspberrypi lighttpd[518]: tail: cannot open '../logs/debug.log' for reading: No such file or directory
394Mar 1 13:32:52 raspberrypi mopidy[456]: INFO [MpdSession-68] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34904
395Mar 1 13:32:52 raspberrypi mopidy[456]: INFO [MpdSession-69] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34908
396Mar 1 13:32:57 raspberrypi mopidy[456]: INFO [MpdSession-70] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34912
397Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-71] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34916
398Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-72] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34920
399Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-73] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34924
400Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [SpotifyBackend-6] mopidy_spotify.lookup Failed to lookup 'spotify:show:42rL0xJ6yo8kHkvx1YHK9g': Could not parse 'spotify:show:42rL0xJ6yo8kHkvx1YHK9g' as a Spotify URI
401Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-74] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34928
402Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-75] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34932
403Mar 1 13:33:02 raspberrypi mopidy[456]: INFO [MpdSession-76] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34936
404Mar 1 13:33:03 raspberrypi mopidy[456]: INFO [MpdSession-77] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34940
405Mar 1 13:33:03 raspberrypi mopidy[456]: INFO [MpdSession-78] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34944
406Mar 1 13:33:04 raspberrypi mopidy[456]: INFO [MpdSession-79] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34948
407Mar 1 13:33:08 raspberrypi mopidy[456]: INFO [MpdSession-80] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34952
408Mar 1 13:33:14 raspberrypi mopidy[456]: INFO [MpdSession-81] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34956
409Mar 1 13:33:20 raspberrypi mopidy[456]: INFO [MpdSession-82] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34960
410Mar 1 13:33:26 raspberrypi mopidy[456]: INFO [MpdSession-83] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34964
411Mar 1 13:33:32 raspberrypi mopidy[456]: INFO [MpdSession-84] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34968
412Mar 1 13:33:38 raspberrypi mopidy[456]: INFO [MpdSession-85] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34972
413Mar 1 13:33:44 raspberrypi mopidy[456]: INFO [MpdSession-86] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34976
414Mar 1 13:33:50 raspberrypi mopidy[456]: INFO [MpdSession-87] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34980
415Mar 1 13:33:56 raspberrypi mopidy[456]: INFO [MpdSession-88] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34984
416Mar 1 13:34:02 raspberrypi mopidy[456]: INFO [MpdSession-89] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34988
417Mar 1 13:34:08 raspberrypi mopidy[456]: INFO [MpdSession-90] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34992
418Mar 1 13:34:14 raspberrypi mopidy[456]: INFO [MpdSession-91] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:34996
419Mar 1 13:34:20 raspberrypi mopidy[456]: INFO [MpdSession-92] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35000
420Mar 1 13:34:26 raspberrypi mopidy[456]: INFO [MpdSession-95] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35004
421Mar 1 13:34:26 raspberrypi mopidy[456]: INFO [MpdSession-96] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35008
422Mar 1 13:34:44 raspberrypi mopidy[456]: INFO [MpdSession-97] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35012
423Mar 1 13:34:45 raspberrypi mopidy[456]: INFO [MpdSession-98] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35016
424Mar 1 13:34:47 raspberrypi dhcpcd[422]: wlan0: Router Advertisement from fe80::3a43:7dff:fec0:8b69
425Mar 1 13:34:50 raspberrypi mopidy[456]: INFO [MpdSession-99] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35020
426Mar 1 13:34:55 raspberrypi mopidy[456]: INFO [MpdSession-100] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35024
427Mar 1 13:35:00 raspberrypi mopidy[456]: INFO [MpdSession-101] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35028
428Mar 1 13:35:06 raspberrypi mopidy[456]: INFO [MpdSession-102] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35032
429Mar 1 13:35:06 raspberrypi mopidy[456]: INFO [MpdSession-103] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35036
430Mar 1 13:35:06 raspberrypi mopidy[456]: INFO [MpdSession-104] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35040
431Mar 1 13:35:06 raspberrypi mopidy[456]: INFO [MpdSession-105] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35044
432Mar 1 13:35:11 raspberrypi mopidy[456]: INFO [MpdSession-106] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35048
433Mar 1 13:35:12 raspberrypi mopidy[456]: INFO [MpdSession-107] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35054
434Mar 1 13:35:17 raspberrypi mopidy[456]: INFO [MpdSession-108] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35060
435Mar 1 13:35:21 raspberrypi mopidy[456]: WARNING [StreamBackend-5] mopidy.internal.http Download of 'http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3' failed due to download taking more than 4.995s
436Mar 1 13:35:21 raspberrypi mopidy[456]: INFO [StreamBackend-5] mopidy.stream.actor Unwrapping stream from URI (http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3) failed: error downloading URI http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3
437Mar 1 13:35:21 raspberrypi mopidy[456]: WARNING [StreamBackend-5] mopidy.stream.actor Problem looking up http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3
438Mar 1 13:35:22 raspberrypi mopidy[456]: INFO [MpdSession-109] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35064
439Mar 1 13:35:22 raspberrypi mopidy[456]: INFO [MpdSession-110] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35068
440Mar 1 13:35:22 raspberrypi mopidy[456]: INFO [MpdSession-111] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35072
441Mar 1 13:35:23 raspberrypi mopidy[456]: INFO [MpdSession-112] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35078
442Mar 1 13:35:23 raspberrypi mopidy[456]: INFO [MpdSession-113] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35082
443Mar 1 13:35:30 raspberrypi mopidy[456]: INFO [MpdSession-114] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35088
444Mar 1 13:35:33 raspberrypi mopidy[456]: WARNING [StreamBackend-5] mopidy.internal.http Download of 'http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3' failed due to download taking more than 4.995s
445Mar 1 13:35:33 raspberrypi mopidy[456]: INFO [StreamBackend-5] mopidy.stream.actor Unwrapping stream from URI (http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3) failed: error downloading URI http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3
446Mar 1 13:35:33 raspberrypi mopidy[456]: WARNING [Core-10] mopidy.core.tracklist Track is not playable: http://br-br2-nord.cast.addradio.de/br/br2/nord/mp3/56/stream.mp3
447Mar 1 13:35:36 raspberrypi mopidy[456]: INFO [MpdSession-115] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35092
448Mar 1 13:35:36 raspberrypi mopidy[456]: INFO [MpdSession-116] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35096
449Mar 1 13:35:41 raspberrypi mopidy[456]: INFO [MpdSession-117] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35100
450Mar 1 13:35:47 raspberrypi mopidy[456]: INFO [MpdSession-118] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35104
451Mar 1 13:35:52 raspberrypi mopidy[456]: INFO [MpdSession-119] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35108
452Mar 1 13:35:57 raspberrypi mopidy[456]: INFO [MpdSession-120] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35112
453Mar 1 13:36:00 raspberrypi dhcpcd[422]: wlan0: Router Advertisement from fe80::3a43:7dff:fec0:8b69
454Mar 1 13:36:02 raspberrypi mopidy[456]: INFO [MpdSession-121] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35116
455Mar 1 13:36:07 raspberrypi mopidy[456]: INFO [MpdSession-122] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35120
456Mar 1 13:36:12 raspberrypi mopidy[456]: INFO [MpdSession-123] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35124
457Mar 1 13:36:18 raspberrypi mopidy[456]: INFO [MpdSession-124] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35128
458Mar 1 13:36:23 raspberrypi mopidy[456]: INFO [MpdSession-125] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35132
459Mar 1 13:36:27 raspberrypi mopidy[456]: INFO [MpdSession-126] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35136
460Mar 1 13:36:27 raspberrypi mopidy[456]: INFO [MpdSession-127] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35140
461Mar 1 13:36:27 raspberrypi mopidy[456]: INFO [MpdSession-128] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35144
462Mar 1 13:36:27 raspberrypi mopidy[456]: INFO [MpdSession-129] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35148
463Mar 1 13:36:28 raspberrypi mopidy[456]: INFO [MpdSession-130] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35156
464Mar 1 13:36:33 raspberrypi mopidy[456]: INFO [MpdSession-132] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35164
465Mar 1 13:36:39 raspberrypi mopidy[456]: INFO [MpdSession-136] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35168
466Mar 1 13:36:39 raspberrypi mopidy[456]: INFO [MpdSession-137] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35172
467Mar 1 13:36:42 raspberrypi mopidy[456]: INFO [MpdSession-138] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35176
468Mar 1 13:36:44 raspberrypi mopidy[456]: INFO [MpdSession-139] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35180
469Mar 1 13:36:49 raspberrypi mopidy[456]: INFO [MpdSession-140] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35184
470Mar 1 13:36:55 raspberrypi mopidy[456]: INFO [MpdSession-141] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35188
471Mar 1 13:37:01 raspberrypi mopidy[456]: INFO [MpdSession-142] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35192
472Mar 1 13:37:07 raspberrypi mopidy[456]: INFO [MpdSession-143] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35196
473Mar 1 13:37:10 raspberrypi systemd[1]: Started Session c3 of user pi.
474Mar 1 13:37:13 raspberrypi mopidy[456]: INFO [MpdSession-144] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35200
475Mar 1 13:37:19 raspberrypi mopidy[456]: INFO [MpdSession-145] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35204
476Mar 1 13:37:25 raspberrypi mopidy[456]: INFO [MpdSession-146] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35208
477Mar 1 13:37:30 raspberrypi dhcpcd[422]: wlan0: Router Advertisement from fe80::3a43:7dff:fec0:8b69
478Mar 1 13:37:31 raspberrypi mopidy[456]: INFO [MpdSession-147] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35212
479Mar 1 13:37:37 raspberrypi mopidy[456]: INFO [MpdSession-148] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35216
480Mar 1 13:37:43 raspberrypi mopidy[456]: INFO [MpdSession-149] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35220
481Mar 1 13:37:49 raspberrypi mopidy[456]: INFO [MpdSession-150] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35224
482Mar 1 13:37:55 raspberrypi mopidy[456]: INFO [MpdSession-151] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35228
483Mar 1 13:38:01 raspberrypi mopidy[456]: INFO [MpdSession-152] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35232
484Mar 1 13:38:07 raspberrypi mopidy[456]: INFO [MpdSession-153] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35236
485Mar 1 13:38:13 raspberrypi mopidy[456]: INFO [MpdSession-154] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35240
486Mar 1 13:38:19 raspberrypi mopidy[456]: INFO [MpdSession-155] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35244
487Mar 1 13:38:25 raspberrypi mopidy[456]: INFO [MpdSession-156] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35248
488Mar 1 13:38:31 raspberrypi mopidy[456]: INFO [MpdSession-157] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35252
489Mar 1 13:38:37 raspberrypi mopidy[456]: INFO [MpdSession-158] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35256
490Mar 1 13:38:43 raspberrypi mopidy[456]: INFO [MpdSession-159] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35260
491Mar 1 13:38:50 raspberrypi mopidy[456]: INFO [MpdSession-160] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35264
492Mar 1 13:38:56 raspberrypi mopidy[456]: INFO [MpdSession-161] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35268
493Mar 1 13:39:01 raspberrypi CRON[4636]: (root) CMD ( [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
494Mar 1 13:39:02 raspberrypi mopidy[456]: INFO [MpdSession-162] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35272
495Mar 1 13:39:03 raspberrypi systemd[1]: Starting Clean php session files...
496Mar 1 13:39:04 raspberrypi systemd[1]: phpsessionclean.service: Succeeded.
497Mar 1 13:39:04 raspberrypi systemd[1]: Started Clean php session files.
498Mar 1 13:39:08 raspberrypi mopidy[456]: INFO [MpdSession-163] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35276
499Mar 1 13:39:14 raspberrypi mopidy[456]: INFO [MpdSession-164] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35280
500Mar 1 13:39:20 raspberrypi mopidy[456]: INFO [MpdSession-165] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:35284