search

MiczFlor / RPi-Jukebox-RFID

A Raspberry Pi jukebox, playing local music, podcasts, web radio and streams triggered by RFID cards, web app or home automation. All plug and play via USB. GPIO scripts available.
http://phoniebox.de
MIT License
1.37k stars 398 forks source link

🐛 | Some Audiofiles don't work, and make that the hole folder/phoniebox don't play anymore #887

Closed Neo778 closed 4 years ago

Neo778 commented 4 years ago

Bug

Hello alltogether, at first, thank you for this great project. And sorry for my english. :-) This is my first Phoniebox, and my first Raspberry/Linux project. So a big thank you for all the good work with the manual, wiki and the youtube videos!

Everything works great. But I have one problem. If I add this youtube links, the files don't start to play and the hole folder with a file in it which worked before don't start to play anymore, too. In the actual installation all other file work anymore until I delete the "problematic" files again. This are the links/files which causes the problems: https://www.youtube.com/watch?v=4m1EFMoRFvY https://www.youtube.com/watch?v=gwA9GfYIYus https://www.youtube.com/watch?v=0OWj0CiM8WU

It happens too, if I download the audio via the Android App NewPipe and put it via the Web App in the folders. Although when i rename them to files without special symbols in it.

Can someone give me a hint, what to do?

I tried some issues from this board, which I though have similar problems. But no one worked.

Of course I can post some log files, but I don't know which may help you and where to find them. Please tell me the command or location, then I give my best. :-)

What I did

I installed buster lite via headless ssh, because I have no Screen and Keyboard. I tried the one-line-skript with and without spotify. And the developer-script with spotify. Same problem.

What happened

As written before.

I expected this to happen

A wonderful working phoniebox. :-)

Further information that might help

Radiostreams are working fine. I haven't tried out spotify, because I have no premium account. And this two youtube-links working too: https://www.youtube.com/watch?v=DBOxXxdg5yQ https://www.youtube.com/watch?v=iqND8VTQK14

Software

Base image and version

PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

Branch / Release

At the moment I have installed the normal version without spotify

Installscript

At the moment i use the recommend one-line-skript without spotify.

Hardware

RaspberryPi version

Raspberry Pi 3 version B

RFID Reader

I have the one from the shopping list.

Soundcard

No extra soundcard. I use the sound from the Raspberry Pi.

Other notable hardware

No other notable hardware. I use the USB-Boxes from the shopping list. I don't use Extra Buttons, only RFID-Cards for the command: Volume up/down, Play/Pause, Next/Privious Song, Shutdown.

Thank you for reading till here. I hope someone can help me.

MiczFlor commented 4 years ago

Hi @Neo778 not quite sure what you did with the YouTube files. Did you download them and then they won't play? Or did you add the YouTube links in a file like a playlist? YouTube files can only be played when you downloaded the files from YouTube. You can do that in the web interface of Phoniebox.

Neo778 commented 4 years ago

Hi @MiczFlor yes, i downloaded them with the web app under "register new card ID"/point C. Youtube. I added the youtube link and created a new folder. As mentioned this works with some youtube links, and with some not...

MiczFlor commented 4 years ago

And the files are in the folder you created? It's strange that the whole phoniebox should stall because of some corrupt mp3 files. I can't see a link there. Did you reboot and try again? (I am sure you did :)

Neo778 commented 4 years ago

Yes, the files are in the folder. And they are showen in the web app on the main site, when i click the small arrow. Yes, i reboot. Only when I delete the files again, the phoniebox is working again. I reinstalled the phoniebox four times, with the different versions (with and without spotify, the developer version...) Is it possible that the buster-lite installation couses the problem?

MiczFlor commented 4 years ago

Bizarre.

Neo778 commented 4 years ago

1.: Yes several times. 2.: Yes, on my Android phone I can play them. 3.: They are depending on the length of the song about 1,5 MB till 4 MB big.

s-martin commented 4 years ago

After you tried to play files, please post the output of tail -n 500 /var/log/syslog.

Neo778 commented 4 years ago

Hello this is the output, after adding https://www.youtube.com/watch?v=4m1EFMoRFvY in the folder Beats.

Apr 10 10:05:20 raspberrypi systemd[1]: Created slice system-bthelper.slice.
Apr 10 10:05:20 raspberrypi systemd[1]: Starting Bluetooth service...
Apr 10 10:05:20 raspberrypi bluetoothd[476]: Bluetooth daemon 5.50
Apr 10 10:05:20 raspberrypi systemd[1]: Started Bluetooth service.
Apr 10 10:05:20 raspberrypi bluetoothd[476]: Starting SDP server
Apr 10 10:05:20 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
Apr 10 10:05:20 raspberrypi systemd[1]: Reached target Bluetooth.
Apr 10 10:05:20 raspberrypi kernel: [   12.939301] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Apr 10 10:05:20 raspberrypi kernel: [   12.939314] Bluetooth: BNEP filters: protocol multicast
Apr 10 10:05:20 raspberrypi kernel: [   12.939338] Bluetooth: BNEP socket layer initialized
Apr 10 10:05:20 raspberrypi bluetoothd[476]: Bluetooth management interface 1.14 initialized
Apr 10 10:05:20 raspberrypi dbus-daemon[325]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=476 comm="/usr/lib/bluetooth/bluetoothd ")
Apr 10 10:05:20 raspberrypi bluetoothd[476]: Sap driver initialization failed.
Apr 10 10:05:20 raspberrypi bluetoothd[476]: sap-server: Operation not permitted (1)
Apr 10 10:05:20 raspberrypi systemd[1]: bthelper@hci0.service: Succeeded.
Apr 10 10:05:20 raspberrypi systemd[1]: Starting Hostname Service...
Apr 10 10:05:20 raspberrypi bluetoothd[476]: Failed to set privacy: Rejected (0x0b)
Apr 10 10:05:21 raspberrypi dbus-daemon[325]: [system] Successfully activated service 'org.freedesktop.hostname1'
Apr 10 10:05:21 raspberrypi systemd[1]: Started Hostname Service.
Apr 10 10:05:22 raspberrypi dhcpcd[330]: wlan0: using static address 192.168.178.37/24
Apr 10 10:05:22 raspberrypi avahi-daemon[354]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.37.
Apr 10 10:05:22 raspberrypi avahi-daemon[354]: New relevant interface wlan0.IPv4 for mDNS.
Apr 10 10:05:22 raspberrypi avahi-daemon[354]: Registering new address record for 192.168.178.37 on wlan0.IPv4.
Apr 10 10:05:22 raspberrypi dhcpcd[330]: wlan0: adding route to 192.168.178.0/24
Apr 10 10:05:22 raspberrypi dhcpcd[330]: wlan0: adding default route via 192.168.178.1
Apr 10 10:05:22 raspberrypi dhcpcd[330]: Database /etc/iproute2/rt_scopes is corrupted at iB'`P#001#0340H2g]#030s4*7ը#031͔9T$C#013#020#017(h2-#022]EMH=Ј#,˰/
Apr 10 10:05:23 raspberrypi dhcpcd[330]: forked to background, child pid 526
Apr 10 10:05:23 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Apr 10 10:05:23 raspberrypi systemd[1]: Reached target Network.
Apr 10 10:05:23 raspberrypi systemd[1]: Starting Permit User Sessions...
Apr 10 10:05:23 raspberrypi systemd[1]: Started Phoniebox Idle Watchdog Service.
Apr 10 10:05:23 raspberrypi systemd[1]: Reached target Network is Online.
Apr 10 10:05:23 raspberrypi systemd[1]: Starting Lighttpd Daemon...
Apr 10 10:05:23 raspberrypi systemd[1]: Starting LSB: exim Mail Transport Agent...
Apr 10 10:05:23 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Apr 10 10:05:23 raspberrypi systemd[1]: Started Phoniebox Rotary Encoder Service.
Apr 10 10:05:23 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
Apr 10 10:05:23 raspberrypi systemd[1]: Starting The PHP 7.3 FastCGI Process Manager...
Apr 10 10:05:23 raspberrypi systemd[1]: Started Phoniebox GPIO Buttons Service.
Apr 10 10:05:23 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Apr 10 10:05:23 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Apr 10 10:05:23 raspberrypi systemd[1]: Starting Mopidy music server...
Apr 10 10:05:23 raspberrypi systemd[1]: Starting Samba NMB Daemon...
Apr 10 10:05:23 raspberrypi systemd[1]: Started Permit User Sessions.
Apr 10 10:05:23 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Apr 10 10:05:23 raspberrypi systemd[1]: Started Mopidy music server.
Apr 10 10:05:23 raspberrypi systemd[1]: Started Getty on tty1.
Apr 10 10:05:23 raspberrypi systemd[1]: Reached target Login Prompts.
Apr 10 10:05:24 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Apr 10 10:05:24 raspberrypi systemd[1]: Started The PHP 7.3 FastCGI Process Manager.
Apr 10 10:05:25 raspberrypi systemd[1]: Started Lighttpd Daemon.
Apr 10 10:05:25 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
Apr 10 10:05:25 raspberrypi systemd[1]: Started Samba NMB Daemon.
Apr 10 10:05:25 raspberrypi systemd[1]: Starting Samba SMB Daemon...
Apr 10 10:05:25 raspberrypi exim4[530]: Starting MTA: exim4.
Apr 10 10:05:25 raspberrypi systemd[1]: Started LSB: exim Mail Transport Agent.
Apr 10 10:05:26 raspberrypi systemd[1]: Started Samba SMB Daemon.
Apr 10 10:05:26 raspberrypi systemd[1]: Reached target Multi-User System.
Apr 10 10:05:26 raspberrypi systemd[1]: Reached target Graphical Interface.
Apr 10 10:05:26 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Apr 10 10:05:26 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Apr 10 10:05:26 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Apr 10 10:05:27 raspberrypi systemd[1]: Startup finished in 1.785s (kernel) + 17.534s (userspace) = 19.320s.
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.__main__ Starting Mopidy 3.0.2
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.config Loading config from builtin defaults
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy.conf
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy-local.conf
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.config Loading config from file:///etc/mopidy/mopidy.conf
Apr 10 10:05:29 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.config Loading config from command line options
Apr 10 10:05:29 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.internal.log Loading logging config '/etc/mopidy/logging.conf' failed. 'formatters'
Apr 10 10:05:29 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:30 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.__main__ Enabled extensions: softwaremixer, m3u, iris, mpd, file, stream, http, local, spotify
Apr 10 10:05:30 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.__main__ Disabled extensions: none
Apr 10 10:05:31 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:31 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting Mopidy mixer: SoftwareMixer
Apr 10 10:05:31 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Mixer volume set to 30
Apr 10 10:05:31 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting Mopidy audio
Apr 10 10:05:31 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting Mopidy backends: FileBackend, M3UBackend, StreamBackend, SpotifyBackend, LocalBackend
Apr 10 10:05:31 raspberrypi mopidy[548]: INFO     [Audio-2] mopidy.audio.actor Audio output set to "alsasink"
Apr 10 10:05:32 raspberrypi mopidy[548]: ERROR    [SpotifyEventLoop] spotify.session Spotify login error: <ErrorType.USER_NEEDS_PREMIUM: 15>
Apr 10 10:05:32 raspberrypi mopidy[548]: INFO     [SpotifyBackend-6] mopidy_spotify.web Logged into Spotify Web API as tobi.777
Apr 10 10:05:34 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:37 raspberrypi dhcpcd[526]: wlan0: fe80::ca0e:14ff:fe57:988b is reachable again
Apr 10 10:05:37 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:40 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:43 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded.
Apr 10 10:05:55 raspberrypi systemd-timesyncd[287]: Synchronized to time server for the first time [2001:638:502:137:21b:78ff:fe32:fe4c]:123 (2.debian.pool.ntp.org).
Apr 10 10:05:56 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:05:56 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:01 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:02 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded.
Apr 10 10:06:02 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:06 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:08 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:11 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:14 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:16 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:20 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:21 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:26 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:27 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:32 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:35 raspberrypi idle-watchdog.sh[528]: mpd error: Connection refused
Apr 10 10:06:37 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:37 raspberrypi idle-watchdog.sh[528]: warning: commands will be executed using /bin/sh
Apr 10 10:06:37 raspberrypi idle-watchdog.sh[528]: job 42 at Fri Apr 10 11:06:00 2020
Apr 10 10:06:39 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:42 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:45 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:47 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:51 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:53 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:57 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:06:58 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:07:03 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:07:03 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:07:05 raspberrypi daemon_rfid_reader.py[539]: mpd error: Connection refused
Apr 10 10:07:05 raspberrypi daemon_rfid_reader.py[539]: mpd error: Connection refused
Apr 10 10:07:05 raspberrypi daemon_rfid_reader.py[539]: mpd error: Connection refused
Apr 10 10:07:06 raspberrypi daemon_rfid_reader.py[539]: mpd error: Connection refused
Apr 10 10:07:06 raspberrypi daemon_rfid_reader.py[539]: mpd error: Connection refused
Apr 10 10:07:08 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:07:09 raspberrypi lighttpd[836]: echo: write error: Broken pipe
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [SpotifyBackend-6] mopidy_spotify.playlists Refreshed 35 Spotify playlists
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting Mopidy core
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting Mopidy frontends: IrisFrontend, HttpFrontend, MpdFrontend
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [IrisFrontend-11] mopidy_iris.core Starting Iris 3.45.0
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [HttpFrontend-13] mopidy.http.actor HTTP server running at [::ffff:0.0.0.0]:6680
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [MainThread] mopidy_mpd.actor MPD server running at [::ffff:0.0.0.0]:6600
Apr 10 10:07:09 raspberrypi mopidy[548]: INFO     [MainThread] mopidy.commands Starting GLib mainloop
Apr 10 10:07:13 raspberrypi mopidy[548]: INFO     [MpdSession-15] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55626
Apr 10 10:07:13 raspberrypi mopidy[548]: INFO     [MpdSession-16] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55630
Apr 10 10:07:15 raspberrypi mopidy[548]: INFO     [MpdSession-17] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55634
Apr 10 10:07:15 raspberrypi mopidy[548]: INFO     [MpdSession-18] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55638
Apr 10 10:07:18 raspberrypi mopidy[548]: INFO     [MpdSession-19] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55642
Apr 10 10:07:19 raspberrypi mopidy[548]: INFO     [MpdSession-20] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55646
Apr 10 10:07:20 raspberrypi mopidy[548]: INFO     [MpdSession-21] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55650
Apr 10 10:07:21 raspberrypi mopidy[548]: INFO     [MpdSession-22] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55654
Apr 10 10:07:25 raspberrypi mopidy[548]: INFO     [MpdSession-23] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55658
Apr 10 10:07:27 raspberrypi mopidy[548]: INFO     [MpdSession-24] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55662
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-25] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55666
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-26] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55670
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:29 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:07:29 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:07:29 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:07:29 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:07:29 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:07:29 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:07:29 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-27] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55674
Apr 10 10:07:29 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/KiGa/Kinderlieder%20Kindergarten-Mix%20-%20Singen%2C%20Tanzen%20und%20Bewegen%20_%20Kinderlieder-DBOxXxdg5yQ.webm: Timeout after 1ms
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: loading: KiGa
Apr 10 10:07:29 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:07:29 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:07:29 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:07:29 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:07:29 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:07:29 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:07:29 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-28] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55678
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-29] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55682
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:29 raspberrypi mopidy[548]: INFO     [MpdSession-30] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55686
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: OK MPD 0.19.0
Apr 10 10:07:29 raspberrypi daemon_rfid_reader.py[539]: OK
Apr 10 10:07:30 raspberrypi mopidy[548]: INFO     [MpdSession-32] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55690
Apr 10 10:07:30 raspberrypi mopidy[548]: INFO     [MpdSession-33] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55694
Apr 10 10:07:33 raspberrypi mopidy[548]: INFO     [MpdSession-34] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55698
Apr 10 10:07:33 raspberrypi mopidy[548]: INFO     [MpdSession-35] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55702
Apr 10 10:07:35 raspberrypi mopidy[548]: INFO     [MpdSession-36] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55706
Apr 10 10:07:37 raspberrypi mopidy[548]: INFO     [MpdSession-37] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55710
Apr 10 10:07:37 raspberrypi mopidy[548]: INFO     [MpdSession-38] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55714
Apr 10 10:07:39 raspberrypi mopidy[548]: INFO     [MpdSession-39] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55718
Apr 10 10:07:40 raspberrypi mopidy[548]: INFO     [MpdSession-40] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55722
Apr 10 10:07:45 raspberrypi mopidy[548]: INFO     [MpdSession-41] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55726
Apr 10 10:07:45 raspberrypi mopidy[548]: INFO     [MpdSession-42] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55730
Apr 10 10:07:45 raspberrypi mopidy[548]: INFO     [MpdSession-43] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55734
Apr 10 10:07:45 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:46 raspberrypi mopidy[548]: INFO     [MpdSession-44] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55738
Apr 10 10:07:46 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:07:46 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:07:46 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:07:46 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:07:46 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:07:46 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:07:46 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:46 raspberrypi mopidy[548]: INFO     [MpdSession-45] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55742
Apr 10 10:07:46 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/Felix/Felix%20Jaehn%20-%20Ain%27t%20Nobody%20%28Loves%20Me%20Better%29%20%28Official%20Video%29%20ft.%20Jasmine%20Thompson-5j1RCys4R0g.webm: Timeout after 1ms
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: loading: Felix
Apr 10 10:07:46 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:07:46 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:07:46 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:07:46 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:07:46 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:07:46 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:07:46 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:07:46 raspberrypi mopidy[548]: INFO     [MpdSession-46] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55746
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:46 raspberrypi mopidy[548]: INFO     [MpdSession-47] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55750
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:07:46 raspberrypi mopidy[548]: INFO     [MpdSession-48] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55754
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: OK MPD 0.19.0
Apr 10 10:07:46 raspberrypi daemon_rfid_reader.py[539]: OK
Apr 10 10:07:51 raspberrypi mopidy[548]: INFO     [MpdSession-50] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55758
Apr 10 10:07:51 raspberrypi mopidy[548]: INFO     [MpdSession-51] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55762
Apr 10 10:07:51 raspberrypi mopidy[548]: INFO     [MpdSession-52] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55766
Apr 10 10:07:51 raspberrypi mopidy[548]: INFO     [MpdSession-53] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55770
Apr 10 10:07:56 raspberrypi mopidy[548]: INFO     [MpdSession-54] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55774
Apr 10 10:07:57 raspberrypi mopidy[548]: INFO     [MpdSession-55] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55778
Apr 10 10:08:05 raspberrypi systemd[1]: getty@tty1.service: Succeeded.
Apr 10 10:08:05 raspberrypi systemd[1]: getty@tty1.service: Service has no hold-off time (RestartSec=0), scheduling restart.
Apr 10 10:08:05 raspberrypi systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 1.
Apr 10 10:08:05 raspberrypi systemd[1]: Stopped Getty on tty1.
Apr 10 10:08:05 raspberrypi systemd[1]: Started Getty on tty1.
Apr 10 10:08:09 raspberrypi mopidy[548]: INFO     [MpdSession-56] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55782
Apr 10 10:08:16 raspberrypi mopidy[548]: INFO     [MpdSession-57] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55786
Apr 10 10:08:22 raspberrypi mopidy[548]: INFO     [MpdSession-58] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55790
Apr 10 10:08:28 raspberrypi mopidy[548]: INFO     [MpdSession-59] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55794
Apr 10 10:08:34 raspberrypi mopidy[548]: INFO     [MpdSession-60] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55798
Apr 10 10:08:39 raspberrypi mopidy[548]: INFO     [MpdSession-61] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55806
Apr 10 10:08:39 raspberrypi mopidy[548]: INFO     [MpdSession-62] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55810
Apr 10 10:08:40 raspberrypi mopidy[548]: INFO     [MpdSession-63] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55814
Apr 10 10:08:41 raspberrypi lighttpd[836]: WARNING: Writing cache to '/var/www/.cache/youtube-dl/youtube-sigfuncs/js_vflset_112.json' failed: Traceback (most recent call last):
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/local/lib/python3.7/dist-packages/youtube_dl/cache.py", line 49, in store
Apr 10 10:08:41 raspberrypi lighttpd[836]:     os.makedirs(os.path.dirname(fn))
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 221, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     mkdir(name, mode)
Apr 10 10:08:41 raspberrypi lighttpd[836]: PermissionError: [Errno 13] Permission denied: '/var/www/.cache'
Apr 10 10:08:44 raspberrypi lighttpd[836]: WARNING: Writing cache to '/var/www/.cache/youtube-dl/youtube-sigfuncs/js_vflset_108.json' failed: Traceback (most recent call last):
Apr 10 10:08:44 raspberrypi lighttpd[836]:   File "/usr/local/lib/python3.7/dist-packages/youtube_dl/cache.py", line 49, in store
Apr 10 10:08:44 raspberrypi lighttpd[836]:     os.makedirs(os.path.dirname(fn))
Apr 10 10:08:44 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:44 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:44 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:44 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:44 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 221, in makedirs
Apr 10 10:08:44 raspberrypi lighttpd[836]:     mkdir(name, mode)
Apr 10 10:08:44 raspberrypi lighttpd[836]: PermissionError: [Errno 13] Permission denied: '/var/www/.cache'
Apr 10 10:08:46 raspberrypi lighttpd[836]: ERROR: ffmpeg: error while loading shared libraries: libvchiq_arm.so: cannot open shared object file: No such file or directory
Apr 10 10:08:55 raspberrypi mopidy[548]: INFO     [MpdSession-64] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55822
Apr 10 10:09:01 raspberrypi mopidy[548]: INFO     [MpdSession-65] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55826
Apr 10 10:09:01 raspberrypi CRON[2342]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Apr 10 10:09:03 raspberrypi systemd[1]: Starting Clean php session files...
Apr 10 10:09:04 raspberrypi systemd[1]: phpsessionclean.service: Succeeded.
Apr 10 10:09:04 raspberrypi systemd[1]: Started Clean php session files.
Apr 10 10:09:06 raspberrypi systemd[1]: getty@tty1.service: Succeeded.
Apr 10 10:09:06 raspberrypi systemd[1]: getty@tty1.service: Service has no hold-off time (RestartSec=0), scheduling restart.
Apr 10 10:09:06 raspberrypi systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 2.
Apr 10 10:09:06 raspberrypi systemd[1]: Stopped Getty on tty1.
Apr 10 10:09:06 raspberrypi systemd[1]: Started Getty on tty1.
Apr 10 10:09:07 raspberrypi mopidy[548]: INFO     [MpdSession-66] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55830
Apr 10 10:09:08 raspberrypi mopidy[548]: INFO     [MpdSession-67] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55834
Apr 10 10:09:08 raspberrypi mopidy[548]: INFO     [MpdSession-68] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55838
Apr 10 10:09:13 raspberrypi mopidy[548]: INFO     [MpdSession-69] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55842
Apr 10 10:09:13 raspberrypi mopidy[548]: INFO     [MpdSession-70] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55846
Apr 10 10:09:18 raspberrypi mopidy[548]: INFO     [MpdSession-71] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55850
Apr 10 10:09:19 raspberrypi mopidy[548]: INFO     [MpdSession-72] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55854
Apr 10 10:09:23 raspberrypi mopidy[548]: INFO     [MpdSession-73] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55858
Apr 10 10:09:23 raspberrypi mopidy[548]: INFO     [MpdSession-74] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55862
Apr 10 10:09:25 raspberrypi mopidy[548]: INFO     [MpdSession-75] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55866
Apr 10 10:09:26 raspberrypi mopidy[548]: INFO     [MpdSession-76] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55870
Apr 10 10:09:26 raspberrypi mopidy[548]: INFO     [MpdSession-77] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55874
Apr 10 10:09:26 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:26 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:26 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:26 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:26 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:26 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:26 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:26 raspberrypi mopidy[548]: INFO     [MpdSession-78] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55878
Apr 10 10:09:26 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/Beats/Beyonc%C3%A9%20-%20Single%20Ladies%20%28Put%20a%20Ring%20on%20It%29%20%28Video%20Version%29-4m1EFMoRFvY.m4a: Timeout after 1ms
Apr 10 10:09:26 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:26 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:26 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:26 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:26 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:26 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:26 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:26 raspberrypi mopidy[548]: INFO     [MpdSession-79] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55882
Apr 10 10:09:27 raspberrypi mopidy[548]: INFO     [MpdSession-80] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55886
Apr 10 10:09:27 raspberrypi mopidy[548]: INFO     [MpdSession-81] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55890
Apr 10 10:09:27 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:09:27 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:09:27 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.
Apr 10 10:09:28 raspberrypi mopidy[548]: INFO     [MpdSession-82] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55894
Apr 10 10:09:28 raspberrypi mopidy[548]: INFO     [MpdSession-83] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55898
Apr 10 10:09:28 raspberrypi mopidy[548]: INFO     [MpdSession-84] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55902
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-85] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55906
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-86] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55910
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-87] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55914
Apr 10 10:09:32 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:32 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:32 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:32 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:32 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:32 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:32 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-88] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55918
Apr 10 10:09:32 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/aram/Aramsamsam.mp3: Timeout after 1ms
Apr 10 10:09:32 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:32 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:32 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:32 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:32 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:32 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:32 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-89] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55922
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-90] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55926
Apr 10 10:09:32 raspberrypi mopidy[548]: INFO     [MpdSession-91] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55930
Apr 10 10:09:33 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:09:33 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:09:33 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.
Apr 10 10:09:33 raspberrypi mopidy[548]: INFO     [MpdSession-92] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55934
Apr 10 10:09:33 raspberrypi mopidy[548]: INFO     [MpdSession-93] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55938
Apr 10 10:09:34 raspberrypi mopidy[548]: INFO     [MpdSession-94] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55942
Apr 10 10:09:34 raspberrypi mopidy[548]: INFO     [MpdSession-95] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55946
Apr 10 10:09:39 raspberrypi mopidy[548]: INFO     [MpdSession-96] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55950
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-97] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55954
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-98] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55958
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-99] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55962
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-100] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55966
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-101] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55970
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-102] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55974
Apr 10 10:09:40 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:40 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:40 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:40 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:40 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:40 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:40 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-103] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55978
Apr 10 10:09:40 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/Bey/Single%20Ladies%20%28Put%20a%20Ring%20on%20It%29.mp3: Timeout after 1ms
Apr 10 10:09:40 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:40 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:40 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:40 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:40 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:40 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:40 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:40 raspberrypi mopidy[548]: INFO     [MpdSession-104] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55982
Apr 10 10:09:41 raspberrypi mopidy[548]: INFO     [MpdSession-105] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55986
Apr 10 10:09:41 raspberrypi mopidy[548]: INFO     [MpdSession-106] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55990
Apr 10 10:09:41 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:09:41 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:09:41 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.
Apr 10 10:09:42 raspberrypi mopidy[548]: INFO     [MpdSession-107] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55994
Apr 10 10:09:43 raspberrypi mopidy[548]: INFO     [MpdSession-108] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:55998
Apr 10 10:09:43 raspberrypi idle-watchdog.sh[528]: warning: commands will be executed using /bin/sh
Apr 10 10:09:43 raspberrypi idle-watchdog.sh[528]: job 43 at Fri Apr 10 11:09:00 2020
Apr 10 10:09:44 raspberrypi mopidy[548]: INFO     [MpdSession-109] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56002
Apr 10 10:09:46 raspberrypi mopidy[548]: INFO     [MpdSession-110] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56006
Apr 10 10:09:46 raspberrypi mopidy[548]: INFO     [MpdSession-111] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56010
Apr 10 10:09:49 raspberrypi mopidy[548]: INFO     [MpdSession-112] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56014
Apr 10 10:09:52 raspberrypi mopidy[548]: INFO     [MpdSession-113] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56018
Apr 10 10:09:54 raspberrypi mopidy[548]: INFO     [MpdSession-114] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56022
Apr 10 10:09:55 raspberrypi mopidy[548]: INFO     [MpdSession-115] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56026
Apr 10 10:09:55 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:09:55 raspberrypi mopidy[548]: INFO     [MpdSession-116] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56030
Apr 10 10:09:55 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:55 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:55 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:55 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:55 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:55 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:55 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:55 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:09:55 raspberrypi mopidy[548]: INFO     [MpdSession-117] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56034
Apr 10 10:09:55 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/Felix/Felix%20Jaehn%20-%20Ain%27t%20Nobody%20%28Loves%20Me%20Better%29%20%28Official%20Video%29%20ft.%20Jasmine%20Thompson-5j1RCys4R0g.webm: Timeout after 1ms
Apr 10 10:09:55 raspberrypi daemon_rfid_reader.py[539]: loading: Felix
Apr 10 10:09:55 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:09:55 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:09:55 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:09:55 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:09:55 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:09:55 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:09:55 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:09:55 raspberrypi mopidy[548]: INFO     [MpdSession-118] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56038
Apr 10 10:09:55 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:09:55 raspberrypi mopidy[548]: INFO     [MpdSession-119] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56042
Apr 10 10:09:55 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:09:56 raspberrypi mopidy[548]: INFO     [MpdSession-120] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56046
Apr 10 10:09:56 raspberrypi daemon_rfid_reader.py[539]: OK MPD 0.19.0
Apr 10 10:09:56 raspberrypi daemon_rfid_reader.py[539]: OK
Apr 10 10:09:56 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:09:56 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:09:56 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.
Apr 10 10:09:58 raspberrypi mopidy[548]: INFO     [MpdSession-121] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56050
Apr 10 10:09:58 raspberrypi mopidy[548]: INFO     [MpdSession-122] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56054
Apr 10 10:10:01 raspberrypi mopidy[548]: INFO     [MpdSession-123] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56058
Apr 10 10:10:01 raspberrypi mopidy[548]: INFO     [MpdSession-124] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56062
Apr 10 10:10:04 raspberrypi mopidy[548]: INFO     [MpdSession-125] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56066
Apr 10 10:10:07 raspberrypi mopidy[548]: INFO     [MpdSession-126] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56070
Apr 10 10:10:10 raspberrypi mopidy[548]: INFO     [MpdSession-127] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56074
Apr 10 10:10:13 raspberrypi mopidy[548]: INFO     [MpdSession-128] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56078
Apr 10 10:10:14 raspberrypi systemd[1]: Created slice User Slice of UID 1000.
Apr 10 10:10:14 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000...
Apr 10 10:10:14 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000.
Apr 10 10:10:14 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Apr 10 10:10:15 raspberrypi systemd[1]: getty@tty1.service: Succeeded.
Apr 10 10:10:15 raspberrypi systemd[1]: getty@tty1.service: Service has no hold-off time (RestartSec=0), scheduling restart.
Apr 10 10:10:15 raspberrypi systemd[1]: getty@tty1.service: Scheduled restart job, restart counter is at 3.
Apr 10 10:10:15 raspberrypi systemd[1]: Stopped Getty on tty1.
Apr 10 10:10:15 raspberrypi systemd[1]: Started Getty on tty1.
Apr 10 10:10:15 raspberrypi systemd[3425]: /usr/lib/systemd/user/default.target:1: String is not UTF-8 clean, ignoring assignment: ��oC���#017՟�#037�="o��s�ן
Apr 10 10:10:15 raspberrypi systemd[3425]: Unit default.target failed to load properly: Invalid argument.
Apr 10 10:10:15 raspberrypi systemd[3425]: Falling back to rescue target: rescue.target
Apr 10 10:10:15 raspberrypi systemd[3425]: Unit rescue.target not found.
Apr 10 10:10:15 raspberrypi systemd[1]: user@1000.service: Failed with result 'protocol'.
Apr 10 10:10:15 raspberrypi systemd[1]: Failed to start User Manager for UID 1000.
Apr 10 10:10:15 raspberrypi systemd[1]: Started Session c1 of user pi.
Apr 10 10:10:22 raspberrypi mopidy[548]: INFO     [MpdSession-129] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56082
Apr 10 10:10:23 raspberrypi mopidy[548]: INFO     [MpdSession-130] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56086
Apr 10 10:10:28 raspberrypi mopidy[548]: INFO     [MpdSession-131] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56090
Apr 10 10:10:29 raspberrypi mopidy[548]: INFO     [MpdSession-132] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56094
Apr 10 10:10:34 raspberrypi mopidy[548]: INFO     [MpdSession-133] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56098
Apr 10 10:10:35 raspberrypi mopidy[548]: INFO     [MpdSession-134] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56102
Apr 10 10:10:40 raspberrypi mopidy[548]: INFO     [MpdSession-135] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56106
Apr 10 10:10:41 raspberrypi mopidy[548]: INFO     [MpdSession-136] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56110
Apr 10 10:10:43 raspberrypi mopidy[548]: INFO     [MpdSession-137] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56114
Apr 10 10:10:43 raspberrypi mopidy[548]: INFO     [MpdSession-138] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56118
Apr 10 10:10:46 raspberrypi mopidy[548]: INFO     [MpdSession-139] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56122
Apr 10 10:10:47 raspberrypi mopidy[548]: INFO     [MpdSession-140] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56126
Apr 10 10:10:50 raspberrypi mopidy[548]: INFO     [MpdSession-141] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56130
Apr 10 10:10:50 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:10:50 raspberrypi mopidy[548]: INFO     [MpdSession-142] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56134
Apr 10 10:10:50 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:10:50 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:10:50 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:10:50 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:10:50 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:10:50 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:10:50 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:10:50 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:10:50 raspberrypi mopidy[548]: INFO     [MpdSession-143] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56138
Apr 10 10:10:50 raspberrypi mopidy[548]: WARNING  [FileBackend-3] mopidy.file.library Failed looking up file:///home/pi/RPi-Jukebox-RFID/shared/audiofolders/aram/Aramsamsam.mp3: Timeout after 1ms
Apr 10 10:10:50 raspberrypi daemon_rfid_reader.py[539]: loading: aram
Apr 10 10:10:50 raspberrypi mopidy[548]: ERROR    [IrisFrontend-11] mopidy.listener Triggering event failed: tracklist_changed()
Apr 10 10:10:50 raspberrypi mopidy[548]: Traceback (most recent call last):
Apr 10 10:10:50 raspberrypi mopidy[548]:   File "/usr/lib/python3/dist-packages/mopidy/listener.py", line 40, in on_event
Apr 10 10:10:50 raspberrypi mopidy[548]:     getattr(self, event)(**kwargs)
Apr 10 10:10:50 raspberrypi mopidy[548]:   File "/usr/local/lib/python3.7/dist-packages/mopidy_iris/frontend.py", line 29, in tracklist_changed
Apr 10 10:10:50 raspberrypi mopidy[548]:     iris.ioloop.add_callback(functools.partial(iris.clean_queue_metadata))
Apr 10 10:10:50 raspberrypi mopidy[548]: AttributeError: 'NoneType' object has no attribute 'add_callback'
Apr 10 10:10:50 raspberrypi mopidy[548]: INFO     [MpdSession-144] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56142
Apr 10 10:10:50 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:10:50 raspberrypi mopidy[548]: INFO     [MpdSession-145] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56146
Apr 10 10:10:50 raspberrypi daemon_rfid_reader.py[539]: volume: 30%   repeat: off   random: off   single: off   consume: off
Apr 10 10:10:51 raspberrypi mopidy[548]: INFO     [MpdSession-146] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56150
Apr 10 10:10:51 raspberrypi daemon_rfid_reader.py[539]: OK MPD 0.19.0
Apr 10 10:10:51 raspberrypi daemon_rfid_reader.py[539]: OK
Apr 10 10:10:51 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:10:51 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:10:51 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.
Apr 10 10:10:52 raspberrypi mopidy[548]: INFO     [MpdSession-147] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56154
Apr 10 10:10:52 raspberrypi mopidy[548]: INFO     [MpdSession-148] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56158
Apr 10 10:10:53 raspberrypi mopidy[548]: INFO     [MpdSession-149] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56162
Apr 10 10:10:53 raspberrypi mopidy[548]: INFO     [MpdSession-150] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56166
Apr 10 10:11:03 raspberrypi mopidy[548]: INFO     [MpdSession-151] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56170
Apr 10 10:11:03 raspberrypi mopidy[548]: INFO     [MpdSession-152] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56174
Apr 10 10:11:09 raspberrypi mopidy[548]: INFO     [MpdSession-153] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56178
Apr 10 10:11:09 raspberrypi mopidy[548]: INFO     [MpdSession-154] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56182
Apr 10 10:11:09 raspberrypi systemd[1]: session-c1.scope: Succeeded.
Apr 10 10:11:09 raspberrypi systemd[1]: Stopping User Runtime Directory /run/user/1000...
Apr 10 10:11:09 raspberrypi systemd[1]: run-user-1000.mount: Succeeded.
Apr 10 10:11:09 raspberrypi systemd[1]: user-runtime-dir@1000.service: Succeeded.
Apr 10 10:11:09 raspberrypi systemd[1]: Stopped User Runtime Directory /run/user/1000.
Apr 10 10:11:09 raspberrypi systemd[1]: Removed slice User Slice of UID 1000.
Apr 10 10:11:18 raspberrypi mopidy[548]: INFO     [MpdSession-155] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56186
Apr 10 10:11:18 raspberrypi mopidy[548]: INFO     [MpdSession-156] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56190
Apr 10 10:11:24 raspberrypi mopidy[548]: INFO     [MpdSession-157] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56194
Apr 10 10:11:24 raspberrypi mopidy[548]: INFO     [MpdSession-158] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56198
Apr 10 10:11:28 raspberrypi systemd[1]: Created slice User Slice of UID 1000.
Apr 10 10:11:28 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000...
Apr 10 10:11:28 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000.
Apr 10 10:11:28 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Apr 10 10:11:28 raspberrypi systemd[3928]: /usr/lib/systemd/user/default.target:1: String is not UTF-8 clean, ignoring assignment: ��oC���#017՟�#037�="o��s�ן
Apr 10 10:11:28 raspberrypi systemd[3928]: Unit default.target failed to load properly: Invalid argument.
Apr 10 10:11:28 raspberrypi systemd[3928]: Falling back to rescue target: rescue.target
Apr 10 10:11:28 raspberrypi systemd[3928]: Unit rescue.target not found.
Apr 10 10:11:28 raspberrypi systemd[1]: user@1000.service: Failed with result 'protocol'.
Apr 10 10:11:28 raspberrypi systemd[1]: Failed to start User Manager for UID 1000.
Apr 10 10:11:28 raspberrypi systemd[1]: Started Session c2 of user pi.
Apr 10 10:11:30 raspberrypi mopidy[548]: INFO     [MpdSession-159] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56202
Apr 10 10:11:30 raspberrypi mopidy[548]: INFO     [MpdSession-160] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56206
Apr 10 10:11:36 raspberrypi mopidy[548]: INFO     [MpdSession-161] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56210
Apr 10 10:11:36 raspberrypi mopidy[548]: INFO     [MpdSession-162] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56214
Apr 10 10:11:42 raspberrypi kernel: [  383.522199] Voltage normalised (0x00000000)
Apr 10 10:11:42 raspberrypi mopidy[548]: INFO     [MpdSession-163] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56218
Apr 10 10:11:42 raspberrypi mopidy[548]: INFO     [MpdSession-164] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56222
Apr 10 10:11:44 raspberrypi kernel: [  385.602217] Under-voltage detected! (0x00050005)
Apr 10 10:11:44 raspberrypi mopidy[548]: INFO     [MpdSession-165] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56226
Apr 10 10:11:44 raspberrypi mopidy[548]: INFO     [MpdSession-166] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:56230
s-martin commented 4 years ago

Some findings in the log:

Apr 10 10:10:51 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.actor Could not find a MPEG-4 AAC decoder to handle media.
Apr 10 10:10:51 raspberrypi mopidy[548]: WARNING  [MainThread] mopidy.audio.gst GStreamer warning: No decoder available for type 'audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)12100000000000000000000000000000, rate=(int)44100, channels=(int)2'.
Apr 10 10:10:51 raspberrypi mopidy[548]: ERROR    [MainThread] mopidy.audio.gst GStreamer error: Your GStreamer installation is missing a plug-in.

There seems to be an issue with GStreamer. You could try apt-get install gstreamer1.0-plugins-bad according to https://discourse.mopidy.com/t/missing-gstreamer-plugin-for-m4a/1486

In addition you could check: https://github.com/MiczFlor/RPi-Jukebox-RFID/wiki/Troubleshooting-FAQ#fix-broken-youtube-dl-while-staying-on-same-rpi-jukebox-rfid-releaseversion

Apr 10 10:08:41 raspberrypi lighttpd[836]: WARNING: Writing cache to '/var/www/.cache/youtube-dl/youtube-sigfuncs/js_vflset_112.json' failed: Traceback (most recent call last):
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/local/lib/python3.7/dist-packages/youtube_dl/cache.py", line 49, in store
Apr 10 10:08:41 raspberrypi lighttpd[836]:     os.makedirs(os.path.dirname(fn))
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 211, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     makedirs(head, exist_ok=exist_ok)
Apr 10 10:08:41 raspberrypi lighttpd[836]:   File "/usr/lib/python3.7/os.py", line 221, in makedirs
Apr 10 10:08:41 raspberrypi lighttpd[836]:     mkdir(name, mode)
Apr 10 10:08:41 raspberrypi lighttpd[836]: PermissionError: [Errno 13] Permission denied: '/var/www/.cache'

The web server (user www-data) cannot write to this directory. You should fix the permissions of /var/www

The following should work:

sudo chown -R pi:www-data /var/www
sudo chmod -R 775 /var/www
Neo778 commented 4 years ago

Hello @s-martin thank you but it don't solve the problem. Everything like before.

The first command gives this output: pi@raspberrypi:~ $ apt-get install gstreamer1.0-plugins-bad E: Could not open lock file /var/lib/dpkg/lock-frontend - open (13: Permission denied) E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), are you root?

s-martin commented 4 years ago

Sorry you need the appropriate rights, please try: sudo apt-get install gstreamer1.0-plugins-bad

Neo778 commented 4 years ago

Thank you, I am a newbie to this whole raspberry world.

There is one Error shown in the process. And the problem is still there.

sudo apt-get install gstreamer1.0-plugins-bad Reading package lists... Done Building dependency tree Reading state information... Done The following packages were automatically installed and are no longer required: python-backports-abc python-certifi python-chardet python-concurrent.futures python-gst-1.0 python-idna python-openssl python-pykka python-requests python-singledispatch python-tornado python-urllib3 Use 'sudo apt autoremove' to remove them. The following additional packages will be installed: libdca0 libde265-0 libgssdp-1.0-3 libgstreamer-plugins-bad1.0-0 libgupnp-1.0-4 libgupnp-igd-1.0-4 libilmbase23 libkate1 liblcms2-2 libmjpegutils-2.1-0 libmpeg2encpp-2.1-0 libmplex2-2.1-0 libnice10 libofa0 libopenexr23 libsbc1 libsoundtouch1 libspandsp2 libsrtp2-1 libvo-aacenc0 libvo-amrwbenc0 libvulkan1 libwebrtc-audio-processing1 libzbar0 Suggested packages: frei0r-plugins liblcms2-utils The following NEW packages will be installed: gstreamer1.0-plugins-bad libdca0 libde265-0 libgssdp-1.0-3 libgstreamer-plugins-bad1.0-0 libgupnp-1.0-4 libgupnp-igd-1.0-4 libilmbase23 libkate1 liblcms2-2 libmjpegutils-2.1-0 libmpeg2encpp-2.1-0 libmplex2-2.1-0 libnice10 libofa0 libopenexr23 libsbc1 libsoundtouch1 libspandsp2 libsrtp2-1 libvo-aacenc0 libvo-amrwbenc0 libvulkan1 libwebrtc-audio-processing1 libzbar0 0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded. Need to get 7,332 kB of archives. After this operation, 18.1 MB of additional disk space will be used. Do you want to continue? [Y/n] y Get:1 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libvo-aacenc0 armhf 0.1.3-1 [71.1 kB] Get:2 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libvo-amrwbenc0 armhf 0.1.3-1 [67.3 kB] Get:3 http://mirror.de.leaseweb.net/raspbian/raspbian buster/main armhf libdca0 armhf 0.0.6-1 [85.1 kB] Get:4 http://mirror1.hs-esslingen.de/pub/Mirrors/archive.raspbian.org/raspbian buster/main armhf libde265-0 armhf 1.0.3-1+rpi1+b9 [180 kB] Get:5 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libgssdp-1.0-3 armhf 1.0.2-4 [22.3 kB] Get:7 http://mirror.de.leaseweb.net/raspbian/raspbian buster/main armhf libgupnp-igd-1.0-4 armhf 0.2.5-3 [24.0 kB] Err:6 http://ftp.agdsn.de/pub/mirrors/raspbian/raspbian buster/main armhf libgupnp-1.0-4 armhf 1.0.3-3 Could not connect to ftp.agdsn.de:80 (141.76.119.131). - connect (113: No route to host) Get:9 http://mirror1.hs-esslingen.de/pub/Mirrors/archive.raspbian.org/raspbian buster/main armhf libkate1 armhf 0.4.1-9 [37.0 kB] Get:8 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libilmbase23 armhf 2.2.1-2 [65.7 kB] Get:10 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf liblcms2-2 armhf 2.9-3 [116 kB] Get:11 http://mirror1.hs-esslingen.de/pub/Mirrors/archive.raspbian.org/raspbian buster/main armhf libmjpegutils-2.1-0 armhf 1:2.1.0+debian-5 [24.9 kB] Get:12 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libmpeg2encpp-2.1-0 armhf 1:2.1.0+debian-5 [66.0 kB] Get:15 http://mirror.de.leaseweb.net/raspbian/raspbian buster/main armhf libofa0 armhf 0.9.3-19 [48.0 kB] Get:17 http://mirror.de.leaseweb.net/raspbian/raspbian buster/main armhf libsbc1 armhf 1.4-1 [30.5 kB] Get:13 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libmplex2-2.1-0 armhf 1:2.1.0+debian-5 [45.1 kB] Get:14 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libnice10 armhf 0.1.14-1 [112 kB] Get:16 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libopenexr23 armhf 2.2.1-4.1 [519 kB] Get:18 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libsoundtouch1 armhf 2.1.2+ds1-1 [28.7 kB] Get:19 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libspandsp2 armhf 0.0.6+dfsg-2 [237 kB] Get:20 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libsrtp2-1 armhf 2.2.0-1 [65.0 kB] Get:21 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libvulkan1 armhf 1.1.97-2 [77.3 kB] Get:22 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libwebrtc-audio-processing1 armhf 0.3-1 [230 kB] Get:23 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libzbar0 armhf 0.22-1 [118 kB] Get:24 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf libgstreamer-plugins-bad1.0-0 armhf 1.14.4-1+b1 [1,914 kB] Get:25 http://ftp.halifax.rwth-aachen.de/raspbian/raspbian buster/main armhf gstreamer1.0-plugins-bad armhf 1.14.4-1+b1 [3,083 kB] Fetched 7,267 kB in 3min 2s (39.9 kB/s) E: Failed to fetch http://ftp.agdsn.de/pub/mirrors/raspbian/raspbian/pool/main/g/gupnp/libgupnp-1.0-4_1.0.3-3_armhf.deb Could not connect to ftp.agdsn.de:80 (141.76.119.131). - connect (113: No route to host) E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?

Neo778 commented 4 years ago

Two more things i have noticed:

  1. In the Web App it says under "Info" MPD Server Status: inactive (dead)
  2. And the phoniebox plays a sound when i shutdown with Card or with Web app, but it don't plays the welcome sound when I start the raspberry, like I saw in youtubevideos. I don't know if this have something to do with this problem.
s-martin commented 4 years ago

Please try

sudo apt update
sudo apt-get install gstreamer1.0-plugins-bad

and afterwards sudo systemctl restart mpd to fix the dead mpd

Neo778 commented 4 years ago

The problem is solved!!! Thank you very much!!!

Only in the Web App it says still MPD Server Status: inactive (dead)

This is the output of the mpd command you posted.

pi@raspberrypi:~ $ sudo systemctl restart mpd Job for mpd.service failed because the control process exited with error code. See "systemctl status mpd.service" and "journalctl -xe" for details.

s-martin commented 4 years ago

Oh, I remember you are using the Spotify edition. If I remember correctly then the MPD status in the webapp is always dead, because you don't use mpd, but mopidy

s-martin commented 4 years ago

If it's now working please close the issue.

Neo778 commented 4 years ago

No, at the moment I use classic version without spotify.