-- Logs begin at Fri 2025-06-06 17:25:38 CST, end at Fri 2025-06-06 19:00:29 CST. -- Jun 06 18:59:01 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5181. Jun 06 18:59:01 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:01 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:02 ft1905-febf volumio[25356]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:02 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:02 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:04 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5182. Jun 06 18:59:04 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:04 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:04 ft1905-febf volumio[25361]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:04 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:04 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:06 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5183. Jun 06 18:59:06 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:06 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:06 ft1905-febf volumio[25368]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:06 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:06 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:08 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5184. Jun 06 18:59:08 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:08 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:08 ft1905-febf volumio[25383]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:08 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:08 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:10 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5185. Jun 06 18:59:10 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:10 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:11 ft1905-febf volumio[25462]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:11 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:11 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:13 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5186. Jun 06 18:59:13 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:13 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:13 ft1905-febf volumio[25528]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:13 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:13 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:14 ft1905-febf sshd[25529]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 06 18:59:14 ft1905-febf sshd[25529]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 06 18:59:14 ft1905-febf sshd[25529]: rexec line 8: Deprecated option ServerKeyBits Jun 06 18:59:14 ft1905-febf sshd[25529]: rexec line 14: Deprecated option RSAAuthentication Jun 06 18:59:14 ft1905-febf sshd[25529]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 06 18:59:14 ft1905-febf sshd[25529]: reprocess config line 14: Deprecated option RSAAuthentication Jun 06 18:59:14 ft1905-febf sshd[25529]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 06 18:59:14 ft1905-febf sshd[25529]: Accepted password for volumio from 192.168.4.28 port 50244 ssh2 Jun 06 18:59:14 ft1905-febf sshd[25529]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 06 18:59:14 ft1905-febf systemd-logind[2242]: New session c17 of user volumio. Jun 06 18:59:14 ft1905-febf systemd[1]: Started Session c17 of user volumio. Jun 06 18:59:15 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5187. Jun 06 18:59:15 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:15 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:15 ft1905-febf volumio[25551]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:15 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:15 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::volumioReplaceAndPlayItems Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::ClearQueue Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::stop Jun 06 18:59:16 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:16 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 18:59:16 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:16 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: UNSET VOLATILE mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: SPOTIFY: UNSET VOLATILE Jun 06 18:59:16 ft1905-febf volumio[19431]: SPOTIFY: {"status":"play","title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","albumart":"/albumart","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"repeat":false,"consume":false,"volume":35,"dbVolume":null,"stream":false,"updatedb":false,"volatile":true,"service":"mpd"} Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Setting Spotify stop after unset volatile call Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::stPlaybackTimer Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::updateTrackBlock Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrackBlock Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::serviceStop Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::serviceStop trackBlock {"uri":"mnt/USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","service":"mpd","name":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=859&web=Alicia%20Keys/The%20Element%20Of%20Freedom%C2%A3%C2%A8Deluxe%20Edition%C2%A3%C2%A9/extralarge&path=%2Ftmp%2Falbumart%2Fmetadata%2Ff5ecca55a782461c66ca5e6f88940c5b&metadata=false","duration":217,"trackType":"mp3"} Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::serviceStop Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ControllerMpd::stop Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::clearPlayQueue Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::saveQueue Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::volumioPushQueue Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::addQueueItems Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::addQueueItems Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/林俊杰-飞鸟和蝉（咖啡调调）.mp3 in service mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/蝶恋花_32k.wav in service mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/Adele - Someone Like You.mp3 in service mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/Avril Lavigne - Innocence.mp3 in service mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3 in service mpd Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Exploding uri music-library/USB/udisk/LiSA - 炎.mp3 in service mpd Jun 06 18:59:16 ft1905-febf socketio[2420]: socket send Jun 06 18:59:16 ft1905-febf socketio[2420]: socket send Jun 06 18:59:16 ft1905-febf socketio[2420]: socket send Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'stop', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'lsinfo "USB/udisk/林俊杰-飞鸟和蝉（咖啡调调）.mp3"', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'lsinfo "USB/udisk/蝶恋花_32k.wav"', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { Jun 06 18:59:16 ft1905-febf volumio[19431]: name: 'lsinfo "USB/udisk/Adele - Someone Like You.mp3"', Jun 06 18:59:16 ft1905-febf volumio[19431]: args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { Jun 06 18:59:16 ft1905-febf volumio[19431]: name: 'lsinfo "USB/udisk/Avril Lavigne - Innocence.mp3"', Jun 06 18:59:16 ft1905-febf volumio[19431]: args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { Jun 06 18:59:16 ft1905-febf volumio[19431]: name: Jun 06 18:59:16 ft1905-febf volumio[19431]: 'lsinfo "USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3"', Jun 06 18:59:16 ft1905-febf volumio[19431]: args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'lsinfo "USB/udisk/LiSA - 炎.mp3"', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:16 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: Jun 06 18:59:16 ft1905-febf volumio[19431]: ---------------------------- MPD announces state update: player Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=//extralarge&path=%2Fmnt%2FUSB%2Fudisk%2F%E6%9E%97%E4%BF%8A%E6%9D%B0-%E9%A3%9E%E9%B8%9F%E5%92%8C%E8%9D%89%EF%BC%88%E5%92%96%E5%95%A1%E8%B0%83%E8%B0%83%EF%BC%89.mp3&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/林俊杰-飞鸟和蝉（咖啡调调）.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/林俊杰-飞鸟和蝉（咖啡调调）.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=//extralarge&path=%2Fmnt%2FUSB%2Fudisk%2F%E8%9D%B6%E6%81%8B%E8%8A%B1_32k.wav&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/蝶恋花_32k.wav Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/蝶恋花_32k.wav Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=Adele/21/extralarge&path=%2Fmnt%2FUSB%2Fudisk%2FAdele%20-%20Someone%20Like%20You.mp3&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/Adele - Someone Like You.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/Adele - Someone Like You.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=Avril%20Lavigne/The%20Best%20Damn%20Thing%2FUnder%20My%20Skin/extralarge&path=%2Fmnt%2FUSB%2Fudisk%2FAvril%20Lavigne%20-%20Innocence.mp3&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/Avril Lavigne - Innocence.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/Avril Lavigne - Innocence.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=Alicia%20Keys/The%20Element%20Of%20Freedom%C2%A3%C2%A8Deluxe%20Edition%C2%A3%C2%A9/extralarge&path=%2Fmnt%2FUSB%2Fudisk%2FAlicia%20Keys%20-%20Empire%20State%20Of%20Mind%20(Part%20II)%20(Bro.mp3&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ALBUMART /albumart?cacheid=859&web=LiSA%20(%E7%BB%87%E9%83%A8%E9%87%8C%E6%B2%99)/%E7%82%8E/extralarge&path=%2Fmnt%2FUSB%2Fudisk%2FLiSA%20-%20%E7%82%8E.mp3&metadata=false Jun 06 18:59:16 ft1905-febf volumio[19431]: info: URI /mnt/USB/udisk/LiSA - 炎.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: 提取封面： /mnt/USB/udisk/LiSA - 炎.mp3 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: sendMpdCommand stop took 316 milliseconds Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ControllerMpd::getState Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand status Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ControllerMpd::getState Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand status Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'status', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'status', args: [] } Jun 06 18:59:16 ft1905-febf volumio[19431]: info: sendMpdCommand status took 56 milliseconds Jun 06 18:59:16 ft1905-febf volumio[19431]: info: sendMpdCommand status took 47 milliseconds Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseState Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseState Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [ 0 ] } Jun 06 18:59:16 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [ 0 ] } Jun 06 18:59:16 ft1905-febf volumio[19431]: info: [pushCmd] {"desc":"changeSource","index":0} Jun 06 18:59:16 ft1905-febf socketio[2420]: socket send Jun 06 18:59:16 ft1905-febf volumio[19431]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 06 18:59:16 ft1905-febf volumio[19431]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseTrackInfo Jun 06 18:59:16 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseTrackInfo Jun 06 18:59:16 ft1905-febf volumio[19431]: info: ControllerMpd::pushState Jun 06 18:59:16 ft1905-febf volumio[19431]: this.isVolatile: false, stateService.status: stop Jun 06 18:59:16 ft1905-febf volumio[19431]: this.volatileState: {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd","stream":false,"albumart":"/albumart","repeatMode":"all"} Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:16 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd"} Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: CURRENT POSITION 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState stateService stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState currentStatus stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: No code Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: ControllerMpd::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: this.isVolatile: false, stateService.status: stop Jun 06 18:59:17 ft1905-febf volumio[19431]: this.volatileState: {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd","stream":false,"albumart":"/albumart","repeatMode":"all"} Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd"} Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: CURRENT POSITION 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState stateService stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState currentStatus stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: No code Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf volumio[19431]: info: ------------------------------ 306ms Jun 06 18:59:17 ft1905-febf volumio[19431]: info: Spotify Stop Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: SPOTIFY STOP Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":35,"dbVolume":null,"random":false,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Jun 06 18:59:17 ft1905-febf volumio[19431]: info: Sending Spotify command to local API: /player/pause Jun 06 18:59:17 ft1905-febf volumio[19431]: 提取封面图时出错: { type: 'tagFormat', info: 'No suitable tag reader found' } Jun 06 18:59:17 ft1905-febf sudo[25558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jun 06 18:59:17 ft1905-febf sshd[25546]: Received disconnect from 192.168.4.28 port 50244:11: Jun 06 18:59:17 ft1905-febf sshd[25546]: Disconnected from user volumio 192.168.4.28 port 50244 Jun 06 18:59:17 ft1905-febf sshd[25529]: pam_unix(sshd:session): session closed for user volumio Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:17 ft1905-febf go-librespot[19605]: time="2025-06-06T18:59:17+08:00" level=debug msg="pause track at 243686ms" Jun 06 18:59:17 ft1905-febf volumio[19431]: 未找到封面图 Jun 06 18:59:17 ft1905-febf sudo[25558]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::saveQueue Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::volumioPushQueue Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::updateTrackBlock Jun 06 18:59:17 ft1905-febf sshd[25561]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrackBlock Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::volumioPlay Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: UNSET VOLATILE undefined Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::play index 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf sshd[25561]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 06 18:59:17 ft1905-febf sshd[25561]: rexec line 8: Deprecated option ServerKeyBits Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::stop Jun 06 18:59:17 ft1905-febf sshd[25561]: rexec line 14: Deprecated option RSAAuthentication Jun 06 18:59:17 ft1905-febf sshd[25561]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::serviceStop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::serviceStop trackBlock {"uri":"mnt/USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","service":"mpd","name":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=859&web=Alicia%20Keys/The%20Element%20Of%20Freedom%C2%A3%C2%A8Deluxe%20Edition%C2%A3%C2%A9/extralarge&path=%2Ftmp%2Falbumart%2Fmetadata%2Ff5ecca55a782461c66ca5e6f88940c5b&metadata=false","duration":217,"trackType":"mp3"} Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::serviceStop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: ControllerMpd::stop Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'stop', args: [] } Jun 06 18:59:17 ft1905-febf volumio[19431]: info: sendMpdCommand stop took 20 milliseconds Jun 06 18:59:17 ft1905-febf volumio[19431]: info: ControllerMpd::getState Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand status Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'status', args: [] } Jun 06 18:59:17 ft1905-febf volumio[19431]: info: sendMpdCommand status took 13 milliseconds Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseState Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [ 0 ] } Jun 06 18:59:17 ft1905-febf volumio[19431]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseTrackInfo Jun 06 18:59:17 ft1905-febf volumio[19431]: info: ControllerMpd::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: this.isVolatile: false, stateService.status: stop Jun 06 18:59:17 ft1905-febf volumio[19431]: this.volatileState: {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd","stream":false,"albumart":"/albumart","repeatMode":"all"} Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf systemd[1]: session-c17.scope: Succeeded. Jun 06 18:59:17 ft1905-febf systemd-logind[2242]: Session c17 logged out. Waiting for processes to exit. Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd"} Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: CURRENT POSITION 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState stateService stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState currentStatus stop Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5188. Jun 06 18:59:17 ft1905-febf systemd-logind[2242]: Removed session c17. Jun 06 18:59:17 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:17 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:17 ft1905-febf volumio[19431]: info: No code Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[25572]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:17 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:17 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:17 ft1905-febf systemd[1]: Stopping go-librespot Daemon... Jun 06 18:59:17 ft1905-febf systemd[1]: go-librespot-daemon.service: Killing process 19611 (go-librespot) with signal SIGKILL. Jun 06 18:59:17 ft1905-febf systemd[1]: go-librespot-daemon.service: Succeeded. Jun 06 18:59:17 ft1905-febf systemd[1]: Stopped go-librespot Daemon. Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::play index undefined Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 18:59:17 ft1905-febf volumio[19431]: !! !! Jun 06 18:59:17 ft1905-febf volumio[19431]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CoreStateMachine::startPlaybackTimer Jun 06 18:59:17 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::clearAddPlayTracks USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3 Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 18:59:17 ft1905-febf sshd[25561]: reprocess config line 14: Deprecated option RSAAuthentication Jun 06 18:59:17 ft1905-febf socketio[2420]: socket send Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'stop', args: [] } Jun 06 18:59:17 ft1905-febf sshd[25561]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 06 18:59:17 ft1905-febf systemd[1]: Started go-librespot Daemon. Jun 06 18:59:17 ft1905-febf sudo[25558]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:17 ft1905-febf volumio[19431]: info: sendMpdCommand stop took 62 milliseconds Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand clear Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'clear', args: [] } Jun 06 18:59:17 ft1905-febf go-librespot[25573]: go-librespot daemon starting... Jun 06 18:59:17 ft1905-febf volumio[19431]: error: Failed to send command to Spotify local API: /player/pause: Error: socket hang up Jun 06 18:59:17 ft1905-febf volumio[19431]: info: Connection to go-librespot Websocket closed Jun 06 18:59:17 ft1905-febf volumio[19431]: info: Jun 06 18:59:17 ft1905-febf volumio[19431]: ---------------------------- MPD announces system playlist update Jun 06 18:59:17 ft1905-febf volumio[19431]: info: Ignoring MPD Status Update Jun 06 18:59:17 ft1905-febf volumio[19431]: info: sendMpdCommand clear took 65 milliseconds Jun 06 18:59:17 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand add "USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3" Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 06 18:59:17 ft1905-febf volumio[19431]: sendCommand: Command { Jun 06 18:59:17 ft1905-febf volumio[19431]: name: Jun 06 18:59:17 ft1905-febf volumio[19431]: 'add "USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3"', Jun 06 18:59:17 ft1905-febf volumio[19431]: args: [] } Jun 06 18:59:18 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:18 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:18 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:18 ft1905-febf volumio[19431]: error: updateQueue error: null Jun 06 18:59:18 ft1905-febf volumio[19431]: info: Jun 06 18:59:18 ft1905-febf volumio[19431]: ---------------------------- MPD announces system playlist update Jun 06 18:59:18 ft1905-febf volumio[19431]: info: Ignoring MPD Status Update Jun 06 18:59:18 ft1905-febf volumio[19431]: info: ------------------------------ 136ms Jun 06 18:59:18 ft1905-febf volumio[19431]: info: sendMpdCommand add "USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3" took 128 milliseconds Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand play Jun 06 18:59:18 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 06 18:59:18 ft1905-febf volumio[19431]: sendCommand: Command { name: 'play', args: [] } Jun 06 18:59:18 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 06 18:59:18 ft1905-febf volumio[19431]: info: Jun 06 18:59:18 ft1905-febf volumio[19431]: ---------------------------- MPD announces state update: player Jun 06 18:59:18 ft1905-febf volumio[19431]: info: ------------------------------ 44ms Jun 06 18:59:18 ft1905-febf volumio[19431]: info: sendMpdCommand play took 31 milliseconds Jun 06 18:59:18 ft1905-febf volumio[19431]: info: ControllerMpd::getState Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand status Jun 06 18:59:18 ft1905-febf volumio[19431]: sendCommand: Command { name: 'status', args: [] } Jun 06 18:59:18 ft1905-febf volumio[19431]: info: sendMpdCommand status took 18 milliseconds Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseState Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 18:59:18 ft1905-febf volumio[19431]: sendCommand: Command { name: 'playlistinfo', args: [ 0 ] } Jun 06 18:59:18 ft1905-febf volumio[19431]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: ControllerMpd::parseTrackInfo Jun 06 18:59:18 ft1905-febf volumio[19431]: info: ControllerMpd::pushState Jun 06 18:59:18 ft1905-febf volumio[19431]: this.isVolatile: false, stateService.status: play Jun 06 18:59:18 ft1905-febf volumio[19431]: this.volatileState: {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd","stream":false,"albumart":"/albumart","repeatMode":"all"} Jun 06 18:59:18 ft1905-febf volumio[19431]: set volatileService undefined Jun 06 18:59:18 ft1905-febf volumio[19431]: info: volatileService: undefined Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Empire State Of Mind (Part II) (Broken Down)","artist":"Alicia Keys","album":"The Element Of Freedom£¨Deluxe Edition£©","uri":"USB/udisk/Alicia Keys - Empire State Of Mind (Part II) (Bro.mp3","trackType":"mp3","service":"mpd"} Jun 06 18:59:18 ft1905-febf volumio[19431]: verbose: CURRENT POSITION 4 Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState stateService play Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CoreStateMachine::syncState currentStatus stop Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CoreStateMachine::pushState Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CorePlayQueue::getTrack 4 Jun 06 18:59:18 ft1905-febf volumio[19431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=info msg="running go-librespot 0.2.0" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=debug msg="app state loaded" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=debug msg="stored credentials not found" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 06 18:59:18 ft1905-febf socketio[2420]: socket send Jun 06 18:59:18 ft1905-febf volumio[19431]: info: ------------------------------ 159ms Jun 06 18:59:18 ft1905-febf volumio[19431]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 06 18:59:18 ft1905-febf sshd[25561]: Accepted password for volumio from 192.168.4.28 port 50254 ssh2 Jun 06 18:59:18 ft1905-febf sshd[25561]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 06 18:59:18 ft1905-febf systemd-logind[2242]: New session c18 of user volumio. Jun 06 18:59:18 ft1905-febf systemd[1]: Started Session c18 of user volumio. Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 06 18:59:18 ft1905-febf go-librespot[25576]: time="2025-06-06T18:59:18+08:00" level=info msg="zeroconf server listening on port 39733" Jun 06 18:59:19 ft1905-febf sudo[25612]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop volumio.service Jun 06 18:59:19 ft1905-febf sudo[25612]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:19 ft1905-febf systemd[1]: Stopping Volumio Backend Module... Jun 06 18:59:19 ft1905-febf socketio[2420]: [2025-06-06 18:59:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 06 18:59:19 ft1905-febf socketio[2420]: [2025-06-06 18:59:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 06 18:59:19 ft1905-febf systemd[1]: volumio.service: Succeeded. Jun 06 18:59:19 ft1905-febf systemd[1]: Stopped Volumio Backend Module. Jun 06 18:59:19 ft1905-febf sudo[25612]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:19 ft1905-febf sudo[25621]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start volumio.service Jun 06 18:59:19 ft1905-febf sudo[25621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:19 ft1905-febf systemd[1]: Started dynamicswap service. Jun 06 18:59:19 ft1905-febf systemd[1]: Started Volumio Backend Module. Jun 06 18:59:19 ft1905-febf sudo[25621]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:19 ft1905-febf sshd[25606]: Received disconnect from 192.168.4.28 port 50254:11: Jun 06 18:59:19 ft1905-febf systemd[1]: dynamicswap.service: Succeeded. Jun 06 18:59:19 ft1905-febf sshd[25606]: Disconnected from user volumio 192.168.4.28 port 50254 Jun 06 18:59:19 ft1905-febf sshd[25561]: pam_unix(sshd:session): session closed for user volumio Jun 06 18:59:19 ft1905-febf systemd[1]: session-c18.scope: Succeeded. Jun 06 18:59:19 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5189. Jun 06 18:59:19 ft1905-febf systemd-logind[2242]: Session c18 logged out. Waiting for processes to exit. Jun 06 18:59:19 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:19 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:19 ft1905-febf systemd-logind[2242]: Removed session c18. Jun 06 18:59:19 ft1905-febf volumio[25647]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:19 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:19 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:21 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5190. Jun 06 18:59:21 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:21 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:22 ft1905-febf volumio[25649]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:22 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:22 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:23 ft1905-febf volumio[25628]: info: ------------------------------------------- Jun 06 18:59:23 ft1905-febf volumio[25628]: info: ----- Volumio2 ---- Jun 06 18:59:23 ft1905-febf volumio[25628]: info: ------------------------------------------- Jun 06 18:59:23 ft1905-febf volumio[25628]: info: ----- System startup ---- Jun 06 18:59:23 ft1905-febf volumio[25628]: info: ------------------------------------------- Jun 06 18:59:24 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5191. Jun 06 18:59:24 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:24 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:24 ft1905-febf volumio[25662]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:24 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:24 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:24 ft1905-febf socketio[2420]: [2025-06-06 18:59:24] [connect] Successful connection Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Found new core plugin music_service/cdplayer. Adding it Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Plugin folders cleanup Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning into folder /volumio/app/plugins/ Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category audio_interface Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category miscellanea Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category music_service Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category plugins.json Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category system_controller Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning category user_interface Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Scanning into folder /data/plugins/ Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Plugin folders cleanup completed Jun 06 18:59:25 ft1905-febf volumio[25628]: info: ------------------------------------------- Jun 06 18:59:25 ft1905-febf volumio[25628]: info: ----- Core plugins startup ---- Jun 06 18:59:25 ft1905-febf volumio[25628]: info: ------------------------------------------- Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Loading plugins from folder /volumio/app/plugins/ Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Adding plugin upnp to MyMusic Plugins Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Loading plugins from folder /data/plugins/ Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Loading plugin "system"... Jun 06 18:59:25 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jun 06 18:59:25 ft1905-febf volumio[25628]: info: Loading plugin "appearance"... Jun 06 18:59:25 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "network"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Refreshing Cached IP Addresses Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "services"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "alsa_controller"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Applying required configuration parameters for plugin alsa_controller Jun 06 18:59:26 ft1905-febf sudo[25666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 18:59:26 ft1905-febf sudo[25668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 18:59:26 ft1905-febf sudo[25666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:26 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5192. Jun 06 18:59:26 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:26 ft1905-febf sudo[25668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:26 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:26 ft1905-febf sudo[25668]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:26 ft1905-febf sudo[25666]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:26 ft1905-febf volumio[25676]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:26 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:26 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "wizard"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "volumio_command_line_client"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jun 06 18:59:26 ft1905-febf volumio[25628]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jun 06 18:59:26 ft1905-febf volumio[25628]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jun 06 18:59:26 ft1905-febf volumio[25628]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jun 06 18:59:26 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "upnp"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: [1749207566919] Starting Upmpd Daemon Jun 06 18:59:26 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "my_music"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jun 06 18:59:26 ft1905-febf volumio[25628]: info: Loading plugin "mpd"... Jun 06 18:59:26 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jun 06 18:59:27 ft1905-febf volumio[25628]: info: Creating MPD Configuration file Jun 06 18:59:27 ft1905-febf volumio[25628]: info: Loading plugin "upnp_browser"... Jun 06 18:59:27 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jun 06 18:59:27 ft1905-febf sudo[25696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 18:59:27 ft1905-febf sudo[25698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 18:59:27 ft1905-febf sudo[25696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:27 ft1905-febf sudo[25698]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:28 ft1905-febf sudo[25696]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:28 ft1905-febf systemd[1]: Stopping Music Player Daemon... Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Loading plugin "networkfs"... Jun 06 18:59:28 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jun 06 18:59:28 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Loading plugin "alarm-clock"... Jun 06 18:59:28 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jun 06 18:59:28 ft1905-febf systemd[1]: mpd.service: Succeeded. Jun 06 18:59:28 ft1905-febf systemd[1]: Stopped Music Player Daemon. Jun 06 18:59:28 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5193. Jun 06 18:59:28 ft1905-febf systemd[1]: Starting Music Player Daemon... Jun 06 18:59:28 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:28 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:28 ft1905-febf volumio[25715]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:28 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:28 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Loading plugin "airplay_emulation"... Jun 06 18:59:28 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jun 06 18:59:28 ft1905-febf volumio[25628]: Shairport Sync D-Bus 服务已注册。 Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Starting Shairport Sync Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Loading plugin "last_100"... Jun 06 18:59:28 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jun 06 18:59:28 ft1905-febf volumio[25628]: info: Loading plugin "spop"... Jun 06 18:59:28 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jun 06 18:59:29 ft1905-febf volumio[25628]: info: Loading plugin "webradio"... Jun 06 18:59:29 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jun 06 18:59:30 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5194. Jun 06 18:59:30 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:31 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:31 ft1905-febf volumio[25720]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:31 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:31 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "i2s_dacs"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jun 06 18:59:32 ft1905-febf mpd[25714]: Jun 06 18:59 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 18:59:32 ft1905-febf mpd[25714]: Jun 06 18:59 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 06 18:59:32 ft1905-febf mpd[25714]: Jun 06 18:59 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 06 18:59:32 ft1905-febf volumio[25628]: info: I2S DAC not set, start Auto-detection Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "volumiodiscovery"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** For more information see Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 18:59:32 ft1905-febf volumio[25628]: *** WARNING *** For more information see Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** For more information see Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 18:59:32 ft1905-febf node[25628]: *** WARNING *** For more information see Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Discovery: Started advertising with name: FT1905-febf Jun 06 18:59:32 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "outputs"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "albumart"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Plugin example_plugin is not enabled Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "inputs"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jun 06 18:59:32 ft1905-febf volumio[25628]: info: Loading plugin "pandora"... Jun 06 18:59:32 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jun 06 18:59:33 ft1905-febf volumio[25628]: info: Applying required configuration parameters for plugin pandora Jun 06 18:59:33 ft1905-febf volumio[25628]: info: Loading plugin "podcast"... Jun 06 18:59:33 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jun 06 18:59:33 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5195. Jun 06 18:59:33 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:33 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:33 ft1905-febf volumio[25729]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:33 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:33 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:33 ft1905-febf volumio[25628]: info: ControllerPodcast::constructor Jun 06 18:59:33 ft1905-febf volumio[25628]: info: Loading plugin "qobuz"... Jun 06 18:59:33 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jun 06 18:59:33 ft1905-febf volumio[25628]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jun 06 18:59:33 ft1905-febf volumio[25628]: info: Loading plugin "tidal"... Jun 06 18:59:33 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jun 06 18:59:34 ft1905-febf volumio[25628]: info: [1749207574359] ControllerTidalPlugin::constructor Jun 06 18:59:34 ft1905-febf volumio[25628]: info: [1749207574362] ControllerTidalPlugin::onVolumioStart Jun 06 18:59:34 ft1905-febf volumio[25628]: info: Loading plugin "updater_comm"... Jun 06 18:59:34 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jun 06 18:59:34 ft1905-febf volumio[25628]: info: Plugin mpdemulation is not enabled Jun 06 18:59:34 ft1905-febf volumio[25628]: info: Loading plugin "rest_api"... Jun 06 18:59:34 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jun 06 18:59:34 ft1905-febf volumio[25628]: info: Loading plugin "websocket"... Jun 06 18:59:34 ft1905-febf volumio[25628]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jun 06 18:59:34 ft1905-febf volumio[25628]: info: ___________ START PLUGINS ___________ Jun 06 18:59:34 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 18:59:34 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:34 ft1905-febf volumio[25628]: info: [1749207574890] CoreMusicLibrary::Adding element Media Servers Jun 06 18:59:34 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:34 ft1905-febf volumio[25628]: SSDP Client initialized. Jun 06 18:59:34 ft1905-febf volumio[25628]: Starting initial SSDP search... Jun 06 18:59:34 ft1905-febf volumio[25628]: Discovering on interface: lo Jun 06 18:59:34 ft1905-febf volumio[25628]: Discovering on interface: eth0 Jun 06 18:59:34 ft1905-febf volumio[25628]: Discovering on interface: wlan0 Jun 06 18:59:34 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575077] CoreMusicLibrary::Adding element Last_100 Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: Creating Spotify config file Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575165] CoreMusicLibrary::Adding element Webradio Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575238] CoreMusicLibrary::Adding element Pandora Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575255] ControllerPandora::initializeMQTT Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575259] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575303] CoreMusicLibrary::Adding element Podcast Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575322] CoreMusicLibrary::Adding element Qobuz Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575359] ControllerTidalPlugin::onStart Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575379] ControllerTidalPlugin::addToBrowseSources Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 18:59:35 ft1905-febf volumio[25628]: info: [1749207575383] CoreMusicLibrary::Adding element Tidal Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5196. Jun 06 18:59:35 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:35 ft1905-febf volumio[25628]: info: Loading i18n strings for locale en Jun 06 18:59:35 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:35 ft1905-febf volumio[25628]: Updating browse sources language Jun 06 18:59:35 ft1905-febf volumio[25628]: Cannot find translation for sourceMedia Servers Jun 06 18:59:35 ft1905-febf volumio[25628]: Cannot find translation for sourcePodcast Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:35 ft1905-febf volumio[25735]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:35 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:35 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::initPlayerControls Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 18:59:35 ft1905-febf volumio[25628]: info: BOOT COMPLETED Jun 06 18:59:35 ft1905-febf volumio[25628]: [Metrics] CommandRouter: 11s 932.56ms Jun 06 18:59:35 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 18:59:35 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 06 18:59:36 ft1905-febf socketio[2420]: [2025-06-06 18:59:36] [connect] Successful connection Jun 06 18:59:37 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5197. Jun 06 18:59:37 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:37 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:37 ft1905-febf volumio[25743]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:37 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:37 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:39 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5198. Jun 06 18:59:39 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:40 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:40 ft1905-febf volumio[25748]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:40 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:40 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:41 ft1905-febf volumio[25628]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreCommandRouter::Close All Modals sent Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreCommandRouter::Close All Modals sent Jun 06 18:59:41 ft1905-febf volumio[25628]: Express server listening on port 3000 Jun 06 18:59:41 ft1905-febf volumio[25628]: [Metrics] WebUI: 18s 34.47ms Jun 06 18:59:41 ft1905-febf volumio[25628]: (node:25628) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 06 18:59:41 ft1905-febf volumio[25628]: info: [1749207581350] ControllerPandora::validateAndSetAccountOptions Jun 06 18:59:41 ft1905-febf volumio[25628]: error: [1749207581354] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreStateMachine::resetVolumioState Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreStateMachine::getcurrentVolume Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 18:59:41 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 18:59:41 ft1905-febf volumio[25628]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 06 18:59:41 ft1905-febf sudo[25759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 18:59:41 ft1905-febf sudo[25761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 18:59:42 ft1905-febf sudo[25759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:42 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:42 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 18:59:42 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 18:59:42 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 18:59:42 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 18:59:42 ft1905-febf volumio[25628]: USN: Jun 06 18:59:42 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: EXT: '', Jun 06 18:59:42 ft1905-febf volumio[25628]: DATE: '2025-06-06T10:59:41.329931Z' } Jun 06 18:59:42 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:42 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:42 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 18:59:42 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 18:59:42 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 18:59:42 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 18:59:42 ft1905-febf volumio[25628]: USN: Jun 06 18:59:42 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: EXT: '', Jun 06 18:59:42 ft1905-febf volumio[25628]: DATE: '2025-06-06T10:59:41.634241Z' } Jun 06 18:59:42 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:42 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:42 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 18:59:42 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 18:59:42 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 18:59:42 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 18:59:42 ft1905-febf volumio[25628]: USN: Jun 06 18:59:42 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: EXT: '', Jun 06 18:59:42 ft1905-febf volumio[25628]: DATE: '2025-06-06T10:59:41.432001Z' } Jun 06 18:59:42 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:42 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:42 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 18:59:42 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 18:59:42 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 18:59:42 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 18:59:42 ft1905-febf volumio[25628]: USN: Jun 06 18:59:42 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546781', Jun 06 18:59:42 ft1905-febf volumio[25628]: EXT: '', Jun 06 18:59:42 ft1905-febf volumio[25628]: DATE: '2025-06-06T10:59:41.736054Z' } Jun 06 18:59:42 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:42 ft1905-febf sudo[25761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:42 ft1905-febf sudo[25759]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:42 ft1905-febf sudo[25761]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:42 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5199. Jun 06 18:59:42 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:42 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:42 ft1905-febf volumio[25775]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:42 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:42 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:42 ft1905-febf volumio[25628]: info: this.stateMachine.currentVolume: null, vol: 0 Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreStateMachine::pushState Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreStateMachine::updateTrackBlock Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CorePlayQueue::getTrackBlock Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 18:59:42 ft1905-febf volumio[25628]: info: MPD Permissions set Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 06 18:59:42 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 06 18:59:43 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 18:59:43 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:43 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 18:59:43 ft1905-febf volumio[25628]: USN: Jun 06 18:59:43 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:43 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:43 ft1905-febf volumio[25628]: LOCATION: Jun 06 18:59:43 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 18:59:43 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:43 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:43 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:43 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:43 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:44 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:44 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:44 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:44 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:44 ft1905-febf volumio[25628]: info: Spotify config file written Jun 06 18:59:44 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 18:59:44 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:44 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 18:59:44 ft1905-febf volumio[25628]: USN: Jun 06 18:59:44 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:44 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:44 ft1905-febf volumio[25628]: LOCATION: Jun 06 18:59:44 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 18:59:44 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:44 ft1905-febf sudo[25798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jun 06 18:59:44 ft1905-febf sudo[25798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:44 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5200. Jun 06 18:59:44 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:44 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:44 ft1905-febf volumio[25803]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:44 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:44 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:44 ft1905-febf systemd[1]: Stopping go-librespot Daemon... Jun 06 18:59:44 ft1905-febf systemd[1]: go-librespot-daemon.service: Killing process 25586 (go-librespot) with signal SIGKILL. Jun 06 18:59:44 ft1905-febf systemd[1]: go-librespot-daemon.service: Succeeded. Jun 06 18:59:44 ft1905-febf systemd[1]: Stopped go-librespot Daemon. Jun 06 18:59:44 ft1905-febf systemd[1]: Started go-librespot Daemon. Jun 06 18:59:44 ft1905-febf sudo[25798]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:44 ft1905-febf go-librespot[25805]: go-librespot daemon starting... Jun 06 18:59:45 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 18:59:45 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:45 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 18:59:45 ft1905-febf volumio[25628]: USN: Jun 06 18:59:45 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:45 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:45 ft1905-febf volumio[25628]: LOCATION: Jun 06 18:59:45 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 18:59:45 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:45 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 18:59:45 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:45 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 18:59:45 ft1905-febf volumio[25628]: USN: Jun 06 18:59:45 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 18:59:45 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 18:59:45 ft1905-febf volumio[25628]: LOCATION: Jun 06 18:59:45 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 18:59:45 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 18:59:45 ft1905-febf volumio[25785]: Forking 1 albumart workers Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:45 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:45 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:45+08:00" level=info msg="running go-librespot 0.2.0" Jun 06 18:59:45 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:45+08:00" level=debug msg="app state loaded" Jun 06 18:59:45 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:45+08:00" level=debug msg="stored credentials not found" Jun 06 18:59:45 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:45+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:45 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:45 ft1905-febf volumio[25628]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jun 06 18:59:45 ft1905-febf volumio[25628]: sudo: /usr/sbin/i2cdetect: command not found Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 06 18:59:45 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 18:59:46 ft1905-febf volumio[25628]: info: No need to fix Spotify hosts Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreStateMachine::setRepeat null single undefined Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreStateMachine::pushState Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreStateMachine::setRandom false Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreStateMachine::pushState Jun 06 18:59:46 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 18:59:46 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5201. Jun 06 18:59:46 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:46 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:47 ft1905-febf volumio[25827]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:47 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:47 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:47 ft1905-febf volumio[25814]: Starting albumart workers Jun 06 18:59:48 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:48 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:48 ft1905-febf volumio[25628]: info: Starting Shairport Sync Jun 06 18:59:48 ft1905-febf sudo[25830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 06 18:59:48 ft1905-febf sudo[25830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:48 ft1905-febf sudo[25832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 18:59:48 ft1905-febf sudo[25832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 18:59:48 ft1905-febf sudo[25830]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:49 ft1905-febf systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 06 18:59:49 ft1905-febf systemd[1]: shairport-sync.service: Succeeded. Jun 06 18:59:49 ft1905-febf systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 06 18:59:49 ft1905-febf systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 06 18:59:49 ft1905-febf sudo[25832]: pam_unix(sudo:session): session closed for user root Jun 06 18:59:49 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5202. Jun 06 18:59:49 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:49 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:49 ft1905-febf volumio[25851]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:49 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:49 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:49 ft1905-febf volumio[25628]: info: Upmpdcli Daemon Started Jun 06 18:59:49 ft1905-febf volumio[25628]: info: Shairport-Sync Started Jun 06 18:59:49 ft1905-febf volumio[25628]: Error adding Membership: Error: addMembership EINVAL Jun 06 18:59:49 ft1905-febf volumio[25628]: info: go-librespot daemon successfully initialized Jun 06 18:59:51 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:51+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 06 18:59:51 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:51+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 06 18:59:51 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:51+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 06 18:59:51 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:51+08:00" level=info msg="zeroconf server listening on port 33351" Jun 06 18:59:51 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5203. Jun 06 18:59:51 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:51 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:51 ft1905-febf volumio[25876]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:51 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:51 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:52 ft1905-febf volumio[25628]: Discovery: adding 1262c1f4-267b-4e25-a601-bdb1d988c032 Jun 06 18:59:52 ft1905-febf volumio[25628]: info: mDNS: Found device FT1905-c7dc Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: Discovery: adding 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 06 18:59:52 ft1905-febf volumio[25628]: info: mDNS: Found device FT1905-febf Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: Discovery: this is already registered, 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 06 18:59:52 ft1905-febf volumio[25628]: info: mDNS: Found device FT1905-febf Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: Discovery: this is already registered, 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 06 18:59:52 ft1905-febf volumio[25628]: info: mDNS: Found device FT1905-febf Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:52 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioGetVisibleSources Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 18:59:52 ft1905-febf volumio[25628]: info: [pushCmd] {"desc":"getToneInfo","index":5,"getinfo":4} Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: error: Could not retrieve plugin audio_interface multiroom Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioGetQueue Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CoreStateMachine::getQueue Jun 06 18:59:52 ft1905-febf volumio[25628]: info: CorePlayQueue::getQueue Jun 06 18:59:52 ft1905-febf volumio[25628]: info: Listing playlists Jun 06 18:59:52 ft1905-febf volumio[25628]: info: Initializing connection to go-librespot Websocket Jun 06 18:59:53 ft1905-febf go-librespot[25807]: time="2025-06-06T18:59:53+08:00" level=debug msg="new websocket client" Jun 06 18:59:53 ft1905-febf socketio[2420]: [2025-06-06 18:59:53] [connect] Successful connection Jun 06 18:59:53 ft1905-febf volumio[25628]: info: Connection to go-librespot Websocket established Jun 06 18:59:53 ft1905-febf socketio[2420]: [2025-06-06 18:59:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749207593 101 Jun 06 18:59:53 ft1905-febf socketio[2420]: sio connected Jun 06 18:59:53 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:53 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:53 ft1905-febf socketio[2420]: socket send Jun 06 18:59:53 ft1905-febf socketio[2420]: command_len 356 Jun 06 18:59:53 ft1905-febf socketio[2420]: pushSrctotal Jun 06 18:59:53 ft1905-febf socketio[2420]: { Jun 06 18:59:53 ft1905-febf socketio[2420]: "total": 7, Jun 06 18:59:53 ft1905-febf socketio[2420]: "cur": 0, Jun 06 18:59:53 ft1905-febf socketio[2420]: "source": [{ Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 0, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "Media Player" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 4, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "Bluetooth" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 6, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "AUX" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "DIGITAL OPT1" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "DIGITAL OPT2" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "HDMI ARC" Jun 06 18:59:53 ft1905-febf socketio[2420]: }, { Jun 06 18:59:53 ft1905-febf socketio[2420]: "type": 0, Jun 06 18:59:53 ft1905-febf socketio[2420]: "name": "USB" Jun 06 18:59:53 ft1905-febf socketio[2420]: }] Jun 06 18:59:53 ft1905-febf socketio[2420]: } Jun 06 18:59:53 ft1905-febf volumio[25628]: info: [pushSrctotal] {"cur":0,"source":[{"name":"Media Player","type":0},{"name":"Bluetooth","type":4},{"name":"AUX","type":6},{"name":"DIGITAL OPT1","type":5},{"name":"DIGITAL OPT2","type":5},{"name":"HDMI ARC","type":5},{"name":"USB","type":0}],"total":7} Jun 06 18:59:53 ft1905-febf volumio[25628]: info: [pushCmd] {"desc":"getSourceInfo","index":5,"getinfo":1} Jun 06 18:59:53 ft1905-febf socketio[2420]: socket send Jun 06 18:59:53 ft1905-febf socketio[2420]: command_len 31 Jun 06 18:59:53 ft1905-febf socketio[2420]: pushSourceInfo Jun 06 18:59:53 ft1905-febf socketio[2420]: { Jun 06 18:59:53 ft1905-febf socketio[2420]: "source": 0 Jun 06 18:59:53 ft1905-febf socketio[2420]: } Jun 06 18:59:53 ft1905-febf volumio[25628]: info: [pushSourceInfo] {"source":0} Jun 06 18:59:53 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5204. Jun 06 18:59:53 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:53 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:53 ft1905-febf volumio[25885]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:53 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:53 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:55 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5205. Jun 06 18:59:55 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:55 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:56 ft1905-febf volumio[25892]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:56 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:56 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 18:59:56 ft1905-febf volumio[25628]: info: Getting Spotify volume Jun 06 18:59:56 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 18:59:56 ft1905-febf volumio[25628]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 18:59:56 ft1905-febf socketio[2420]: socket send Jun 06 18:59:56 ft1905-febf socketio[2420]: command_len 356 Jun 06 18:59:56 ft1905-febf socketio[2420]: pushSrctotal Jun 06 18:59:56 ft1905-febf socketio[2420]: { Jun 06 18:59:56 ft1905-febf socketio[2420]: "total": 7, Jun 06 18:59:56 ft1905-febf socketio[2420]: "cur": 0, Jun 06 18:59:56 ft1905-febf socketio[2420]: "source": [{ Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 0, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "Media Player" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 4, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "Bluetooth" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 6, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "AUX" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "DIGITAL OPT1" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "DIGITAL OPT2" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 5, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "HDMI ARC" Jun 06 18:59:56 ft1905-febf socketio[2420]: }, { Jun 06 18:59:56 ft1905-febf socketio[2420]: "type": 0, Jun 06 18:59:56 ft1905-febf socketio[2420]: "name": "USB" Jun 06 18:59:56 ft1905-febf socketio[2420]: }] Jun 06 18:59:56 ft1905-febf socketio[2420]: } Jun 06 18:59:56 ft1905-febf volumio[25628]: info: [pushSrctotal] {"cur":0,"source":[{"name":"Media Player","type":0},{"name":"Bluetooth","type":4},{"name":"AUX","type":6},{"name":"DIGITAL OPT1","type":5},{"name":"DIGITAL OPT2","type":5},{"name":"HDMI ARC","type":5},{"name":"USB","type":0}],"total":7} Jun 06 18:59:56 ft1905-febf volumio[25628]: info: [pushCmd] {"desc":"getSourceInfo","index":5,"getinfo":1} Jun 06 18:59:56 ft1905-febf socketio[2420]: socket send Jun 06 18:59:56 ft1905-febf socketio[2420]: command_len 31 Jun 06 18:59:56 ft1905-febf socketio[2420]: pushSourceInfo Jun 06 18:59:56 ft1905-febf socketio[2420]: { Jun 06 18:59:56 ft1905-febf socketio[2420]: "source": 0 Jun 06 18:59:56 ft1905-febf socketio[2420]: } Jun 06 18:59:56 ft1905-febf volumio[25628]: info: [pushSourceInfo] {"source":0} Jun 06 18:59:58 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5206. Jun 06 18:59:58 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 18:59:58 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 18:59:58 ft1905-febf volumio[25893]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 18:59:58 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 18:59:58 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:00 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5207. Jun 06 19:00:00 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:00 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:00 ft1905-febf volumio[25894]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:00 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:00 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:02 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5208. Jun 06 19:00:02 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:02 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:02 ft1905-febf volumio[25895]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:02 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:02 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:04 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5209. Jun 06 19:00:04 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:04 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:04 ft1905-febf volumio[25897]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:04 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:04 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:05 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 19:00:05 ft1905-febf volumio[25628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 19:00:07 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5210. Jun 06 19:00:07 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:07 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:07 ft1905-febf volumio[25900]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:07 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:07 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:09 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5211. Jun 06 19:00:09 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:09 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:09 ft1905-febf volumio[25903]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:09 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:09 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:11 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5212. Jun 06 19:00:11 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:11 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:11 ft1905-febf volumio[25913]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:11 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:11 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:13 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5213. Jun 06 19:00:13 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:13 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:14 ft1905-febf volumio[26047]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:14 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:14 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:16 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5214. Jun 06 19:00:16 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:16 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:16 ft1905-febf volumio[26048]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:16 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:16 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:18 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5215. Jun 06 19:00:18 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:18 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:18 ft1905-febf volumio[26049]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:18 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:18 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:20 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5216. Jun 06 19:00:20 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:20 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:20 ft1905-febf volumio[26050]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:20 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:20 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:22 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5217. Jun 06 19:00:22 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:22 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:23 ft1905-febf volumio[26051]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:23 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:23 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:25 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 19:00:25 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 19:00:25 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 19:00:25 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 19:00:25 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 19:00:25 ft1905-febf volumio[25628]: USN: Jun 06 19:00:25 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546824', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546824', Jun 06 19:00:25 ft1905-febf volumio[25628]: EXT: '', Jun 06 19:00:25 ft1905-febf volumio[25628]: DATE: '2025-06-06T11:00:24.852945Z' } Jun 06 19:00:25 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 19:00:25 ft1905-febf volumio[25628]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 19:00:25 ft1905-febf volumio[25628]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 19:00:25 ft1905-febf volumio[25628]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 19:00:25 ft1905-febf volumio[25628]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: NTS: 'ssdp:alive', Jun 06 19:00:25 ft1905-febf volumio[25628]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 19:00:25 ft1905-febf volumio[25628]: USN: Jun 06 19:00:25 ft1905-febf volumio[25628]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'BOOTID.UPNP.ORG': '13546824', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'CONFIGID.UPNP.ORG': '13546824', Jun 06 19:00:25 ft1905-febf volumio[25628]: EXT: '', Jun 06 19:00:25 ft1905-febf volumio[25628]: DATE: '2025-06-06T11:00:24.851794Z' } Jun 06 19:00:25 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 19:00:25 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5218. Jun 06 19:00:25 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:25 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:25 ft1905-febf volumio[26060]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:25 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:25 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:25 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 19:00:25 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 19:00:25 ft1905-febf volumio[25628]: USN: Jun 06 19:00:25 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:25 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 19:00:25 ft1905-febf volumio[25628]: LOCATION: Jun 06 19:00:25 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 19:00:25 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 19:00:26 ft1905-febf volumio[25628]: Device response: { EXT: '', Jun 06 19:00:26 ft1905-febf volumio[25628]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:26 ft1905-febf volumio[25628]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 19:00:26 ft1905-febf volumio[25628]: USN: Jun 06 19:00:26 ft1905-febf volumio[25628]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 19:00:26 ft1905-febf volumio[25628]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 19:00:26 ft1905-febf volumio[25628]: LOCATION: Jun 06 19:00:26 ft1905-febf volumio[25628]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 19:00:26 ft1905-febf volumio[25628]: Device IP: 192.168.4.85 Jun 06 19:00:26 ft1905-febf volumio[25628]: info: CoreCommandRouter::volumioReplaceAndPlayItems Jun 06 19:00:26 ft1905-febf volumio[25628]: info: CoreStateMachine::ClearQueue Jun 06 19:00:26 ft1905-febf volumio[25628]: info: CoreStateMachine::stop Jun 06 19:00:26 ft1905-febf volumio[25628]: info: CoreStateMachine::serviceStop Jun 06 19:00:26 ft1905-febf volumio[25628]: info: CoreStateMachine::serviceStop trackBlock undefined Jun 06 19:00:26 ft1905-febf volumio[25628]: info: ControllerMpd::stop Jun 06 19:00:26 ft1905-febf volumio[25628]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 19:00:26 ft1905-febf volumio[25628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 19:00:26 ft1905-febf volumio[25628]: TypeError: Cannot read property 'then' of undefined Jun 06 19:00:26 ft1905-febf volumio[25628]: at ControllerMpd.sendMpdCommand (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:324:6) Jun 06 19:00:26 ft1905-febf volumio[25628]: at ControllerMpd.stop (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:2749:15) Jun 06 19:00:26 ft1905-febf volumio[25628]: at CoreStateMachine.serviceStop (webpack://Volumio2/./app/statemachine.js?:1235:24) Jun 06 19:00:26 ft1905-febf volumio[25628]: at CoreStateMachine.stop (webpack://Volumio2/./app/statemachine.js?:1215:17) Jun 06 19:00:26 ft1905-febf volumio[25628]: at CoreStateMachine.clearQueue (webpack://Volumio2/./app/statemachine.js?:250:8) Jun 06 19:00:26 ft1905-febf volumio[25628]: at CoreCommandRouter.replaceAndPlay (webpack://Volumio2/./app/index.js?:491:21) Jun 06 19:00:26 ft1905-febf volumio[25628]: at CoreCommandRouter.playItemsList (webpack://Volumio2/./app/index.js?:481:17) Jun 06 19:00:26 ft1905-febf volumio[25628]: at Socket.eval (webpack://Volumio2/./app/plugins/user_interface/websocket/index.js?:111:35) Jun 06 19:00:26 ft1905-febf volumio[25628]: at Socket.emit (events.js:198:13) Jun 06 19:00:26 ft1905-febf volumio[25628]: at eval (webpack://Volumio2/./node_modules/socket.io/lib/socket.js?:503:12) Jun 06 19:00:26 ft1905-febf volumio[25628]: at process._tickCallback (internal/process/next_tick.js:61:11) Jun 06 19:00:26 ft1905-febf volumio[25628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 19:00:27 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 5219. Jun 06 19:00:27 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 19:00:27 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 19:00:27 ft1905-febf volumio[26069]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 19:00:27 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 19:00:27 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 19:00:29 ft1905-febf sudo[26071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-06 18:59 Jun 06 19:00:29 ft1905-febf sudo[26071]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.3.0.152 VOLUMIO_BUILD_DATE=2025-05-26 19:03:27 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system