-- Logs begin at Fri 2025-01-17 17:40:49 CET, end at Fri 2025-01-17 18:02:51 CET. -- Jan 17 18:01:00 volumio mpd[10661]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:00 volumio mpd[10661]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:00 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:00 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:00 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:00 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:00 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:00 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:00 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:00 volumio sudo[10679]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:00 volumio sudo[10679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:00 volumio sudo[10679]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:01 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 17 18:01:02 volumio mpd[10681]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:02 volumio mpd[10681]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:02 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:02 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:02 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:02 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:02 volumio sudo[10689]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:02 volumio sudo[10689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:02 volumio sudo[10689]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:03 volumio mpd[10691]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:03 volumio mpd[10691]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:03 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:03 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:03 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:03 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:03 volumio sudo[10692]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:03 volumio sudo[10692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:03 volumio sudo[10692]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:05 volumio mpd[10694]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:05 volumio mpd[10694]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:05 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:05 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:05 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:05 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:05 volumio sudo[10695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:05 volumio sudo[10695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:05 volumio sudo[10695]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:06 volumio mpd[10697]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:06 volumio mpd[10697]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:06 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:06 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:06 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:06 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:06 volumio sudo[10698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:06 volumio sudo[10698]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:06 volumio sudo[10698]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:07 volumio mpd[10700]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:07 volumio mpd[10700]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:07 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:07 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:07 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:07 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:07 volumio sudo[10716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:07 volumio sudo[10716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:07 volumio sudo[10716]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:08 volumio mpd[10718]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:08 volumio mpd[10718]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:08 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:08 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:09 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:09 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:09 volumio sudo[10719]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:09 volumio sudo[10719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:09 volumio sudo[10719]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:10 volumio mpd[10721]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:10 volumio mpd[10721]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:10 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:10 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:10 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:10 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:10 volumio sudo[10722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:10 volumio sudo[10722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:10 volumio sudo[10722]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:10 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:01:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:01:10 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:01:10 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:10 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:01:10 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:01:10 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:01:10 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:10 volumio sudo[10730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:10 volumio sudo[10730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:10 volumio sudo[10730]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:11 volumio mpd[10732]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:11 volumio mpd[10732]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:12 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:12 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:12 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:12 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:12 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:12 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:12 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:12 volumio sudo[10734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:12 volumio sudo[10734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:12 volumio sudo[10734]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:13 volumio mpd[10736]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:13 volumio mpd[10736]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:13 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:13 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:13 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:13 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:13 volumio sudo[10737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:13 volumio sudo[10737]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:13 volumio sudo[10737]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:13 volumio sudo[10645]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:14 volumio mpd[10739]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:14 volumio mpd[10739]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:14 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:14 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:14 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:14 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:14 volumio sudo[10741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:14 volumio sudo[10741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:14 volumio sudo[10741]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:14 volumio go-librespot[1946]: time="2025-01-17T18:01:14+01:00" level=debug msg="fetched chunk 13/13, size: 305852" uri="spotify:track:6GGtHZgBycCgGBUhZo81xe" Jan 17 18:01:15 volumio volumio[1246]: info: CoreCommandRouter::volumioNext Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::next Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::stop Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrack 2 Jan 17 18:01:15 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:01:15 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::serviceStop Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrack 2 Jan 17 18:01:15 volumio volumio[1246]: info: CoreCommandRouter::serviceStop Jan 17 18:01:15 volumio volumio[1246]: info: Spotify Stop Jan 17 18:01:15 volumio volumio[1246]: SPOTIFY: SPOTIFY STOP Jan 17 18:01:15 volumio volumio[1246]: SPOTIFY: {"status":"play","position":2,"title":"Say Yes To Heaven","artist":"Lana Del Rey","album":"Say Yes To Heaven","albumart":"https://i.scdn.co/image/ab67616d0000b273aa27708d07f49c82ff0d0dae","uri":"spotify:track:6GGtHZgBycCgGBUhZo81xe","trackType":"spotify","codec":"ogg","seek":125631,"duration":209,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 17 18:01:15 volumio volumio[1246]: info: Sending Spotify command to local API: /player/pause Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::play index undefined Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:15 volumio volumio[1246]: info: [1737133275186] ControllerSpotify::clearAddPlayTrack Jan 17 18:01:15 volumio volumio[1246]: info: Sending Spotify command with payload to local API: /player/play Jan 17 18:01:15 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Jan 17 18:01:15 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Jan 17 18:01:15 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:01:15 volumio go-librespot[1946]: time="2025-01-17T18:01:15+01:00" level=debug msg="pause track at 155345ms" Jan 17 18:01:15 volumio mpd[10743]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:15 volumio mpd[10743]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:15 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:15 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:15 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:15 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:15 volumio sudo[10744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:15 volumio sudo[10744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:15 volumio sudo[10744]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:16 volumio mpd[10746]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:16 volumio mpd[10746]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:16 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:16 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:16 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:16 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:17 volumio sudo[10747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:17 volumio sudo[10747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:17 volumio sudo[10747]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="resolved context of track" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="emitting websocket event: will_play" Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","play_origin":"go-librespot"}} Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="selected format OGG_VORBIS_320 (4257d9c853f808b63de5b6c1f436f1457a58caf0)" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="requested aes key for file 4257d9c853f808b63de5b6c1f436f1457a58caf0, gid: 2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="fetched first chunk of 21, total size is 10610688 bytes" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=info msg="loaded track \"Cinnamon Girl\" (paused: false, position: 0ms, duration: 300683ms, prefetched: false)" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="scheduling prefetch in 271s" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="emitting websocket event: metadata" Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","name":"Cinnamon Girl","artist_names":["Lana Del Rey"],"album_name":"Norman Fucking Rockwell!","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","position":0,"duration":300683,"release_date":"year:2019 month:8 day:30","track_number":7,"disc_number":1}} Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="emitting websocket event: paused" Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","play_origin":"go-librespot"}} Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: {"status":"pause","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:17 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:01:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:17 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:17 volumio volumio[1246]: verbose: CURRENT POSITION 3 Jan 17 18:01:17 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause Jan 17 18:01:17 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jan 17 18:01:17 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:01:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:17 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:01:17 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:01:17 volumio go-librespot[1946]: time="2025-01-17T18:01:17+01:00" level=trace msg="emitting websocket event: playing" Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","play_origin":"go-librespot"}} Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:01:17 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:17 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:01:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:17 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:17 volumio volumio[1246]: verbose: CURRENT POSITION 3 Jan 17 18:01:17 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:01:17 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jan 17 18:01:18 volumio mpd[10763]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:18 volumio mpd[10763]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:18 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:01:18 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:18 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:01:18 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:18 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d00001e02879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","seek":0,"duration":300,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:01:18 volumio volumio[1246]: verbose: CURRENT POSITION 3 Jan 17 18:01:18 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:01:18 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Jan 17 18:01:18 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Jan 17 18:01:18 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:01:18 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:18 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:01:18 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:01:18 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:18 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:18 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:18 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:01:18 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:18 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:01:18 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:18 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:01:18 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:01:18 volumio sudo[10765]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:18 volumio sudo[10765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:18 volumio sudo[10765]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:19 volumio mpd[10767]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:19 volumio mpd[10767]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:19 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:19 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:19 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:19 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:19 volumio sudo[10768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:19 volumio sudo[10768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:19 volumio sudo[10768]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:20 volumio mpd[10770]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:20 volumio mpd[10770]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:20 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:20 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:20 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:20 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:20 volumio sudo[10771]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:20 volumio sudo[10771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:20 volumio sudo[10771]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:21 volumio mpd[10773]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:21 volumio mpd[10773]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:21 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:21 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:21 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:21 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:21 volumio sudo[10774]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:21 volumio sudo[10774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:21 volumio sudo[10774]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:22 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:01:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:01:22 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:01:22 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:22 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:22 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:01:22 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:01:22 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:01:22 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:22 volumio sudo[10782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:22 volumio sudo[10782]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:22 volumio sudo[10782]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:23 volumio mpd[10784]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:23 volumio mpd[10784]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:23 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:23 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:23 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:23 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:23 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:23 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:23 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:23 volumio sudo[10786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:23 volumio sudo[10786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:23 volumio sudo[10786]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:24 volumio mpd[10788]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:24 volumio mpd[10788]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:24 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:24 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:24 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:24 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:24 volumio sudo[10789]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:24 volumio sudo[10789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:24 volumio sudo[10789]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:25 volumio mpd[10791]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:25 volumio mpd[10791]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:25 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:25 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:25 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:25 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:25 volumio sudo[10792]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:25 volumio sudo[10792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:25 volumio sudo[10792]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:26 volumio mpd[10794]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:26 volumio mpd[10794]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:26 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:26 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:26 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:26 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:26 volumio sudo[10795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:26 volumio sudo[10795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:26 volumio sudo[10795]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:27 volumio mpd[10797]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:27 volumio mpd[10797]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:27 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:27 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:27 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:27 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:27 volumio sudo[10812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:27 volumio sudo[10812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:27 volumio sudo[10812]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:28 volumio mpd[10814]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:28 volumio mpd[10814]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:28 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:28 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:28 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:28 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:29 volumio sudo[10815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:29 volumio sudo[10815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:29 volumio sudo[10815]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:30 volumio mpd[10818]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:30 volumio mpd[10818]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:30 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:30 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:30 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:30 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:30 volumio sudo[10822]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:30 volumio sudo[10822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:30 volumio sudo[10822]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:31 volumio mpd[10824]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:31 volumio mpd[10824]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:31 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:31 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:31 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:31 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:31 volumio sudo[10825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:31 volumio sudo[10825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:31 volumio sudo[10825]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:32 volumio mpd[10827]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:32 volumio mpd[10827]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:32 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:32 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:32 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:32 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:32 volumio sudo[10844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:32 volumio sudo[10844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:32 volumio sudo[10844]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:33 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:01:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:01:33 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:01:33 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:33 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:01:33 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:01:33 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:01:33 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:33 volumio sudo[10852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:33 volumio sudo[10852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:33 volumio sudo[10852]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:34 volumio mpd[10854]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:34 volumio mpd[10854]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:34 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:34 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:34 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:34 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:34 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:34 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:34 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:34 volumio sudo[10856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:34 volumio sudo[10856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:34 volumio sudo[10856]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:34 volumio go-librespot[1946]: time="2025-01-17T18:01:34+01:00" level=debug msg="fetched chunk 4/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:34 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Jan 17 18:01:34 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:35 volumio sudo[10863]: root : TTY=unknown ; PWD=/home/volumio/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Jan 17 18:01:35 volumio sudo[10863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:35 volumio mpd[10858]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:35 volumio mpd[10858]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:35 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:35 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:35 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:35 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:35 volumio sudo[10866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:35 volumio sudo[10866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:35 volumio sudo[10866]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:36 volumio mpd[10868]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:36 volumio mpd[10868]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:36 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:36 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:36 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:36 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:37 volumio sudo[10869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:37 volumio sudo[10869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:37 volumio sudo[10869]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:38 volumio mpd[10875]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:38 volumio mpd[10875]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:38 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:38 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:38 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:38 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:38 volumio sudo[10902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:38 volumio sudo[10902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:38 volumio sudo[10902]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:38 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 17 18:01:38 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Jan 17 18:01:38 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:01:39 volumio mpd[10904]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:39 volumio mpd[10904]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:39 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:39 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:39 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:39 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:40 volumio sudo[10918]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:40 volumio sudo[10918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:40 volumio sudo[10918]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:41 volumio mpd[10920]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:41 volumio mpd[10920]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:41 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:41 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:41 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:41 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:41 volumio sudo[10921]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:41 volumio sudo[10921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:41 volumio sudo[10921]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:43 volumio mpd[10923]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:43 volumio mpd[10923]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:43 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:43 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:43 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:43 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:43 volumio sudo[10925]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:43 volumio sudo[10925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:43 volumio sudo[10925]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:44 volumio mpd[10927]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:44 volumio mpd[10927]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:44 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:44 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:44 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:44 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:44 volumio sudo[10928]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:44 volumio sudo[10928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:44 volumio sudo[10928]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:44 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:01:44 volumio mpd_monitor.sh[740]: mpd: no process found Jan 17 18:01:44 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:01:44 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:01:44 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:01:44 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:44 volumio sudo[10935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:44 volumio sudo[10935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:44 volumio sudo[10935]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:45 volumio mpd[10937]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:45 volumio mpd[10937]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:45 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:45 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:45 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:45 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:45 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:45 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:45 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:45 volumio sudo[10939]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:45 volumio sudo[10939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:45 volumio sudo[10939]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:46 volumio mpd[10941]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:46 volumio mpd[10941]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:46 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:46 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:46 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:46 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:46 volumio sudo[10942]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:46 volumio sudo[10942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:46 volumio sudo[10942]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:48 volumio mpd[10944]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:48 volumio mpd[10944]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:48 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:48 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:48 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:48 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:48 volumio sudo[10959]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:48 volumio sudo[10959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:48 volumio sudo[10959]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:49 volumio mpd[10961]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:49 volumio mpd[10961]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:49 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:49 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:49 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:49 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:49 volumio sudo[10962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:49 volumio sudo[10962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:49 volumio sudo[10962]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:50 volumio mpd[10964]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:50 volumio mpd[10964]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:50 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:50 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:50 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:50 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:50 volumio sudo[10965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:50 volumio sudo[10965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:50 volumio sudo[10965]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:50 volumio go-librespot[1946]: time="2025-01-17T18:01:50+01:00" level=debug msg="fetched chunk 5/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:01:51 volumio mpd[10967]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:51 volumio mpd[10967]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:51 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:51 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:51 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:51 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:51 volumio sudo[10968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:51 volumio sudo[10968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:51 volumio sudo[10968]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:52 volumio mpd[10970]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:52 volumio mpd[10970]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:52 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:52 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:52 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:52 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:53 volumio sudo[10971]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:53 volumio sudo[10971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:53 volumio sudo[10971]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:54 volumio mpd[10973]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:54 volumio mpd[10973]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:54 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:54 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:54 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:54 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:54 volumio sudo[10974]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:54 volumio sudo[10974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:54 volumio sudo[10974]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:55 volumio mpd[10976]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:55 volumio mpd[10976]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:55 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:55 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:55 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:55 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:55 volumio sudo[10977]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:55 volumio sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:55 volumio sudo[10977]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:55 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:01:55 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:01:55 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:01:55 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:55 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:01:55 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:01:55 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:01:55 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:55 volumio sudo[10985]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:55 volumio sudo[10985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:55 volumio sudo[10985]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:56 volumio mpd[10987]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:56 volumio mpd[10987]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:57 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:57 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:57 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:57 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:01:57 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:01:57 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:01:57 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:57 volumio sudo[11036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:57 volumio sudo[11036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:57 volumio sudo[11036]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:58 volumio mpd[11052]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:58 volumio mpd[11052]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:58 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:58 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:58 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:58 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:58 volumio sudo[11053]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:58 volumio sudo[11053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:58 volumio sudo[11053]: pam_unix(sudo:session): session closed for user root Jan 17 18:01:59 volumio mpd[11055]: Jan 17 18:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:01:59 volumio mpd[11055]: Jan 17 18:01 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:01:59 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:01:59 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:01:59 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:01:59 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:01:59 volumio sudo[11056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:01:59 volumio sudo[11056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:01:59 volumio sudo[11056]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:00 volumio mpd[11058]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:00 volumio mpd[11058]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:00 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:00 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:00 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:00 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:00 volumio sudo[11059]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:00 volumio sudo[11059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:00 volumio sudo[11059]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:01 volumio mpd[11061]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:01 volumio mpd[11061]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:01 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:01 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:01 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:02 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:02 volumio sudo[11062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:02 volumio sudo[11062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:02 volumio sudo[11062]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:02 volumio volumio[1246]: info: CALLMETHOD: user_interface now_playing configSaveIdleScreenSettings [object Object] Jan 17 18:02:02 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveIdleScreenSettings Jan 17 18:02:03 volumio mpd[11064]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:03 volumio mpd[11064]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:03 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:03 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:03 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:03 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:03 volumio sudo[11067]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:03 volumio sudo[11067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:03 volumio sudo[11067]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:04 volumio mpd[11069]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:04 volumio mpd[11069]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:04 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:04 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:04 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:04 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:04 volumio sudo[11070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:04 volumio sudo[11070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:04 volumio sudo[11070]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:04 volumio go-librespot[1946]: time="2025-01-17T18:02:04+01:00" level=debug msg="fetched chunk 6/20, size: 524288" uri="spotify:track:2mdEsXPu8ZmkHRRtAdC09e" Jan 17 18:02:05 volumio mpd[11072]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:05 volumio mpd[11072]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:05 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:05 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:05 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:05 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:05 volumio sudo[11073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:05 volumio sudo[11073]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:05 volumio sudo[11073]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:05 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 18:02:06 volumio mpd[11075]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:06 volumio mpd[11075]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:06 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:06 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:06 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:06 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:06 volumio sudo[11077]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:06 volumio sudo[11077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:06 volumio sudo[11077]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:07 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:02:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:02:07 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:02:07 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::volumioNext Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::next Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::stop Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::serviceStop Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 3 Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::serviceStop Jan 17 18:02:07 volumio volumio[1246]: info: Spotify Stop Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: SPOTIFY STOP Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: {"status":"play","position":3,"title":"Cinnamon Girl","artist":"Lana Del Rey","album":"Norman Fucking Rockwell!","albumart":"https://i.scdn.co/image/ab67616d0000b273879e9318cb9f4e05ee552ac9","uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","trackType":"spotify","codec":"ogg","seek":0,"duration":300,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 17 18:02:07 volumio volumio[1246]: info: Sending Spotify command to local API: /player/pause Jan 17 18:02:07 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:02:07 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:02:07 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:02:07 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::play index undefined Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:07 volumio volumio[1246]: info: [1737133327133] ControllerSpotify::clearAddPlayTrack Jan 17 18:02:07 volumio volumio[1246]: info: Sending Spotify command with payload to local API: /player/play Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="pause track at 49585ms" Jan 17 18:02:07 volumio sudo[11086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:07 volumio sudo[11086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:07 volumio sudo[11086]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="emitting websocket event: paused" Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2mdEsXPu8ZmkHRRtAdC09e","play_origin":"go-librespot"}} Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:07 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:07 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="resolved context of track" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="emitting websocket event: will_play" Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","play_origin":"go-librespot"}} Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="selected format OGG_VORBIS_320 (3028c22bea5593aab671253b7c2dd581e68e2e30)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="requested aes key for file 3028c22bea5593aab671253b7c2dd581e68e2e30, gid: 5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="fetched first chunk of 18, total size is 9380016 bytes" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=info msg="loaded track \"West Coast\" (paused: false, position: 0ms, duration: 256760ms, prefetched: false)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="scheduling prefetch in 227s" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="emitting websocket event: metadata" Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","name":"West Coast","artist_names":["Lana Del Rey"],"album_name":"Ultraviolence (Deluxe)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","position":0,"duration":256760,"release_date":"year:2014 month:1 day:1","track_number":5,"disc_number":1}} Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:07 volumio go-librespot[1946]: time="2025-01-17T18:02:07+01:00" level=trace msg="emitting websocket event: playing" Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","play_origin":"go-librespot"}} Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:07 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:07 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:07 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:07 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:07 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:02:07 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Jan 17 18:02:08 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:08 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:08 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:08 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:08 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":0,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:08 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:08 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:02:08 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Jan 17 18:02:08 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Jan 17 18:02:08 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:08 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:08 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:08 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:08 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:08 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:08 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:08 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:08 volumio screensaver.sh[738]: Unhandled exception in thread started by > Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/api.py", line 183, in _typeof Jan 17 18:02:08 volumio screensaver.sh[738]: Unhandled exception in thread started by > Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 600, in urlopen Jan 17 18:02:08 volumio screensaver.sh[738]: KeyError: 'double[2]' Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 554, in render_text Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 343, in _make_request Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cairosvg/__init__.py", line 60, in svg2png Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 841, in _validate_conn Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connection.py", line 332, in connect Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cairosvg/surface.py", line 137, in convert Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/util/ssl_.py", line 264, in create_urllib3_context Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/ssl.py", line 388, in __new__ Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cairosvg/surface.py", line 216, in __init__ Jan 17 18:02:08 volumio screensaver.sh[738]: ssl.SSLError: [SSL] malloc failure (_ssl.c:2994) Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cairosvg/surface.py", line 262, in set_context_size Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 865, in run Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 114, in albumart_thread Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cairocffi/context.py", line 894, in get_current_point Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/api.py", line 266, in new Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 305, in get_albumart_data Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/api.py", line 75, in get Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/api.py", line 60, in request Jan 17 18:02:08 volumio screensaver.sh[738]: Unhandled exception in thread started by > Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 885, in _bootstrap Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/api.py", line 186, in _typeof Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 533, in request Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/api.py", line 171, in _typeof_locked Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 905, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/cparser.py", line 552, in parse_type Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 646, in send Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 899, in _set_tstate_lock Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/adapters.py", line 449, in send Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/cparser.py", line 555, in parse_type_and_quals Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/cffi/cparser.py", line 336, in _parse Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 638, in urlopen Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/pycparser/c_parser.py", line 150, in parse Jan 17 18:02:08 volumio screensaver.sh[738]: MemoryError Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 885, in _bootstrap Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/pycparser/ply/yacc.py", line 331, in parse Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 927, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/pycparser/ply/yacc.py", line 1061, in parseopt_notrack Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 167, in format_exc Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/pycparser/c_lexer.py", line 76, in token Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/local/lib/python3.7/dist-packages/pycparser/ply/lex.py", line 320, in token Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 121, in format_exception Jan 17 18:02:08 volumio screensaver.sh[738]: MemoryError Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 497, in __init__ Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 865, in run Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 121, in titleinfo_thread Jan 17 18:02:08 volumio screensaver.sh[738]: MemoryError Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 583, in render_text Jan 17 18:02:08 volumio screensaver.sh[738]: pygame.error: Text has zero width Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 885, in _bootstrap Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 927, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: (self.name, _format_exc()), file=_sys.stderr) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 167, in format_exc Jan 17 18:02:08 volumio screensaver.sh[738]: return "".join(format_exception(*sys.exc_info(), limit=limit, chain=chain)) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 121, in format_exception Jan 17 18:02:08 volumio screensaver.sh[738]: type(value), value, tb, limit=limit).format(chain=chain)) Jan 17 18:02:08 volumio screensaver.sh[738]: MemoryError Jan 17 18:02:08 volumio screensaver.sh[738]: Unhandled exception in thread started by > Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 865, in run Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 121, in titleinfo_thread Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 519, in render_text Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 501, in render_txt Jan 17 18:02:08 volumio screensaver.sh[738]: pygame.error: Out of memory Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 885, in _bootstrap Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 927, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 167, in format_exc Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 121, in format_exception Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 508, in __init__ Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 363, in extract Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/traceback.py", line 285, in line Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/linecache.py", line 16, in getline Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/linecache.py", line 47, in getlines Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/linecache.py", line 136, in updatecache Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/tokenize.py", line 447, in open Jan 17 18:02:08 volumio screensaver.sh[738]: RuntimeError: can't allocate read lock Jan 17 18:02:08 volumio screensaver.sh[738]: Exception in thread Thread-27: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 600, in urlopen Jan 17 18:02:08 volumio screensaver.sh[738]: chunked=chunked) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 343, in _make_request Jan 17 18:02:08 volumio screensaver.sh[738]: self._validate_conn(conn) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 841, in _validate_conn Jan 17 18:02:08 volumio screensaver.sh[738]: conn.connect() Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connection.py", line 332, in connect Jan 17 18:02:08 volumio screensaver.sh[738]: cert_reqs=resolve_cert_reqs(self.cert_reqs), Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/util/ssl_.py", line 264, in create_urllib3_context Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/ssl.py", line 388, in __new__ Jan 17 18:02:08 volumio screensaver.sh[738]: self = _SSLContext.__new__(cls, protocol) Jan 17 18:02:08 volumio screensaver.sh[738]: ssl.SSLError: [SSL] malloc failure (_ssl.c:2994) Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/adapters.py", line 449, in send Jan 17 18:02:08 volumio screensaver.sh[738]: timeout=timeout Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 638, in urlopen Jan 17 18:02:08 volumio screensaver.sh[738]: _stacktrace=sys.exc_info()[2]) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/urllib3/util/retry.py", line 398, in increment Jan 17 18:02:08 volumio screensaver.sh[738]: raise MaxRetryError(_pool, url, error or ResponseError(cause)) Jan 17 18:02:08 volumio screensaver.sh[738]: urllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host='i.scdn.co', port=443): Max retries exceeded with url: /image/ab67616d0000b2731624590458126fc8b8c64c2f (Caused by SSLError(SSLError(336236609, '[SSL] malloc failure (_ssl.c:2994)'))) Jan 17 18:02:08 volumio screensaver.sh[738]: During handling of the above exception, another exception occurred: Jan 17 18:02:08 volumio screensaver.sh[738]: Traceback (most recent call last): Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jan 17 18:02:08 volumio screensaver.sh[738]: self.run() Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3.7/threading.py", line 865, in run Jan 17 18:02:08 volumio screensaver.sh[738]: self._target(*self._args, **self._kwargs) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 114, in albumart_thread Jan 17 18:02:08 volumio screensaver.sh[738]: tf.get_albumart_data(args[1]) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 305, in get_albumart_data Jan 17 18:02:08 volumio screensaver.sh[738]: stream = io.BytesIO(requests.get(albumart).content) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/api.py", line 75, in get Jan 17 18:02:08 volumio screensaver.sh[738]: return request('get', url, params=params, **kwargs) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/api.py", line 60, in request Jan 17 18:02:08 volumio screensaver.sh[738]: return session.request(method=method, url=url, **kwargs) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 533, in request Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 646, in send Jan 17 18:02:08 volumio screensaver.sh[738]: r = adapter.send(request, **kwargs) Jan 17 18:02:08 volumio screensaver.sh[738]: File "/usr/lib/python3/dist-packages/requests/adapters.py", line 514, in send Jan 17 18:02:08 volumio screensaver.sh[738]: raise SSLError(e, request=request) Jan 17 18:02:08 volumio screensaver.sh[738]: requests.exceptions.SSLError: HTTPSConnectionPool(host='i.scdn.co', port=443): Max retries exceeded with url: /image/ab67616d0000b2731624590458126fc8b8c64c2f (Caused by SSLError(SSLError(336236609, '[SSL] malloc failure (_ssl.c:2994)'))) Jan 17 18:02:08 volumio mpd[11097]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:08 volumio mpd[11097]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:08 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:08 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:08 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:08 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:02:08 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:02:08 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:02:08 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:08 volumio sudo[11114]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:08 volumio sudo[11114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:08 volumio sudo[11114]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:09 volumio mpd[11116]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:09 volumio mpd[11116]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:09 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:09 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:09 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:09 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:09 volumio sudo[11117]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:09 volumio sudo[11117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:09 volumio sudo[11117]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:10 volumio mpd[11119]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:10 volumio mpd[11119]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:10 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:10 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:10 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:10 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:11 volumio sudo[11120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:11 volumio sudo[11120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:11 volumio sudo[11120]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:12 volumio mpd[11122]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:12 volumio mpd[11122]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:12 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:12 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:12 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:12 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:12 volumio sudo[11123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:12 volumio sudo[11123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:12 volumio sudo[11123]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:13 volumio mpd[11125]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:13 volumio mpd[11125]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:13 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:13 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:13 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:13 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:13 volumio sudo[11126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:13 volumio sudo[11126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:13 volumio sudo[11126]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:14 volumio mpd[11128]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:14 volumio mpd[11128]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:14 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:14 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:14 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:14 volumio volumio[1246]: info: CoreCommandRouter::volumioPause Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::pause Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::servicePause Jan 17 18:02:14 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:14 volumio volumio[1246]: info: CoreCommandRouter::servicePause Jan 17 18:02:14 volumio volumio[1246]: info: Spotify Received pause Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: SPOTIFY PAUSE Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: {"status":"play","position":4,"title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d0000b2731624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","codec":"ogg","seek":0,"duration":256,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 17 18:02:14 volumio volumio[1246]: info: Sending Spotify command to local API: /player/pause Jan 17 18:02:14 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:14 volumio go-librespot[1946]: time="2025-01-17T18:02:14+01:00" level=debug msg="pause track at 6910ms" Jan 17 18:02:14 volumio sudo[11129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:14 volumio sudo[11129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:14 volumio sudo[11129]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:14 volumio go-librespot[1946]: time="2025-01-17T18:02:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:14 volumio go-librespot[1946]: time="2025-01-17T18:02:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:14 volumio go-librespot[1946]: time="2025-01-17T18:02:14+01:00" level=trace msg="emitting websocket event: paused" Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","play_origin":"go-librespot"}} Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: {"status":"pause","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:14 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:14 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:14 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:14 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus pause Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:14 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:14 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:14 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:14 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:02:14 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:14 volumio screensaver.sh[738]: pygame 1.9.4.post1 Jan 17 18:02:14 volumio screensaver.sh[738]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 17 18:02:15 volumio sudo[10863]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:15 volumio mpd[11131]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:15 volumio mpd[11131]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:15 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:15 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:15 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:15 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:15 volumio sudo[11135]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:15 volumio sudo[11135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:15 volumio sudo[11135]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:16 volumio mpd[11137]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:16 volumio mpd[11137]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:16 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:16 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:16 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:16 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:16 volumio volumio[1246]: info: CoreCommandRouter::volumioPlay Jan 17 18:02:16 volumio volumio[1246]: info: CoreStateMachine::play index undefined Jan 17 18:02:16 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 18:02:16 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:16 volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer Jan 17 18:02:16 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:16 volumio volumio[1246]: info: Spotify Resume Jan 17 18:02:16 volumio volumio[1246]: info: Sending Spotify command to local API: /player/resume Jan 17 18:02:16 volumio sudo[11139]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:16 volumio sudo[11139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:16 volumio sudo[11139]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:16 volumio go-librespot[1946]: time="2025-01-17T18:02:16+01:00" level=trace msg="seek to 6910ms (diff: 100ms, samples: 304731, bytes: 247579)" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:16 volumio go-librespot[1946]: time="2025-01-17T18:02:16+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Jan 17 18:02:16 volumio go-librespot[1946]: time="2025-01-17T18:02:16+01:00" level=debug msg="resume track at 6760ms" Jan 17 18:02:16 volumio go-librespot[1946]: time="2025-01-17T18:02:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:16 volumio go-librespot[1946]: time="2025-01-17T18:02:16+01:00" level=trace msg="scheduling prefetch in 220s" Jan 17 18:02:17 volumio go-librespot[1946]: time="2025-01-17T18:02:17+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:17 volumio go-librespot[1946]: time="2025-01-17T18:02:17+01:00" level=trace msg="emitting websocket event: playing" Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","play_origin":"go-librespot"}} Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:17 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:17 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus pause Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:17 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":6000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:17 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Jan 17 18:02:17 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:17 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:17 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:17 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:17 volumio volumio[1246]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 17 18:02:17 volumio mpd[11141]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:17 volumio mpd[11141]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:17 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:17 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:17 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:17 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:18 volumio sudo[11158]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:18 volumio sudo[11158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:18 volumio sudo[11158]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:18 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:02:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:02:18 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:02:18 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:18 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:02:18 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:02:18 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:02:18 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:18 volumio sudo[11166]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:18 volumio sudo[11166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:18 volumio sudo[11166]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:19 volumio mpd[11168]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:19 volumio mpd[11168]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:19 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:19 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:19 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:19 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:02:19 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:02:19 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:02:19 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:19 volumio sudo[11170]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:19 volumio sudo[11170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:19 volumio sudo[11170]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:20 volumio mpd[11172]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:20 volumio mpd[11172]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:20 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:20 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:20 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:20 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:20 volumio sudo[11173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:20 volumio sudo[11173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:20 volumio sudo[11173]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:21 volumio mpd[11175]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:21 volumio mpd[11175]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:22 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:22 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:22 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:22 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:22 volumio sudo[11176]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:22 volumio sudo[11176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:22 volumio sudo[11176]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:23 volumio mpd[11178]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:23 volumio mpd[11178]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:23 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:23 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:23 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:23 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:23 volumio sudo[11179]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:23 volumio sudo[11179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:23 volumio sudo[11179]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:23 volumio go-librespot[1946]: time="2025-01-17T18:02:23+01:00" level=debug msg="fetched chunk 4/17, size: 524288" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:24 volumio mpd[11181]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:24 volumio mpd[11181]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:24 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:24 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:24 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:24 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:24 volumio sudo[11182]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:24 volumio sudo[11182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:24 volumio sudo[11182]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:25 volumio mpd[11184]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:25 volumio mpd[11184]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:25 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:25 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:25 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:25 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:25 volumio sudo[11185]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:25 volumio sudo[11185]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:25 volumio sudo[11185]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:26 volumio mpd[11187]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:26 volumio mpd[11187]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:26 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:26 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:26 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:26 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:26 volumio sudo[11188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:26 volumio sudo[11188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:26 volumio sudo[11188]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:27 volumio mpd[11190]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:27 volumio mpd[11190]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:27 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:27 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:27 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:27 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:27 volumio sudo[11205]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:27 volumio sudo[11205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:27 volumio sudo[11205]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:28 volumio mpd[11207]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:28 volumio mpd[11207]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:28 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:28 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:28 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:28 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:29 volumio sudo[11208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:29 volumio sudo[11208]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:29 volumio sudo[11208]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:29 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:02:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:02:29 volumio systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:02:29 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:29 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:02:29 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:02:29 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:02:29 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:29 volumio sudo[11216]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:29 volumio sudo[11216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:29 volumio sudo[11216]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:30 volumio mpd[11218]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:30 volumio mpd[11218]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:30 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:30 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:30 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:30 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:02:30 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:02:30 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:02:30 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:31 volumio sudo[11219]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:31 volumio sudo[11219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:31 volumio sudo[11219]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:32 volumio mpd[11222]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:32 volumio mpd[11222]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:32 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:32 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:32 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:32 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:32 volumio sudo[11223]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:32 volumio sudo[11223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:32 volumio sudo[11223]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:33 volumio mpd[11225]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:33 volumio mpd[11225]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:33 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:33 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:33 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:33 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:33 volumio sudo[11226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:33 volumio sudo[11226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:33 volumio sudo[11226]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:34 volumio mpd[11228]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:34 volumio mpd[11228]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:34 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:34 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:34 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:34 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:34 volumio sudo[11229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:34 volumio sudo[11229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:34 volumio sudo[11229]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:35 volumio mpd[11232]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:35 volumio mpd[11232]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:35 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:35 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:35 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:35 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:35 volumio sudo[11233]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:35 volumio sudo[11233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:35 volumio sudo[11233]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:36 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Jan 17 18:02:36 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:36 volumio sudo[11240]: root : TTY=unknown ; PWD=/home/volumio/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Jan 17 18:02:36 volumio sudo[11240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:36 volumio mpd[11235]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:36 volumio mpd[11235]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:36 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:36 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:36 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:36 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:36 volumio sudo[11243]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:36 volumio sudo[11243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:36 volumio sudo[11243]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:38 volumio mpd[11245]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:38 volumio mpd[11245]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:38 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:38 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:38 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:38 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:38 volumio sudo[11263]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:38 volumio sudo[11263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:38 volumio sudo[11263]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:38 volumio go-librespot[1946]: time="2025-01-17T18:02:38+01:00" level=debug msg="fetched chunk 5/17, size: 524288" uri="spotify:track:5Y6nVaayzitvsD5F7nr3DV" Jan 17 18:02:39 volumio mpd[11265]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:39 volumio mpd[11265]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:39 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:39 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:39 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:39 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:39 volumio sudo[11282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:39 volumio sudo[11282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:39 volumio sudo[11282]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:40 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jan 17 18:02:40 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Jan 17 18:02:40 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:40 volumio mpd[11284]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:40 volumio mpd[11284]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:40 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:40 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:40 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:40 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:40 volumio mpd_monitor.sh[740]: MPD error: Invalid argument Jan 17 18:02:40 volumio mpd_monitor.sh[740]: mpd: no process found Jan 17 18:02:40 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:02:40 volumio systemd[1]: mpd.service: Succeeded. Jan 17 18:02:40 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 18:02:41 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:41 volumio sudo[11312]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:41 volumio sudo[11312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:41 volumio sudo[11312]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:42 volumio mpd[11314]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:42 volumio mpd[11314]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:42 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:42 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:42 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:42 volumio mpd_monitor.sh[740]: Job for mpd.service failed because the control process exited with error code. Jan 17 18:02:42 volumio mpd_monitor.sh[740]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:02:42 volumio mpd_monitor.sh[740]: MPD restarted due to no mpc output. Jan 17 18:02:42 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:42 volumio sudo[11316]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:42 volumio sudo[11316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:42 volumio sudo[11316]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:43 volumio mpd[11318]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:43 volumio mpd[11318]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:43 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:43 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:43 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:43 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:43 volumio sudo[11319]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:43 volumio sudo[11319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:43 volumio sudo[11319]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:44 volumio mpd[11321]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:44 volumio mpd[11321]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:44 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:44 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:44 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:44 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:44 volumio sudo[11322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:44 volumio sudo[11322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:44 volumio sudo[11322]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:45 volumio mpd[11324]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:45 volumio mpd[11324]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:45 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:45 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:45 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:45 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:45 volumio sudo[11325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:45 volumio sudo[11325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:45 volumio sudo[11325]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:47 volumio mpd[11327]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:47 volumio mpd[11327]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:47 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:47 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:47 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:47 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:47 volumio sudo[11328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:47 volumio sudo[11328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:47 volumio sudo[11328]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:48 volumio mpd[11330]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:48 volumio mpd[11330]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:48 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:48 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:48 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:48 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:48 volumio sudo[11345]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:48 volumio sudo[11345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:48 volumio sudo[11345]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:49 volumio mpd[11347]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:49 volumio mpd[11347]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:49 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:49 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:49 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:49 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:49 volumio volumio[1246]: info: CoreCommandRouter::volumioPause Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::pause Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::servicePause Jan 17 18:02:49 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:49 volumio volumio[1246]: info: CoreCommandRouter::servicePause Jan 17 18:02:49 volumio volumio[1246]: info: Spotify Received pause Jan 17 18:02:49 volumio volumio[1246]: SPOTIFY: SPOTIFY PAUSE Jan 17 18:02:49 volumio volumio[1246]: SPOTIFY: {"status":"play","position":4,"title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d0000b2731624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","codec":"ogg","seek":6000,"duration":256,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":true,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jan 17 18:02:49 volumio volumio[1246]: info: Sending Spotify command to local API: /player/pause Jan 17 18:02:49 volumio go-librespot[1946]: time="2025-01-17T18:02:49+01:00" level=debug msg="pause track at 39441ms" Jan 17 18:02:49 volumio sudo[11348]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:49 volumio sudo[11348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:49 volumio sudo[11348]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:49 volumio go-librespot[1946]: time="2025-01-17T18:02:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:49 volumio go-librespot[1946]: time="2025-01-17T18:02:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:02:49 volumio go-librespot[1946]: time="2025-01-17T18:02:49+01:00" level=trace msg="emitting websocket event: paused" Jan 17 18:02:49 volumio volumio[1246]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","play_origin":"go-librespot"}} Jan 17 18:02:49 volumio volumio[1246]: SPOTIFY: PUSH STATE SPOTIFY Jan 17 18:02:49 volumio volumio[1246]: SPOTIFY: {"status":"pause","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":38000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:49 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Jan 17 18:02:49 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:49 volumio volumio[1246]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"West Coast","artist":"Lana Del Rey","album":"Ultraviolence (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e021624590458126fc8b8c64c2f","uri":"spotify:track:5Y6nVaayzitvsD5F7nr3DV","trackType":"spotify","seek":38000,"duration":256,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 17 18:02:49 volumio volumio[1246]: verbose: CURRENT POSITION 4 Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::syncState stateService pause Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus pause Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::pushState Jan 17 18:02:49 volumio volumio[1246]: info: CorePlayQueue::getTrack 4 Jan 17 18:02:49 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 18:02:49 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Jan 17 18:02:49 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Jan 17 18:02:49 volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 18:02:49 volumio volumio[1246]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jan 17 18:02:49 volumio volumio[1246]: at Object.readdirSync (fs.js:1048:3) Jan 17 18:02:49 volumio volumio[1246]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jan 17 18:02:49 volumio volumio[1246]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jan 17 18:02:49 volumio volumio[1246]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jan 17 18:02:49 volumio volumio[1246]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jan 17 18:02:49 volumio volumio[1246]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jan 17 18:02:49 volumio volumio[1246]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 17 18:02:49 volumio volumio[1246]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jan 17 18:02:49 volumio volumio[1246]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jan 17 18:02:49 volumio volumio[1246]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jan 17 18:02:49 volumio volumio[1246]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 17 18:02:49 volumio volumio[1246]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jan 17 18:02:49 volumio volumio[1246]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jan 17 18:02:49 volumio volumio[1246]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jan 17 18:02:49 volumio volumio[1246]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jan 17 18:02:49 volumio volumio[1246]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jan 17 18:02:49 volumio volumio[1246]: errno: -20, Jan 17 18:02:49 volumio volumio[1246]: syscall: 'scandir', Jan 17 18:02:49 volumio volumio[1246]: code: 'ENOTDIR', Jan 17 18:02:49 volumio volumio[1246]: path: '/tmp/peppyrunning' Jan 17 18:02:49 volumio volumio[1246]: } Jan 17 18:02:49 volumio volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 18:02:50 volumio mpd[11350]: Jan 17 18:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 18:02:50 volumio mpd[11350]: Jan 17 18:02 : exception: output devices with identical names: mpd_peppyalsa Jan 17 18:02:50 volumio systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 17 18:02:50 volumio systemd[1]: mpd.service: Failed with result 'exit-code'. Jan 17 18:02:50 volumio systemd[1]: Failed to start Music Player Daemon. Jan 17 18:02:50 volumio systemd[1]: Starting Music Player Daemon... Jan 17 18:02:50 volumio sudo[11360]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:02:50 volumio sudo[11360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:02:50 volumio sudo[11360]: pam_unix(sudo:session): session closed for user root Jan 17 18:02:51 volumio sudo[11364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 18:01 Jan 17 18:02:51 volumio sudo[11364]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"