RPi-Jukebox-RFID icon indicating copy to clipboard operation
RPi-Jukebox-RFID copied to clipboard

Published 20 hours ago • verified publisher icon MiczFlor

Waveshare PN532 NFC hat using SPI

Open borsic77 opened this issue 4 years ago • 5 comments

Bug

What I did

Trying to use RPi jukebox with a Waveshare PN532 NFC hat, SPI configuration. I followed the one-script install guide, and used Reader.py.experimental. RegisterDevice.py shows MFRC522 and RDM6300.

What happened

When I select MFRC522 I can run the python sample code from Waveshare, and it detects my RFID card. https://www.waveshare.com/wiki/PN532_NFC_HAT However, in the web-interface the RFid card does not show up under the Card ID tab. I am guessing that I have to change some settings in Reader.py?

I expected this to happen

Detecting the RFid cards in the web interface.

Further information that might help

tail -n 500 /var/log/syslog May  3 16:06:13 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0. May  3 16:06:13 raspberrypi avahi-daemon[390]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::ba27:ebff:fe5f:8103. May  3 16:06:13 raspberrypi avahi-daemon[390]: Interface wlan0.IPv6 no longer relevant for mDNS. May  3 16:06:13 raspberrypi lightdm[479]: 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 May  3 16:06:13 raspberrypi polkitd[450]: started daemon version 0.105 using authority implementation local' version 0.105' May  3 16:06:13 raspberrypi dbus-daemon[362]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May  3 16:06:13 raspberrypi systemd[1]: Started Authorization Manager. May  3 16:06:13 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 174 (plymouthd). May  3 16:06:13 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 174 (plymouthd). May  3 16:06:13 raspberrypi systemd[1]: plymouth-quit-wait.service: Succeeded. May  3 16:06:13 raspberrypi systemd[1]: Started Hold until boot process finishes up. May  3 16:06:13 raspberrypi systemd[1]: Started Light Display Manager. May  3 16:06:13 raspberrypi systemd[1]: plymouth-start.service: Succeeded. May  3 16:06:13 raspberrypi systemd[1]: Started Getty on tty1. May  3 16:06:13 raspberrypi systemd[1]: Reached target Login Prompts. May  3 16:06:14 raspberrypi systemd[1]: Started The PHP 7.3 FastCGI Process Manager. May  3 16:06:14 raspberrypi raspi-config[398]: Checking if shift key is held down: No. Switching to ondemand scaling governor. May  3 16:06:14 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed). May  3 16:06:14 raspberrypi systemd[1]: Started Disk Manager. May  3 16:06:14 raspberrypi udisksd[350]: Acquired the name org.freedesktop.UDisks2 on the system message bus May  3 16:06:14 raspberrypi systemd[1]: Started Lighttpd Daemon. May  3 16:06:15 raspberrypi daemon_rfid_reader.py[456]: Please run RegisterDevice.py first May  3 16:06:15 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:15 raspberrypi exim4[454]: Starting MTA: exim4. May  3 16:06:15 raspberrypi systemd[1]: Started LSB: exim Mail Transport Agent. May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart. May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 1. May  3 16:06:15 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service. May  3 16:06:15 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service. May  3 16:06:15 raspberrypi daemon_rfid_reader.py[830]: Please run RegisterDevice.py first May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:15 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart. May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 2. May  3 16:06:16 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service. May  3 16:06:16 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service. May  3 16:06:16 raspberrypi daemon_rfid_reader.py[835]: Please run RegisterDevice.py first May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart. May  3 16:06:16 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 3. May  3 16:06:16 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service. May  3 16:06:16 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service. May  3 16:06:17 raspberrypi lightdm[853]: 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 May  3 16:06:17 raspberrypi systemd[1]: Created slice User Slice of UID 1000. May  3 16:06:17 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000... May  3 16:06:17 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000. May  3 16:06:17 raspberrypi systemd[1]: Starting User Manager for UID 1000... May  3 16:06:17 raspberrypi daemon_rfid_reader.py[847]: Please run RegisterDevice.py first May  3 16:06:17 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:17 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:17 raspberrypi systemd[861]: Starting D-Bus User Message Bus Socket. May  3 16:06:17 raspberrypi systemd[861]: Reached target Paths. May  3 16:06:17 raspberrypi systemd[861]: Reached target Timers. May  3 16:06:17 raspberrypi systemd[861]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May  3 16:06:17 raspberrypi systemd[861]: Listening on GnuPG cryptographic agent and passphrase cache. May  3 16:06:17 raspberrypi systemd[861]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May  3 16:06:17 raspberrypi systemd[861]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May  3 16:06:17 raspberrypi systemd[861]: Listening on GnuPG network certificate management daemon. May  3 16:06:17 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart. May  3 16:06:17 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 4. May  3 16:06:17 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service. May  3 16:06:17 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service. May  3 16:06:17 raspberrypi systemd[861]: Listening on D-Bus User Message Bus Socket. May  3 16:06:17 raspberrypi systemd[861]: Reached target Sockets. May  3 16:06:17 raspberrypi systemd[861]: Reached target Basic System. May  3 16:06:17 raspberrypi systemd[1]: Started User Manager for UID 1000. May  3 16:06:17 raspberrypi systemd[861]: Starting Music Player Daemon... May  3 16:06:17 raspberrypi systemd[1]: Started Session c1 of user pi. May  3 16:06:17 raspberrypi lightdm[479]: Error opening audit socket: Protocol not supported May  3 16:06:17 raspberrypi systemd[861]: Started D-Bus User Message Bus. May  3 16:06:18 raspberrypi daemon_rfid_reader.py[875]: Please run RegisterDevice.py first May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: carrier acquired May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: connected to Access Point kucknet2' May  3 16:06:18 raspberrypi dhcpcd[431]: unknown option: SSID May  3 16:06:18 raspberrypi dhcpcd[431]: unknown option: SSID May  3 16:06:18 raspberrypi kernel: [   14.281176] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: IAID eb:5f:81:03 May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: adding address fe80::ba27:ebff:fe5f:8103 May  3 16:06:18 raspberrypi avahi-daemon[390]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::ba27:ebff:fe5f:8103. May  3 16:06:18 raspberrypi avahi-daemon[390]: New relevant interface wlan0.IPv6 for mDNS. May  3 16:06:18 raspberrypi avahi-daemon[390]: Registering new address record for fe80::ba27:ebff:fe5f:8103 on wlan0.*. May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: rebinding lease of 192.168.1.135 May  3 16:06:18 raspberrypi kernel: [   14.500302] Bluetooth: Core ver 2.22 May  3 16:06:18 raspberrypi kernel: [   14.500366] NET: Registered protocol family 31 May  3 16:06:18 raspberrypi kernel: [   14.500369] Bluetooth: HCI device and connection manager initialized May  3 16:06:18 raspberrypi kernel: [   14.500384] Bluetooth: HCI socket layer initialized May  3 16:06:18 raspberrypi kernel: [   14.500392] Bluetooth: L2CAP socket layer initialized May  3 16:06:18 raspberrypi kernel: [   14.500417] Bluetooth: SCO socket layer initialized May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: probing address 192.168.1.135/24 May  3 16:06:18 raspberrypi kernel: [   14.541892] Bluetooth: HCI UART driver ver 2.3 May  3 16:06:18 raspberrypi kernel: [   14.541904] Bluetooth: HCI UART protocol H4 registered May  3 16:06:18 raspberrypi kernel: [   14.541979] Bluetooth: HCI UART protocol Three-wire (H5) registered May  3 16:06:18 raspberrypi kernel: [   14.542116] Bluetooth: HCI UART protocol Broadcom registered May  3 16:06:18 raspberrypi btuart[360]: bcm43xx_init May  3 16:06:18 raspberrypi btuart[360]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd May  3 16:06:18 raspberrypi btuart[360]: Set BDADDR UART: b8:27:eb:a0:7e:fc May  3 16:06:18 raspberrypi btuart[360]: Set Controller UART speed to 3000000 bit/s May  3 16:06:18 raspberrypi btuart[360]: Device setup complete May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Service RestartSec=100ms expired, scheduling restart. May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Scheduled restart job, restart counter is at 5. May  3 16:06:18 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART. May  3 16:06:18 raspberrypi systemd[1]: Stopped Phoniebox RFID-Reader Service. May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Start request repeated too quickly. May  3 16:06:18 raspberrypi systemd[1]: phoniebox-rfid-reader.service: Failed with result 'exit-code'. May  3 16:06:18 raspberrypi systemd[1]: Failed to start Phoniebox RFID-Reader Service. May  3 16:06:18 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status... May  3 16:06:18 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status. May  3 16:06:18 raspberrypi systemd[1]: Created slice system-bthelper.slice. May  3 16:06:18 raspberrypi systemd[1]: Starting Bluetooth service... May  3 16:06:18 raspberrypi dhcpcd[431]: wlan0: soliciting an IPv6 router May  3 16:06:18 raspberrypi bluetoothd[901]: Bluetooth daemon 5.50 May  3 16:06:18 raspberrypi systemd[1]: Started Bluetooth service. May  3 16:06:18 raspberrypi systemd[1]: Started BluezALSA proxy. May  3 16:06:18 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper. May  3 16:06:18 raspberrypi systemd[1]: Reached target Bluetooth. May  3 16:06:18 raspberrypi bluetoothd[901]: Starting SDP server May  3 16:06:18 raspberrypi kernel: [   15.034643] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May  3 16:06:18 raspberrypi kernel: [   15.034655] Bluetooth: BNEP filters: protocol multicast May  3 16:06:18 raspberrypi kernel: [   15.034674] Bluetooth: BNEP socket layer initialized May  3 16:06:18 raspberrypi systemd[1]: [email protected]: Succeeded. May  3 16:06:18 raspberrypi dbus-daemon[362]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.13' (uid=0 pid=901 comm="/usr/lib/bluetooth/bluetoothd ") May  3 16:06:18 raspberrypi bluetoothd[901]: Bluetooth management interface 1.14 initialized May  3 16:06:18 raspberrypi systemd[1]: Starting Hostname Service... May  3 16:06:18 raspberrypi bluetoothd[901]: Sap driver initialization failed. May  3 16:06:18 raspberrypi bluetoothd[901]: sap-server: Operation not permitted (1) May  3 16:06:18 raspberrypi bluetoothd[901]: Endpoint registered: sender=:1.14 path=/org/bluez/hci0/A2DP/SBC/Source/1 May  3 16:06:18 raspberrypi bluetoothd[901]: Endpoint registered: sender=:1.14 path=/org/bluez/hci0/A2DP/SBC/Source/2 May  3 16:06:19 raspberrypi kernel: [   15.153844] Bluetooth: RFCOMM TTY layer initialized May  3 16:06:19 raspberrypi kernel: [   15.153871] Bluetooth: RFCOMM socket layer initialized May  3 16:06:19 raspberrypi kernel: [   15.153893] Bluetooth: RFCOMM ver 1.11 May  3 16:06:19 raspberrypi bluetoothd[901]: Failed to set privacy: Rejected (0x0b) May  3 16:06:19 raspberrypi mopidy[472]: /usr/lib/python2.7/dist-packages/mopidy/ext.py:202: PkgResourcesDeprecationWarning: Parameters to load are deprecated.  Call .resolve and .require separately. May  3 16:06:19 raspberrypi mopidy[472]:   extension_class = entry_point.load(require=False) May  3 16:06:19 raspberrypi systemd[1]: Started Session c2 of user pi. May  3 16:06:19 raspberrypi dbus-daemon[362]: [system] Successfully activated service 'org.freedesktop.hostname1' May  3 16:06:19 raspberrypi systemd[1]: Started Hostname Service. May  3 16:06:19 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=1000 pid=878 comm="/usr/bin/lxsession -s LXDE-pi -e LXDE ") May  3 16:06:19 raspberrypi systemd[861]: Starting Virtual filesystem service... May  3 16:06:19 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.Daemon' May  3 16:06:19 raspberrypi systemd[861]: Started Virtual filesystem service. May  3 16:06:20 raspberrypi kernel: [   16.160840] fuse init (API version 7.27) May  3 16:06:20 raspberrypi systemd[1]: Condition check resulted in FUSE Control File System being skipped. May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: Router Advertisement from fe80::1e24:cdff:fe46:b1e0 May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: adding address 2a02:120b:7ff:54c0:ba27:ebff:fe5f:8103/64 May  3 16:06:20 raspberrypi avahi-daemon[390]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::ba27:ebff:fe5f:8103. May  3 16:06:20 raspberrypi avahi-daemon[390]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2a02:120b:7ff:54c0:ba27:ebff:fe5f:8103. May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: adding route to 2a02:120b:7ff:54c0::/64 May  3 16:06:20 raspberrypi avahi-daemon[390]: Registering new address record for 2a02:120b:7ff:54c0:ba27:ebff:fe5f:8103 on wlan0.*. May  3 16:06:20 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0. May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: requesting DHCPv6 information May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: fe80::1e24:cdff:fe46:b1e0 is reachable again May  3 16:06:20 raspberrypi dhcpcd[431]: wlan0: adding default route via fe80::1e24:cdff:fe46:b1e0 May  3 16:06:20 raspberrypi kernel: [   16.809168] ICMPv6: process dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=984 comm="pcmanfm --desktop --profile LXDE-pi ") May  3 16:06:21 raspberrypi systemd[861]: Starting Virtual filesystem service - disk device monitor... May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' May  3 16:06:21 raspberrypi systemd[861]: Started Virtual filesystem service - disk device monitor. May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.7' (uid=1000 pid=984 comm="pcmanfm --desktop --profile LXDE-pi ") May  3 16:06:21 raspberrypi systemd[861]: Starting Virtual filesystem service - Media Transfer Protocol monitor... May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' May  3 16:06:21 raspberrypi systemd[861]: Started Virtual filesystem service - Media Transfer Protocol monitor. May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.7' (uid=1000 pid=984 comm="pcmanfm --desktop --profile LXDE-pi ") May  3 16:06:21 raspberrypi systemd[861]: Starting Virtual filesystem service - Apple File Conduit monitor... May  3 16:06:21 raspberrypi mpd[876]: exception: failed to open log file "/var/log/mpd/mpd.log" (config line 40): Permission denied May  3 16:06:21 raspberrypi gvfs-afc-volume-monitor[1035]: Volume monitor alive May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' May  3 16:06:21 raspberrypi systemd[861]: Started Virtual filesystem service - Apple File Conduit monitor. May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.7' (uid=1000 pid=984 comm="pcmanfm --desktop --profile LXDE-pi ") May  3 16:06:21 raspberrypi systemd[861]: Starting Virtual filesystem service - GNOME Online Accounts monitor... May  3 16:06:21 raspberrypi systemd[861]: mpd.service: Main process exited, code=exited, status=1/FAILURE May  3 16:06:21 raspberrypi systemd[861]: mpd.service: Failed with result 'exit-code'. May  3 16:06:21 raspberrypi systemd[861]: Failed to start Music Player Daemon. May  3 16:06:21 raspberrypi systemd[861]: Reached target Default. May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' May  3 16:06:21 raspberrypi systemd[861]: Started Virtual filesystem service - GNOME Online Accounts monitor. May  3 16:06:21 raspberrypi systemd[861]: Startup finished in 4.433s. May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=984 comm="pcmanfm --desktop --profile LXDE-pi ") May  3 16:06:21 raspberrypi systemd[861]: Starting Virtual filesystem service - digital camera monitor... May  3 16:06:21 raspberrypi dbus-daemon[887]: [session uid=1000 pid=887] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' May  3 16:06:21 raspberrypi systemd[861]: Started Virtual filesystem service - digital camera monitor. May  3 16:06:21 raspberrypi mopidy[472]: INFO     Starting Mopidy 2.2.2 May  3 16:06:21 raspberrypi mopidy[472]: INFO     Loading config from builtin defaults May  3 16:06:21 raspberrypi mopidy[472]: INFO     Loading config from /etc/mopidy/mopidy.conf May  3 16:06:21 raspberrypi mopidy[472]: INFO     Loading config from command line options May  3 16:06:22 raspberrypi mopidy[472]: INFO     Enabled extensions: iris, mpd, http, stream, m3u, softwaremixer, file, local-images, local May  3 16:06:22 raspberrypi mopidy[472]: INFO     Disabled extensions: none May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Mopidy mixer: SoftwareMixer May  3 16:06:22 raspberrypi mopidy[472]: INFO     Mixer volume set to 30 May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Mopidy audio May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Mopidy backends: StreamBackend, M3UBackend, FileBackend, LocalBackend May  3 16:06:22 raspberrypi mopidy[472]: INFO     No local library metadata cache found at /var/lib/mopidy/local/library.json.gz. Please run mopidy local scan to index your local music library. If you do not have a local music collection, you can disable the local backend to hide this message. May  3 16:06:22 raspberrypi mopidy[472]: INFO     Loaded 0 local tracks using json May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Mopidy core May  3 16:06:22 raspberrypi mopidy[472]: INFO     Audio output set to "alsasink" May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Mopidy frontends: IrisFrontend, MpdFrontend, HttpFrontend May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting Iris 3.43.0 May  3 16:06:22 raspberrypi mopidy[472]: INFO     MPD server running at [::ffff:0.0.0.0]:6600 May  3 16:06:22 raspberrypi mopidy[472]: INFO     HTTP server running at [::ffff:0.0.0.0]:6680 May  3 16:06:22 raspberrypi mopidy[472]: INFO     Starting GLib mainloop May  3 16:06:22 raspberrypi dhcpcd[431]: wlan0: leased 192.168.1.135 for 86400 seconds May  3 16:06:22 raspberrypi avahi-daemon[390]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.135. May  3 16:06:22 raspberrypi avahi-daemon[390]: New relevant interface wlan0.IPv4 for mDNS. May  3 16:06:22 raspberrypi avahi-daemon[390]: Registering new address record for 192.168.1.135 on wlan0.IPv4. May  3 16:06:22 raspberrypi dhcpcd[431]: wlan0: adding route to 192.168.1.0/24 May  3 16:06:22 raspberrypi dhcpcd[431]: wlan0: adding default route via 192.168.1.1 May  3 16:06:23 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. May  3 16:06:24 raspberrypi systemd[1]: Started Samba NMB Daemon. May  3 16:06:24 raspberrypi systemd[1]: Starting Samba SMB Daemon... May  3 16:06:25 raspberrypi systemd[1]: Started Samba SMB Daemon. May  3 16:06:25 raspberrypi systemd[1]: Reached target Multi-User System. May  3 16:06:25 raspberrypi systemd[1]: Reached target Graphical Interface. May  3 16:06:25 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes... May  3 16:06:25 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May  3 16:06:25 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes. May  3 16:06:25 raspberrypi systemd[1]: Startup finished in 1.634s (kernel) + 19.939s (userspace) = 21.573s. May  3 16:06:31 raspberrypi dhcpcd[431]: wlan0: fe80::1e24:cdff:fe46:b1e0 is reachable again May  3 16:06:31 raspberrypi dhcpcd[431]: wlan0: fe80::1e24:cdff:fe46:b1e0 is reachable again May  3 16:06:39 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded. May  3 16:06:50 raspberrypi systemd-timesyncd[309]: Synchronized to time server for the first time [2a02:1205:34d5:5070:b26e:bfff:fe1d:e19b]:123 (2.debian.pool.ntp.org). May  3 16:06:58 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded. May  3 16:07:22 raspberrypi systemd[1]: phoniebox-idle-watchdog.service: Succeeded. May  3 16:09:01 raspberrypi CRON[1667]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi) May  3 16:09:03 raspberrypi systemd[1]: Starting Clean php session files... May  3 16:09:03 raspberrypi systemd[1]: phpsessionclean.service: Succeeded. May  3 16:09:03 raspberrypi systemd[1]: Started Clean php session files. May  3 16:16:40 raspberrypi systemd[1]: Reloading. May  3 16:16:40 raspberrypi systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. May  3 16:16:40 raspberrypi systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. May  3 16:16:40 raspberrypi systemd[1]: /lib/systemd/system/lighttpd.service:6: PIDFile= references path below legacy directory /var/run/, updating /var/run/lighttpd.pid → /run/lighttpd.pid; please update the unit file accordingly. May  3 16:16:54 raspberrypi systemd[1]: Reloading. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/lighttpd.service:6: PIDFile= references path below legacy directory /var/run/, updating /var/run/lighttpd.pid → /run/lighttpd.pid; please update the unit file accordingly. May  3 16:16:55 raspberrypi systemd[1]: Reloading. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. May  3 16:16:55 raspberrypi systemd[1]: /lib/systemd/system/lighttpd.service:6: PIDFile= references path below legacy directory /var/run/, updating /var/run/lighttpd.pid → /run/lighttpd.pid; please update the unit file accordingly. May  3 16:16:56 raspberrypi systemd[1]: Reloading. May  3 16:16:56 raspberrypi systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. May  3 16:16:56 raspberrypi systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. May  3 16:16:56 raspberrypi systemd[1]: /lib/systemd/system/lighttpd.service:6: PIDFile= references path below legacy directory /var/run/, updating /var/run/lighttpd.pid → /run/lighttpd.pid; please update the unit file accordingly. May  3 16:16:58 raspberrypi systemd[1]: Reloading. May  3 16:16:59 raspberrypi systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. May  3 16:16:59 raspberrypi systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. May  3 16:16:59 raspberrypi systemd[1]: /lib/systemd/system/lighttpd.service:6: PIDFile= references path below legacy directory /var/run/, updating /var/run/lighttpd.pid → /run/lighttpd.pid; please update the unit file accordingly. May  3 16:17:01 raspberrypi CRON[2102]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly) May  3 16:17:11 raspberrypi systemd[1]: Started Phoniebox Idle Watchdog Service. May  3 16:17:12 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service. May  3 16:17:12 raspberrypi systemd[1]: Starting Phoniebox Startup Sound... May  3 16:17:12 raspberrypi mpg123[2154]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3 May  3 16:17:12 raspberrypi mpg123[2154]: #011version 1.25.10; written and copyright by Michael Hipp and others May  3 16:17:12 raspberrypi mpg123[2154]: #011free software (LGPL) without any warranty but with best wishes May  3 16:17:12 raspberrypi mpg123[2154]: Directory: /home/pi/RPi-Jukebox-RFID/shared/ May  3 16:17:12 raspberrypi mpg123[2154]: Playing MPEG stream 1 of 1: startupsound.mp3 ... May  3 16:17:12 raspberrypi mpg123[2154]: MPEG 1.0 L III vbr 44100 j-s May  3 16:17:14 raspberrypi mpg123[2154]: [0:02] Decoding of startupsound.mp3 finished. May  3 16:17:14 raspberrypi systemd[1]: Started Phoniebox Startup Sound. May  3 16:18:12 raspberrypi systemd[1]: phoniebox-idle-watchdog.service: Succeeded. May  3 16:20:16 raspberrypi lighttpd[685]: chmod: changing permissions of '/home/pi/RPi-Jukebox-RFID/scripts/../settings/edition': Operation not permitted May  3 16:20:16 raspberrypi lighttpd[685]: rm: cannot remove '/home/pi/RPi-Jukebox-RFID/scripts/../settings/global.conf': No such file or directory May  3 16:20:16 raspberrypi lighttpd[685]: chmod: changing permissions of '/home/pi/RPi-Jukebox-RFID/settings/global.conf': Operation not permitted May  3 16:20:17 raspberrypi mopidy[472]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:20:17 raspberrypi mopidy[472]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:20:22 raspberrypi mopidy[472]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:20:49 raspberrypi mopidy[472]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:20:49 raspberrypi mopidy[472]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:20:49 raspberrypi systemd[1]: Stopping Mopidy music server... May  3 16:20:49 raspberrypi mopidy[472]: INFO     GLib mainloop got SIGTERM. Exiting... May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Mopidy frontends May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Iris May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Mopidy core May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Mopidy backends May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Mopidy audio May  3 16:20:49 raspberrypi mopidy[472]: INFO     Stopping Mopidy mixer May  3 16:20:49 raspberrypi systemd[1]: mopidy.service: Succeeded. May  3 16:20:49 raspberrypi systemd[1]: Stopped Mopidy music server. May  3 16:20:50 raspberrypi systemd[1]: Created slice User Slice of UID 112. May  3 16:20:50 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/112... May  3 16:20:50 raspberrypi systemd[1]: Started User Runtime Directory /run/user/112. May  3 16:20:50 raspberrypi systemd[1]: Starting User Manager for UID 112... May  3 16:20:50 raspberrypi systemd[2558]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). May  3 16:20:50 raspberrypi systemd[2558]: Starting D-Bus User Message Bus Socket. May  3 16:20:50 raspberrypi systemd[2558]: Listening on GnuPG network certificate management daemon. May  3 16:20:50 raspberrypi systemd[2558]: Reached target Paths. May  3 16:20:50 raspberrypi systemd[2558]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). May  3 16:20:50 raspberrypi systemd[2558]: Reached target Timers. May  3 16:20:50 raspberrypi systemd[2558]: Listening on GnuPG cryptographic agent and passphrase cache. May  3 16:20:50 raspberrypi systemd[2558]: Listening on GnuPG cryptographic agent (ssh-agent emulation). May  3 16:20:50 raspberrypi systemd[2558]: Listening on D-Bus User Message Bus Socket. May  3 16:20:50 raspberrypi systemd[2558]: Reached target Sockets. May  3 16:20:50 raspberrypi systemd[2558]: Reached target Basic System. May  3 16:20:50 raspberrypi systemd[1]: Started User Manager for UID 112. May  3 16:20:50 raspberrypi systemd[2558]: Starting Music Player Daemon... May  3 16:20:50 raspberrypi systemd[1]: Started Session c3 of user mopidy. May  3 16:20:51 raspberrypi mpd[2571]: exception: failed to open log file "/var/log/mpd/mpd.log" (config line 40): Permission denied May  3 16:20:51 raspberrypi systemd[2558]: mpd.service: Main process exited, code=exited, status=1/FAILURE May  3 16:20:51 raspberrypi systemd[2558]: mpd.service: Failed with result 'exit-code'. May  3 16:20:51 raspberrypi systemd[2558]: Failed to start Music Player Daemon. May  3 16:20:51 raspberrypi systemd[2558]: Reached target Default. May  3 16:20:51 raspberrypi systemd[2558]: Startup finished in 1.325s. May  3 16:20:52 raspberrypi systemd[2558]: Started D-Bus User Message Bus. May  3 16:20:54 raspberrypi lighttpd[685]: echo: write error: Broken pipe May  3 16:20:55 raspberrypi systemd[1]: session-c3.scope: Succeeded. May  3 16:20:55 raspberrypi systemd[1]: Starting Mopidy music server... May  3 16:20:55 raspberrypi systemd[1]: Started Mopidy music server. May  3 16:20:56 raspberrypi mopidy[2620]: /usr/lib/python2.7/dist-packages/mopidy/ext.py:202: PkgResourcesDeprecationWarning: Parameters to load are deprecated.  Call .resolve and .require separately. May  3 16:20:56 raspberrypi mopidy[2620]:   extension_class = entry_point.load(require=False) May  3 16:20:56 raspberrypi lighttpd[685]: mpd error: Connection refused May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy 2.2.2 May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Loading config from builtin defaults May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Loading config from /etc/mopidy/mopidy.conf May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Loading config from command line options May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Enabled extensions: iris, mpd, http, stream, m3u, softwaremixer, file, local-images, local May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Disabled extensions: none May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy mixer: SoftwareMixer May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Mixer volume set to 30 May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy audio May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy backends: StreamBackend, M3UBackend, FileBackend, LocalBackend May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Audio output set to "alsasink" May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Loaded 58 local tracks using json May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy core May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Mopidy frontends: IrisFrontend, MpdFrontend, HttpFrontend May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting Iris 3.43.0 May  3 16:20:57 raspberrypi mopidy[2620]: INFO     MPD server running at [::ffff:0.0.0.0]:6600 May  3 16:20:57 raspberrypi mopidy[2620]: INFO     HTTP server running at [::ffff:0.0.0.0]:6680 May  3 16:20:57 raspberrypi mopidy[2620]: INFO     Starting GLib mainloop May  3 16:21:00 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:00 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:05 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:05 raspberrypi systemd[1]: Stopping User Manager for UID 112... May  3 16:21:05 raspberrypi systemd[2558]: Stopped target Default. May  3 16:21:05 raspberrypi systemd[2558]: Stopping D-Bus User Message Bus... May  3 16:21:05 raspberrypi systemd[2558]: dbus.service: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Stopped D-Bus User Message Bus. May  3 16:21:05 raspberrypi systemd[2558]: Stopped target Basic System. May  3 16:21:05 raspberrypi systemd[2558]: Stopped target Paths. May  3 16:21:05 raspberrypi systemd[2558]: Stopped target Sockets. May  3 16:21:05 raspberrypi systemd[2558]: gpg-agent-extra.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed GnuPG cryptographic agent and passphrase cache (restricted). May  3 16:21:05 raspberrypi systemd[2558]: gpg-agent-ssh.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed GnuPG cryptographic agent (ssh-agent emulation). May  3 16:21:05 raspberrypi systemd[2558]: gpg-agent-browser.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). May  3 16:21:05 raspberrypi systemd[2558]: gpg-agent.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed GnuPG cryptographic agent and passphrase cache. May  3 16:21:05 raspberrypi systemd[2558]: dirmngr.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed GnuPG network certificate management daemon. May  3 16:21:05 raspberrypi systemd[2558]: Stopped target Timers. May  3 16:21:05 raspberrypi systemd[2558]: dbus.socket: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Closed D-Bus User Message Bus Socket. May  3 16:21:05 raspberrypi systemd[2558]: Reached target Shutdown. May  3 16:21:05 raspberrypi systemd[2558]: systemd-exit.service: Succeeded. May  3 16:21:05 raspberrypi systemd[2558]: Started Exit the Session. May  3 16:21:05 raspberrypi systemd[2558]: Reached target Exit the Session. May  3 16:21:05 raspberrypi systemd[1]: [email protected]: Succeeded. May  3 16:21:05 raspberrypi systemd[1]: Stopped User Manager for UID 112. May  3 16:21:05 raspberrypi systemd[1]: Stopping User Runtime Directory /run/user/112... May  3 16:21:05 raspberrypi systemd[861]: run-user-112.mount: Succeeded. May  3 16:21:05 raspberrypi systemd[1]: run-user-112.mount: Succeeded. May  3 16:21:05 raspberrypi systemd[1]: [email protected]: Succeeded. May  3 16:21:05 raspberrypi systemd[1]: Stopped User Runtime Directory /run/user/112. May  3 16:21:05 raspberrypi systemd[1]: Removed slice User Slice of UID 112. May  3 16:21:10 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:13 raspberrypi systemd[1]: Starting Cleanup of Temporary Directories... May  3 16:21:13 raspberrypi systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May  3 16:21:13 raspberrypi systemd[1]: Started Cleanup of Temporary Directories. May  3 16:21:15 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:21 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:27 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:34 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:39 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:46 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:52 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:21:58 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:04 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:10 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:15 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:22 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:27 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:34 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:40 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:22:57 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:04 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:09 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:15 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:21 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:28 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:33 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:39 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:23:53 raspberrypi dhcpcd[431]: wlan0: part of Router Advertisement expired May  3 16:23:55 raspberrypi avahi-daemon[390]: Registering new address record for fe80::ba27:ebff:fe5f:8103 on wlan0.. May  3 16:23:58 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:04 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:09 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:15 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:22 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:27 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0. May  3 16:24:28 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:33 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:39 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:24:57 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:01 raspberrypi dbus-daemon[362]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service' requested by ':1.31' (uid=0 pid=3587 comm="/usr/bin/gdbus call --system --dest org.freedeskto") May  3 16:25:01 raspberrypi systemd[1]: Starting PackageKit Daemon... May  3 16:25:01 raspberrypi PackageKit: daemon start May  3 16:25:01 raspberrypi dbus-daemon[362]: [system] Successfully activated service 'org.freedesktop.PackageKit' May  3 16:25:01 raspberrypi systemd[1]: Started PackageKit Daemon. May  3 16:25:03 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:08 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:14 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:20 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:26 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:32 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:38 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:25:56 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:05 raspberrypi lighttpd[685]: echo: write error: Broken pipe May  3 16:26:05 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:05 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:14 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:17 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:18 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:26 raspberrypi lighttpd[685]: echo: write error: Broken pipe May  3 16:26:33 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:33 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:33 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:38 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:39 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:50 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:56 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:59 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:26:59 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:27:03 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:08 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:14 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:20 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:26 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:32 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:39 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:45 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:27:56 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:02 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:08 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:14 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:20 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:26 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:32 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:38 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:44 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:28:56 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:03 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:09 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:14 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:20 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:26 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:29:32 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:30:07 raspberrypi PackageKit: daemon quit May  3 16:30:07 raspberrypi systemd[1]: packagekit.service: Main process exited, code=killed, status=15/TERM May  3 16:30:07 raspberrypi systemd[1]: packagekit.service: Succeeded. May  3 16:31:52 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:31:52 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:32:03 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:32:03 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:32:23 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:32:23 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:32:44 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:32:44 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:32:51 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:32:51 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:32:56 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:32:56 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:33:55 raspberrypi mopidy[2620]: INFO     New MPD connection from [::ffff:127.0.0.1]:6600 May  3 16:33:55 raspberrypi gpio-buttons.py[452]: volume: 30%   repeat: off   random: off   single: off   consume: off May  3 16:37:22 raspberrypi dhcpcd[431]: wlan0: part of Router Advertisement expired May  3 16:37:24 raspberrypi avahi-daemon[390]: Registering new address record for fe80::ba27:ebff:fe5f:8103 on wlan0.. May  3 16:38:01 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0. May  3 16:39:01 raspberrypi CRON[4687]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi) May  3 16:39:03 raspberrypi systemd[1]: Starting Clean php session files... May  3 16:39:04 raspberrypi systemd[1]: phpsessionclean.service: Succeeded. May  3 16:39:04 raspberrypi systemd[1]: Started Clean php session files. May  3 16:47:54 raspberrypi dhcpcd[431]: wlan0: part of Router Advertisement expired May  3 16:47:59 raspberrypi avahi-daemon[390]: Registering new address record for fe80::ba27:ebff:fe5f:8103 on wlan0.. May  3 16:48:26 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0. May  3 16:49:26 raspberrypi dhcpcd[431]: wlan0: part of Router Advertisement expired May  3 16:49:26 raspberrypi avahi-daemon[390]: Registering new address record for fe80::ba27:ebff:fe5f:8103 on wlan0.. May  3 16:49:59 raspberrypi avahi-daemon[390]: Withdrawing address record for fe80::ba27:ebff:fe5f:8103 on wlan0.

journalctl -u mopidy -- Logs begin at Sun 2020-05-03 16:06:07 BST, end at Sun 2020-05-03 16:49:59 BST May 03 16:06:12 raspberrypi systemd[1]: Starting Mopidy music server... May 03 16:06:12 raspberrypi systemd[1]: Started Mopidy music server. May 03 16:06:19 raspberrypi mopidy[472]: /usr/lib/python2.7/dist-packages/mopidy May 03 16:06:19 raspberrypi mopidy[472]: extension_class = entry_point.load(re May 03 16:06:21 raspberrypi mopidy[472]: INFO Starting Mopidy 2.2.2 May 03 16:06:21 raspberrypi mopidy[472]: INFO Loading config from builtin de May 03 16:06:21 raspberrypi mopidy[472]: INFO Loading config from /etc/mopid May 03 16:06:21 raspberrypi mopidy[472]: INFO Loading config from command li May 03 16:06:22 raspberrypi mopidy[472]: INFO Enabled extensions: iris, mpd, May 03 16:06:22 raspberrypi mopidy[472]: INFO Disabled extensions: none May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Mopidy mixer: Softwar May 03 16:06:22 raspberrypi mopidy[472]: INFO Mixer volume set to 30 May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Mopidy audio May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Mopidy backends: Stre May 03 16:06:22 raspberrypi mopidy[472]: INFO No local library metadata cach May 03 16:06:22 raspberrypi mopidy[472]: INFO Loaded 0 local tracks using js May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Mopidy core May 03 16:06:22 raspberrypi mopidy[472]: INFO Audio output set to "alsasink" May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Mopidy frontends: Iri May 03 16:06:22 raspberrypi mopidy[472]: INFO Starting Iris 3.43.0 May 03 16:06:22 raspberrypi mopidy[472]: INFO MPD server running at [::ffff: May 03 16:06:22 raspberrypi mopidy[472]: INFO HTTP server running at [::fff -->

Software

Base image and version

Raspbian GNU/Linux 10

RaspberryPi version

3 B+

RFID Reader

Waveshield PN532 NFC Hat in SPI mode

Thank you for your help!

borsic77 avatar May 03 '20 18:05 borsic77

I've added basic support for PN532 RFID readers via I2C. So you might be able to get your RFID reader to work with that as well. Check out the develop branch and see https://github.com/MiczFlor/RPi-Jukebox-RFID/tree/develop/components/rfid-reader/PN532 for more info.

If you want/need to use SPI you will most likely need to do some hacking yourself.

BTW: I don't consider this to be a bug. This should be a feature-request.

fredg02 avatar May 15 '20 16:05 fredg02

Hi @borsic77 thanks for the input. You write "in the web-interface the RFid card does not show up under the Card ID tab" and I am not sure what you expect in that tab. In the web UI this tab allows you to connect RFID tags with audio folders or system commands. Swiping the card should display the RFID ID in the form. The card itself is not shown there.

MiczFlor avatar May 19 '20 07:05 MiczFlor

Hey @borsic77, is this still an issue?

s-martin avatar Feb 28 '21 22:02 s-martin

I can confirm the waveshare hat DOES work out of the box over I2C when configured with the pins and jumpers as shown here:

https://www.waveshare.com/wiki/PN532_NFC_HAT

Tested on 2.3.0 and future3/develop

I think the ticket confusion here is on the expectation of the cards screen. I too expected it to show cards as swiped. I suggest updating the empty state message to "No cards registered. Add one below to get started" or similar.

charlesvestal avatar Jan 28 '22 15:01 charlesvestal

Labeled also for future 3.

s-martin avatar Jan 29 '22 12:01 s-martin

Confirmed it works both with recent 2.x versions and 3.x

s-martin avatar Dec 14 '23 20:12 s-martin