-- Logs begin at Fri 2025-06-06 12:22:18 CST, end at Fri 2025-06-06 14:15:34 CST. -- Jun 06 14:14:02 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4380. Jun 06 14:14:02 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:02 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:02 ft1905-febf volumio[29619]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:02 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:02 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:04 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4381. Jun 06 14:14:04 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:04 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:04 ft1905-febf volumio[29625]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:04 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:04 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:06 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4382. Jun 06 14:14:06 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:06 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:06 ft1905-febf volumio[29630]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:06 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:06 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:08 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4383. Jun 06 14:14:08 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:08 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:08 ft1905-febf volumio[29635]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:08 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:08 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:09 ft1905-febf sshd[29638]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 06 14:14:09 ft1905-febf sshd[29638]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 06 14:14:09 ft1905-febf sshd[29638]: rexec line 8: Deprecated option ServerKeyBits Jun 06 14:14:09 ft1905-febf sshd[29638]: rexec line 14: Deprecated option RSAAuthentication Jun 06 14:14:09 ft1905-febf sshd[29638]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 06 14:14:09 ft1905-febf sshd[29638]: reprocess config line 14: Deprecated option RSAAuthentication Jun 06 14:14:09 ft1905-febf sshd[29638]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 06 14:14:10 ft1905-febf sshd[29638]: Accepted password for volumio from 192.168.4.28 port 50210 ssh2 Jun 06 14:14:10 ft1905-febf sshd[29638]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 06 14:14:10 ft1905-febf systemd-logind[2176]: New session c4 of user volumio. Jun 06 14:14:10 ft1905-febf systemd[1]: Started Session c4 of user volumio. Jun 06 14:14:11 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4384. Jun 06 14:14:11 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:11 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:11 ft1905-febf volumio[29658]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:11 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:11 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:12 ft1905-febf sshd[29655]: Received disconnect from 192.168.4.28 port 50210:11: Jun 06 14:14:12 ft1905-febf sshd[29655]: Disconnected from user volumio 192.168.4.28 port 50210 Jun 06 14:14:12 ft1905-febf sshd[29638]: pam_unix(sshd:session): session closed for user volumio Jun 06 14:14:12 ft1905-febf sshd[29663]: rexec line 6: Deprecated option UsePrivilegeSeparation Jun 06 14:14:12 ft1905-febf sshd[29663]: rexec line 7: Deprecated option KeyRegenerationInterval Jun 06 14:14:12 ft1905-febf sshd[29663]: rexec line 8: Deprecated option ServerKeyBits Jun 06 14:14:12 ft1905-febf sshd[29663]: rexec line 14: Deprecated option RSAAuthentication Jun 06 14:14:12 ft1905-febf sshd[29663]: rexec line 17: Deprecated option RhostsRSAAuthentication Jun 06 14:14:12 ft1905-febf systemd-logind[2176]: Session c4 logged out. Waiting for processes to exit. Jun 06 14:14:12 ft1905-febf systemd[1]: session-c4.scope: Succeeded. Jun 06 14:14:12 ft1905-febf systemd-logind[2176]: Removed session c4. Jun 06 14:14:12 ft1905-febf sshd[29663]: reprocess config line 14: Deprecated option RSAAuthentication Jun 06 14:14:12 ft1905-febf sshd[29663]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Jun 06 14:14:12 ft1905-febf sshd[29663]: Accepted password for volumio from 192.168.4.28 port 50216 ssh2 Jun 06 14:14:12 ft1905-febf sshd[29663]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 06 14:14:13 ft1905-febf systemd-logind[2176]: New session c5 of user volumio. Jun 06 14:14:13 ft1905-febf systemd[1]: Started Session c5 of user volumio. Jun 06 14:14:13 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4385. Jun 06 14:14:13 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:13 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:13 ft1905-febf volumio[29685]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:13 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:13 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:13 ft1905-febf sudo[29687]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl stop volumio.service Jun 06 14:14:13 ft1905-febf sudo[29687]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:13 ft1905-febf systemd[1]: Stopping Volumio Backend Module... Jun 06 14:14:13 ft1905-febf socketio[2406]: [2025-06-06 14:14:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 06 14:14:13 ft1905-febf socketio[2406]: [2025-06-06 14:14:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 06 14:14:13 ft1905-febf go-librespot[22451]: time="2025-06-06T14:14:13+08:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 06 14:14:13 ft1905-febf systemd[1]: volumio.service: Succeeded. Jun 06 14:14:13 ft1905-febf systemd[1]: Stopped Volumio Backend Module. Jun 06 14:14:13 ft1905-febf sudo[29687]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:13 ft1905-febf sudo[29696]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl start volumio.service Jun 06 14:14:13 ft1905-febf sudo[29696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:13 ft1905-febf systemd[1]: Started dynamicswap service. Jun 06 14:14:13 ft1905-febf systemd[1]: Started Volumio Backend Module. Jun 06 14:14:13 ft1905-febf sudo[29696]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:13 ft1905-febf sshd[29681]: Received disconnect from 192.168.4.28 port 50216:11: Jun 06 14:14:13 ft1905-febf sshd[29681]: Disconnected from user volumio 192.168.4.28 port 50216 Jun 06 14:14:13 ft1905-febf sshd[29663]: pam_unix(sshd:session): session closed for user volumio Jun 06 14:14:13 ft1905-febf systemd[1]: dynamicswap.service: Succeeded. Jun 06 14:14:14 ft1905-febf systemd[1]: session-c5.scope: Succeeded. Jun 06 14:14:14 ft1905-febf systemd-logind[2176]: Session c5 logged out. Waiting for processes to exit. Jun 06 14:14:14 ft1905-febf systemd-logind[2176]: Removed session c5. Jun 06 14:14:15 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4386. Jun 06 14:14:15 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:15 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:15 ft1905-febf volumio[29724]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:15 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:15 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:17 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4387. Jun 06 14:14:17 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:17 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:17 ft1905-febf volumio[29728]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:17 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:17 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:18 ft1905-febf socketio[2406]: [2025-06-06 14:14:18] [connect] Successful connection Jun 06 14:14:18 ft1905-febf volumio[29703]: info: ------------------------------------------- Jun 06 14:14:18 ft1905-febf volumio[29703]: info: ----- Volumio2 ---- Jun 06 14:14:18 ft1905-febf volumio[29703]: info: ------------------------------------------- Jun 06 14:14:18 ft1905-febf volumio[29703]: info: ----- System startup ---- Jun 06 14:14:18 ft1905-febf volumio[29703]: info: ------------------------------------------- Jun 06 14:14:20 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4388. Jun 06 14:14:20 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:20 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:20 ft1905-febf volumio[29739]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:20 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:20 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Found new core plugin music_service/cdplayer. Adding it Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Plugin folders cleanup Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning into folder /volumio/app/plugins/ Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category audio_interface Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category miscellanea Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category music_service Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category plugins.json Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category system_controller Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning category user_interface Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Scanning into folder /data/plugins/ Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Plugin folders cleanup completed Jun 06 14:14:20 ft1905-febf volumio[29703]: info: ------------------------------------------- Jun 06 14:14:20 ft1905-febf volumio[29703]: info: ----- Core plugins startup ---- Jun 06 14:14:20 ft1905-febf volumio[29703]: info: ------------------------------------------- Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Loading plugins from folder /volumio/app/plugins/ Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Adding plugin upnp to MyMusic Plugins Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Loading plugins from folder /data/plugins/ Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Loading plugin "system"... Jun 06 14:14:20 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jun 06 14:14:20 ft1905-febf volumio[29703]: info: Loading plugin "appearance"... Jun 06 14:14:20 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "network"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Refreshing Cached IP Addresses Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "services"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "alsa_controller"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jun 06 14:14:21 ft1905-febf sudo[29743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 14:14:21 ft1905-febf sudo[29745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 14:14:21 ft1905-febf sudo[29743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Applying required configuration parameters for plugin alsa_controller Jun 06 14:14:21 ft1905-febf sudo[29745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:21 ft1905-febf sudo[29743]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:21 ft1905-febf sudo[29745]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "wizard"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "volumio_command_line_client"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jun 06 14:14:21 ft1905-febf volumio[29703]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jun 06 14:14:21 ft1905-febf volumio[29703]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jun 06 14:14:21 ft1905-febf volumio[29703]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jun 06 14:14:21 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "upnp"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: [1749190461859] Starting Upmpd Daemon Jun 06 14:14:21 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "my_music"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jun 06 14:14:21 ft1905-febf volumio[29703]: info: Loading plugin "mpd"... Jun 06 14:14:21 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jun 06 14:14:22 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4389. Jun 06 14:14:22 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:22 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:22 ft1905-febf volumio[29770]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:22 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:22 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:22 ft1905-febf volumio[29703]: info: Creating MPD Configuration file Jun 06 14:14:22 ft1905-febf volumio[29703]: info: Loading plugin "upnp_browser"... Jun 06 14:14:22 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jun 06 14:14:22 ft1905-febf sudo[29772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 14:14:22 ft1905-febf sudo[29774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 14:14:22 ft1905-febf sudo[29772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:22 ft1905-febf sudo[29774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:22 ft1905-febf sudo[29772]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:23 ft1905-febf systemd[1]: Stopping Music Player Daemon... Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Loading plugin "networkfs"... Jun 06 14:14:23 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jun 06 14:14:23 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Loading plugin "alarm-clock"... Jun 06 14:14:23 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Loading plugin "airplay_emulation"... Jun 06 14:14:23 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jun 06 14:14:23 ft1905-febf volumio[29703]: Shairport Sync D-Bus 服务已注册。 Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Starting Shairport Sync Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Loading plugin "last_100"... Jun 06 14:14:23 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jun 06 14:14:23 ft1905-febf systemd[1]: mpd.service: Succeeded. Jun 06 14:14:23 ft1905-febf systemd[1]: Stopped Music Player Daemon. Jun 06 14:14:23 ft1905-febf volumio[29703]: info: Loading plugin "spop"... Jun 06 14:14:23 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jun 06 14:14:23 ft1905-febf systemd[1]: Starting Music Player Daemon... Jun 06 14:14:24 ft1905-febf volumio[29703]: info: Loading plugin "webradio"... Jun 06 14:14:24 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jun 06 14:14:24 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4390. Jun 06 14:14:24 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:24 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:24 ft1905-febf volumio[29793]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:24 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:24 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:26 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4391. Jun 06 14:14:26 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:26 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:26 ft1905-febf volumio[29795]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:26 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:26 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:27 ft1905-febf mpd[29792]: Jun 06 14:14 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 14:14:27 ft1905-febf mpd[29792]: Jun 06 14:14 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 06 14:14:27 ft1905-febf mpd[29792]: Jun 06 14:14 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "i2s_dacs"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jun 06 14:14:27 ft1905-febf systemd[1]: Started Music Player Daemon. Jun 06 14:14:27 ft1905-febf sudo[29774]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:27 ft1905-febf volumio[29703]: info: I2S DAC not set, start Auto-detection Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "volumiodiscovery"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** For more information see Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 14:14:27 ft1905-febf volumio[29703]: *** WARNING *** For more information see Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** For more information see Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 06 14:14:27 ft1905-febf node[29703]: *** WARNING *** For more information see Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Discovery: Started advertising with name: FT1905-febf Jun 06 14:14:27 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "outputs"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "albumart"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Plugin example_plugin is not enabled Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "inputs"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "pandora"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Applying required configuration parameters for plugin pandora Jun 06 14:14:27 ft1905-febf volumio[29703]: info: Loading plugin "podcast"... Jun 06 14:14:27 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jun 06 14:14:28 ft1905-febf volumio[29703]: info: ControllerPodcast::constructor Jun 06 14:14:28 ft1905-febf volumio[29703]: info: Loading plugin "qobuz"... Jun 06 14:14:28 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jun 06 14:14:28 ft1905-febf volumio[29703]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jun 06 14:14:28 ft1905-febf volumio[29703]: info: Loading plugin "tidal"... Jun 06 14:14:28 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jun 06 14:14:29 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4392. Jun 06 14:14:29 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:29 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469076] ControllerTidalPlugin::constructor Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469080] ControllerTidalPlugin::onVolumioStart Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Loading plugin "updater_comm"... Jun 06 14:14:29 ft1905-febf volumio[29807]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:29 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:29 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:29 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Plugin mpdemulation is not enabled Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Loading plugin "rest_api"... Jun 06 14:14:29 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Loading plugin "websocket"... Jun 06 14:14:29 ft1905-febf volumio[29703]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jun 06 14:14:29 ft1905-febf volumio[29703]: info: ___________ START PLUGINS ___________ Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469401] CoreMusicLibrary::Adding element Media Servers Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: SSDP Client initialized. Jun 06 14:14:29 ft1905-febf volumio[29703]: Starting initial SSDP search... Jun 06 14:14:29 ft1905-febf volumio[29703]: Discovering on interface: lo Jun 06 14:14:29 ft1905-febf volumio[29703]: Discovering on interface: eth0 Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469572] CoreMusicLibrary::Adding element Last_100 Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Creating Spotify config file Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469626] CoreMusicLibrary::Adding element Webradio Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469656] CoreMusicLibrary::Adding element Pandora Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469663] ControllerPandora::initializeMQTT Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469665] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469681] CoreMusicLibrary::Adding element Podcast Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469689] CoreMusicLibrary::Adding element Qobuz Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469703] ControllerTidalPlugin::onStart Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469711] ControllerTidalPlugin::addToBrowseSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 06 14:14:29 ft1905-febf volumio[29703]: info: [1749190469713] CoreMusicLibrary::Adding element Tidal Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: Loading i18n strings for locale en Jun 06 14:14:29 ft1905-febf volumio[29703]: Updating browse sources language Jun 06 14:14:29 ft1905-febf volumio[29703]: Cannot find translation for sourceMedia Servers Jun 06 14:14:29 ft1905-febf volumio[29703]: Cannot find translation for sourcePodcast Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::initPlayerControls Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 06 14:14:29 ft1905-febf volumio[29703]: info: BOOT COMPLETED Jun 06 14:14:29 ft1905-febf volumio[29703]: [Metrics] CommandRouter: 11s 163.72ms Jun 06 14:14:29 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 14:14:30 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 06 14:14:31 ft1905-febf socketio[2406]: [2025-06-06 14:14:31] [connect] Successful connection Jun 06 14:14:31 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4393. Jun 06 14:14:31 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:31 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:31 ft1905-febf volumio[29916]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:31 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:31 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:33 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4394. Jun 06 14:14:33 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:33 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:33 ft1905-febf volumio[29959]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:33 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:33 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:35 ft1905-febf volumio[29703]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreCommandRouter::Close All Modals sent Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreCommandRouter::Close All Modals sent Jun 06 14:14:35 ft1905-febf volumio[29703]: Express server listening on port 3000 Jun 06 14:14:35 ft1905-febf volumio[29703]: [Metrics] WebUI: 17s 293.26ms Jun 06 14:14:35 ft1905-febf volumio[29703]: (node:29703) [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 14:14:35 ft1905-febf volumio[29703]: info: [1749190475518] ControllerPandora::validateAndSetAccountOptions Jun 06 14:14:35 ft1905-febf volumio[29703]: error: [1749190475521] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreStateMachine::resetVolumioState Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreStateMachine::getcurrentVolume Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 14:14:35 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 14:14:35 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4395. Jun 06 14:14:35 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:35 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:35 ft1905-febf sudo[29974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 06 14:14:35 ft1905-febf volumio[29703]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 06 14:14:35 ft1905-febf sudo[29974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:35 ft1905-febf sudo[29972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 06 14:14:35 ft1905-febf volumio[29975]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:35 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:35 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:35 ft1905-febf sudo[29972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:36 ft1905-febf sudo[29974]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:36 ft1905-febf sudo[29972]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:36 ft1905-febf volumio[29703]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 14:14:36 ft1905-febf volumio[29703]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 14:14:36 ft1905-febf volumio[29703]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 14:14:36 ft1905-febf volumio[29703]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: NTS: 'ssdp:alive', Jun 06 14:14:36 ft1905-febf volumio[29703]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 14:14:36 ft1905-febf volumio[29703]: USN: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '13529675', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '13529675', Jun 06 14:14:36 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:14:36 ft1905-febf volumio[29703]: DATE: '2025-06-06T06:14:35.559183Z' } Jun 06 14:14:36 ft1905-febf volumio[29703]: Device IP: 192.168.4.85 Jun 06 14:14:36 ft1905-febf volumio[29703]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 14:14:36 ft1905-febf volumio[29703]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 14:14:36 ft1905-febf volumio[29703]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 14:14:36 ft1905-febf volumio[29703]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: NTS: 'ssdp:alive', Jun 06 14:14:36 ft1905-febf volumio[29703]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 14:14:36 ft1905-febf volumio[29703]: USN: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '13529675', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '13529675', Jun 06 14:14:36 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:14:36 ft1905-febf volumio[29703]: DATE: '2025-06-06T06:14:35.766295Z' } Jun 06 14:14:36 ft1905-febf volumio[29703]: Device IP: 192.168.4.85 Jun 06 14:14:36 ft1905-febf volumio[29703]: Device response: { LOCATION: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'http://192.168.4.154:9365/MediaServer/DeviceDescription.xml', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '0', Jun 06 14:14:36 ft1905-febf volumio[29703]: SERVER: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '1748572885', Jun 06 14:14:36 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:14:36 ft1905-febf volumio[29703]: USN: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: DATE: 'Fri, 06 Jun 2025 06:14:35 GMT', Jun 06 14:14:36 ft1905-febf volumio[29703]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800' } Jun 06 14:14:36 ft1905-febf volumio[29703]: Device IP: 192.168.4.154 Jun 06 14:14:36 ft1905-febf volumio[29703]: info: this.stateMachine.currentVolume: null, vol: 0 Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreStateMachine::pushState Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreStateMachine::updateTrackBlock Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CorePlayQueue::getTrackBlock Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioRetrievevolume Jun 06 14:14:36 ft1905-febf volumio[29703]: info: MPD Permissions set Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 06 14:14:36 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 06 14:14:36 ft1905-febf volumio[29703]: Device response: { EXT: '', Jun 06 14:14:36 ft1905-febf volumio[29703]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 14:14:36 ft1905-febf volumio[29703]: USN: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:36 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 14:14:36 ft1905-febf volumio[29703]: LOCATION: Jun 06 14:14:36 ft1905-febf volumio[29703]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 14:14:36 ft1905-febf volumio[29703]: Device IP: 192.168.4.85 Jun 06 14:14:36 ft1905-febf volumio[29703]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jun 06 14:14:36 ft1905-febf volumio[29703]: sudo: /usr/sbin/i2cdetect: command not found Jun 06 14:14:36 ft1905-febf volumio[29703]: info: Spotify config file written Jun 06 14:14:37 ft1905-febf volumio[29703]: Device response: { LOCATION: Jun 06 14:14:37 ft1905-febf volumio[29703]: 'http://192.168.4.154:9365/MediaServer/DeviceDescription.xml', Jun 06 14:14:37 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '0', Jun 06 14:14:37 ft1905-febf volumio[29703]: SERVER: Jun 06 14:14:37 ft1905-febf volumio[29703]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', Jun 06 14:14:37 ft1905-febf volumio[29703]: USN: Jun 06 14:14:37 ft1905-febf volumio[29703]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:37 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:14:37 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '1748572885', Jun 06 14:14:37 ft1905-febf volumio[29703]: DATE: 'Fri, 06 Jun 2025 06:14:35 GMT', Jun 06 14:14:37 ft1905-febf volumio[29703]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:37 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800' } Jun 06 14:14:37 ft1905-febf volumio[29703]: Device IP: 192.168.4.154 Jun 06 14:14:37 ft1905-febf volumio[29703]: Device response: { EXT: '', Jun 06 14:14:37 ft1905-febf volumio[29703]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:37 ft1905-febf volumio[29703]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 06 14:14:37 ft1905-febf volumio[29703]: USN: Jun 06 14:14:37 ft1905-febf volumio[29703]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:14:37 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 14:14:37 ft1905-febf volumio[29703]: LOCATION: Jun 06 14:14:37 ft1905-febf volumio[29703]: 'http://192.168.4.85:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 06 14:14:37 ft1905-febf volumio[29703]: Device IP: 192.168.4.85 Jun 06 14:14:37 ft1905-febf sudo[30012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jun 06 14:14:37 ft1905-febf sudo[30012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:37 ft1905-febf volumio[29703]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 06 14:14:37 ft1905-febf volumio[29703]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Jun 06 14:14:37 ft1905-febf volumio[29703]: sendCommand: Command { name: 'search album ""', args: [] } Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 14:14:37 ft1905-febf volumio[29703]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 14:14:37 ft1905-febf volumio[29703]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 14:14:37 ft1905-febf volumio[29703]: info: MPD running with PID29792 Jun 06 14:14:37 ft1905-febf volumio[29703]: ,establishing connection Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 14:14:37 ft1905-febf systemd[1]: Stopping go-librespot Daemon... Jun 06 14:14:37 ft1905-febf systemd[1]: go-librespot-daemon.service: Succeeded. Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 06 14:14:37 ft1905-febf systemd[1]: Stopped go-librespot Daemon. Jun 06 14:14:37 ft1905-febf systemd[1]: Started go-librespot Daemon. Jun 06 14:14:37 ft1905-febf sudo[30012]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:37 ft1905-febf go-librespot[30022]: go-librespot daemon starting... Jun 06 14:14:37 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 14:14:37 ft1905-febf volumio[29703]: info: No need to fix Spotify hosts Jun 06 14:14:37 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:37+08:00" level=info msg="running go-librespot 0.2.0" Jun 06 14:14:37 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:37+08:00" level=debug msg="app state loaded" Jun 06 14:14:37 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:37+08:00" level=debug msg="stored credentials not found" Jun 06 14:14:37 ft1905-febf volumio[29703]: error: updateQueue error: null Jun 06 14:14:37 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:37+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 06 14:14:37 ft1905-febf volumio[29703]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 06 14:14:38 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4396. Jun 06 14:14:38 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:38 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:38 ft1905-febf volumio[30033]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:38 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:38 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:38 ft1905-febf volumio[29998]: Forking 1 albumart workers Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreStateMachine::setRepeat null single undefined Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreStateMachine::pushState Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreStateMachine::setRandom false Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreStateMachine::pushState Jun 06 14:14:38 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 14:14:38 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:38+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 06 14:14:38 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:38+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 06 14:14:38 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:38+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 06 14:14:38 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:38+08:00" level=info msg="zeroconf server listening on port 40733" Jun 06 14:14:39 ft1905-febf volumio[29703]: sendCommand: Command { name: 'playlistinfo', args: [] } Jun 06 14:14:39 ft1905-febf volumio[29703]: error: updateQueue error: null Jun 06 14:14:39 ft1905-febf volumio[29703]: info: Starting Shairport Sync Jun 06 14:14:39 ft1905-febf sudo[30047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 06 14:14:39 ft1905-febf sudo[30050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 06 14:14:39 ft1905-febf sudo[30047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:39 ft1905-febf sudo[30050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 06 14:14:39 ft1905-febf systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 06 14:14:39 ft1905-febf sudo[30050]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:40 ft1905-febf systemd[1]: shairport-sync.service: Succeeded. Jun 06 14:14:40 ft1905-febf systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 06 14:14:40 ft1905-febf systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 06 14:14:40 ft1905-febf sudo[30047]: pam_unix(sudo:session): session closed for user root Jun 06 14:14:40 ft1905-febf volumio[29703]: Discovery: adding 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 06 14:14:40 ft1905-febf volumio[29703]: info: mDNS: Found device FT1905-febf Jun 06 14:14:40 ft1905-febf volumio[29703]: error: Could not retrieve plugin audio_interface multiroom Jun 06 14:14:40 ft1905-febf volumio[29703]: error: Could not retrieve plugin audio_interface multiroom Jun 06 14:14:40 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4397. Jun 06 14:14:40 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:40 ft1905-febf volumio[29703]: info: Upmpdcli Daemon Started Jun 06 14:14:40 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:40 ft1905-febf volumio[29703]: info: Shairport-Sync Started Jun 06 14:14:40 ft1905-febf volumio[30068]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:40 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:40 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:40 ft1905-febf volumio[29703]: Error adding Membership: Error: addMembership EINVAL Jun 06 14:14:41 ft1905-febf volumio[30035]: Starting albumart workers Jun 06 14:14:42 ft1905-febf volumio[29703]: info: go-librespot daemon successfully initialized Jun 06 14:14:42 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4398. Jun 06 14:14:42 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:42 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:42 ft1905-febf volumio[30092]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:42 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:42 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 14:14:44 ft1905-febf volumio[29703]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioGetVisibleSources Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 06 14:14:44 ft1905-febf volumio[29703]: info: [pushCmd] {"desc":"getToneInfo","index":5,"getinfo":4} Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 06 14:14:44 ft1905-febf volumio[29703]: error: Could not retrieve plugin audio_interface multiroom Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreCommandRouter::volumioGetQueue Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CoreStateMachine::getQueue Jun 06 14:14:44 ft1905-febf volumio[29703]: info: CorePlayQueue::getQueue Jun 06 14:14:44 ft1905-febf volumio[29703]: info: Listing playlists Jun 06 14:14:44 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4399. Jun 06 14:14:44 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:44 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:44 ft1905-febf volumio[30101]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:44 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:44 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:45 ft1905-febf volumio[29703]: Discovery: this is already registered, 28bc41e0-af37-44db-b28c-6c26e99ff0b2 Jun 06 14:14:45 ft1905-febf volumio[29703]: info: mDNS: Found device FT1905-febf Jun 06 14:14:45 ft1905-febf volumio[29703]: error: Could not retrieve plugin audio_interface multiroom Jun 06 14:14:45 ft1905-febf volumio[29703]: error: Could not retrieve plugin audio_interface multiroom Jun 06 14:14:45 ft1905-febf volumio[29703]: info: Initializing connection to go-librespot Websocket Jun 06 14:14:45 ft1905-febf go-librespot[30024]: time="2025-06-06T14:14:45+08:00" level=debug msg="new websocket client" Jun 06 14:14:45 ft1905-febf volumio[29703]: info: Connection to go-librespot Websocket established Jun 06 14:14:47 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4400. Jun 06 14:14:47 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:47 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:47 ft1905-febf volumio[30112]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:47 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:47 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:47 ft1905-febf socketio[2406]: [2025-06-06 14:14:47] [connect] Successful connection Jun 06 14:14:47 ft1905-febf socketio[2406]: [2025-06-06 14:14:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749190487 101 Jun 06 14:14:47 ft1905-febf socketio[2406]: sio connected Jun 06 14:14:47 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 14:14:47 ft1905-febf volumio[29703]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 14:14:47 ft1905-febf socketio[2406]: socket send Jun 06 14:14:47 ft1905-febf socketio[2406]: command_len 356 Jun 06 14:14:47 ft1905-febf socketio[2406]: pushSrctotal Jun 06 14:14:47 ft1905-febf socketio[2406]: { Jun 06 14:14:47 ft1905-febf socketio[2406]: "total": 7, Jun 06 14:14:47 ft1905-febf socketio[2406]: "cur": 0, Jun 06 14:14:47 ft1905-febf socketio[2406]: "source": [{ Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 0, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "Media Player" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 4, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "Bluetooth" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 6, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "AUX" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "DIGITAL OPT1" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "DIGITAL OPT2" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "HDMI ARC" Jun 06 14:14:47 ft1905-febf socketio[2406]: }, { Jun 06 14:14:47 ft1905-febf socketio[2406]: "type": 0, Jun 06 14:14:47 ft1905-febf socketio[2406]: "name": "USB" Jun 06 14:14:47 ft1905-febf socketio[2406]: }] Jun 06 14:14:47 ft1905-febf socketio[2406]: } Jun 06 14:14:47 ft1905-febf volumio[29703]: 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 14:14:47 ft1905-febf volumio[29703]: info: [pushCmd] {"desc":"getSourceInfo","index":5,"getinfo":1} Jun 06 14:14:47 ft1905-febf socketio[2406]: socket send Jun 06 14:14:47 ft1905-febf socketio[2406]: command_len 31 Jun 06 14:14:47 ft1905-febf socketio[2406]: pushSourceInfo Jun 06 14:14:47 ft1905-febf socketio[2406]: { Jun 06 14:14:47 ft1905-febf socketio[2406]: "source": 0 Jun 06 14:14:47 ft1905-febf socketio[2406]: } Jun 06 14:14:47 ft1905-febf volumio[29703]: info: [pushSourceInfo] {"source":0} Jun 06 14:14:48 ft1905-febf volumio[29703]: info: Getting Spotify volume Jun 06 14:14:48 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jun 06 14:14:48 ft1905-febf volumio[29703]: info: [pushCmd] {"index":5,"getInfo":0} Jun 06 14:14:48 ft1905-febf socketio[2406]: socket send Jun 06 14:14:48 ft1905-febf socketio[2406]: command_len 356 Jun 06 14:14:48 ft1905-febf socketio[2406]: pushSrctotal Jun 06 14:14:48 ft1905-febf socketio[2406]: { Jun 06 14:14:48 ft1905-febf socketio[2406]: "total": 7, Jun 06 14:14:48 ft1905-febf socketio[2406]: "cur": 0, Jun 06 14:14:48 ft1905-febf socketio[2406]: "source": [{ Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 0, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "Media Player" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 4, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "Bluetooth" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 6, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "AUX" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "DIGITAL OPT1" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "DIGITAL OPT2" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 5, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "HDMI ARC" Jun 06 14:14:48 ft1905-febf socketio[2406]: }, { Jun 06 14:14:48 ft1905-febf socketio[2406]: "type": 0, Jun 06 14:14:48 ft1905-febf socketio[2406]: "name": "USB" Jun 06 14:14:48 ft1905-febf socketio[2406]: }] Jun 06 14:14:48 ft1905-febf socketio[2406]: } Jun 06 14:14:48 ft1905-febf volumio[29703]: 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 14:14:48 ft1905-febf volumio[29703]: info: [pushCmd] {"desc":"getSourceInfo","index":5,"getinfo":1} Jun 06 14:14:48 ft1905-febf socketio[2406]: socket send Jun 06 14:14:48 ft1905-febf socketio[2406]: command_len 31 Jun 06 14:14:48 ft1905-febf socketio[2406]: pushSourceInfo Jun 06 14:14:48 ft1905-febf socketio[2406]: { Jun 06 14:14:48 ft1905-febf socketio[2406]: "source": 0 Jun 06 14:14:48 ft1905-febf socketio[2406]: } Jun 06 14:14:48 ft1905-febf volumio[29703]: info: [pushSourceInfo] {"source":0} Jun 06 14:14:49 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4401. Jun 06 14:14:49 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:49 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:49 ft1905-febf volumio[30113]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:49 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:49 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:51 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4402. Jun 06 14:14:51 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:51 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:51 ft1905-febf volumio[30115]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:51 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:51 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:53 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4403. Jun 06 14:14:53 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:53 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:53 ft1905-febf volumio[30116]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:53 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:53 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:56 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4404. Jun 06 14:14:56 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:56 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:56 ft1905-febf volumio[30117]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:56 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:56 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:58 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4405. Jun 06 14:14:58 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:14:58 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:14:58 ft1905-febf volumio[30119]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:14:58 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:14:58 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:14:59 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 14:14:59 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 14:15:00 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4406. Jun 06 14:15:00 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:00 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:00 ft1905-febf volumio[30122]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:00 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:00 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:02 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4407. Jun 06 14:15:02 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:02 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:02 ft1905-febf volumio[30131]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:02 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:02 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:05 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4408. Jun 06 14:15:05 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:05 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:05 ft1905-febf volumio[30133]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:05 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:05 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:07 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4409. Jun 06 14:15:07 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:07 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:07 ft1905-febf volumio[30135]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:07 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:07 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:09 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4410. Jun 06 14:15:09 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:09 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:09 ft1905-febf volumio[30136]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:09 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:09 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:11 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4411. Jun 06 14:15:11 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:11 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:11 ft1905-febf volumio[30137]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:11 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:11 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:13 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:13+08:00" level=debug msg="obtained new client token: AAAveIj9sSLPPEd868G4G5AeFC/I+7mE//rJ/tOjru+W3NLwPkOwABGWPZPAtgi2mJ1qwrl1SPNxtVYHLdXvaNzG1e4UNfNReJDnFAFqoMQpyH9k1ElBobN0c7C1lht7gioeE0XjTsZHexe280z3ytpCwP2gXTHX4xGWDnEKNbhoD3U8Dusbq2Mn99wvWVifCFKYGpSwB3AeQCsP1My/PHYqL7m2mvSYeBEBC+oK8IVpBWi/a8gjSbaDu702" Jun 06 14:15:14 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4412. Jun 06 14:15:14 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:14 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:14 ft1905-febf volumio[30138]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:14 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:14 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:14 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:14+08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Jun 06 14:15:14 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:14+08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Jun 06 14:15:14 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:14+08:00" level=debug msg="connected to ap-gae2.spotify.com:80" Jun 06 14:15:15 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:15+08:00" level=debug msg="completed keyexchange" Jun 06 14:15:15 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:15+08:00" level=debug msg="completed challenge" Jun 06 14:15:15 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:15+08:00" level=info msg="authenticated AP as 31dobbykadpjqbg53ytmzna3bo7u" Jun 06 14:15:15 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:15+08:00" level=info msg="authenticated Login5 as 31dobbykadpjqbg53ytmzna3bo7u" Jun 06 14:15:15 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:15+08:00" level=info msg="accepted zeroconf user 31dobbykadpjqbg53ytmzna3bo7u from iQOO 13" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="dealer connection opened" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=trace msg="starting accesspoint recv loop" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=trace msg="starting dealer recv loop" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=trace msg="received accesspoint ping" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="received connection id: MTk0MWFmMGUtY2Y3YS00ZDA2LTgzYzItMWMyNjA1MmJhNjcwK2RlYWxlcit0Y3A6Ly8wYWM5NDg3Yy5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArRjUwRkFEQzU2MjA4RjgzNjI2Mzc3NDZCMDZFNzNEOTU5MjY1RjhDRjQzMEU1MEFFODRBNTRBQzBFNzI5NUMwNQ==" Jun 06 14:15:16 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4413. Jun 06 14:15:16 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:16 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:16 ft1905-febf volumio[30139]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:16 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:16 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="put connect state because NEW_DEVICE" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="handling transfer player command from 13db9b7c8f69fb0f08b58b2838ef8017b5b60605" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=trace msg="received accesspoint pong ack" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DWSBcxmKiZ0B8" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=trace msg="fetched new page 0 with 101 items (list: 101)" uri="spotify:playlist:37i9dQZF1DWSBcxmKiZ0B8" Jun 06 14:15:16 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:16+08:00" level=debug msg="loading track (paused: true, position: 247063ms)" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=trace msg="emitting websocket event: will_play" Jun 06 14:15:17 ft1905-febf volumio[29703]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7KoqEF76fGnDYdxTSHLxcm","play_origin":"playlist"}} Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jun 06 14:15:17 ft1905-febf volumio[29703]: info: Spotify is playing in volatile mode Jun 06 14:15:17 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:17 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:17 ft1905-febf volumio[29703]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 14:15:17 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:17 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:17 ft1905-febf volumio[29703]: verbose: SET VOLATILE Jun 06 14:15:17 ft1905-febf volumio[29703]: info: CoreStateMachine::stop Jun 06 14:15:17 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:17 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:17 ft1905-febf volumio[29703]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 14:15:17 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:17 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:17 ft1905-febf volumio[29703]: verbose: UNSET VOLATILE Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="selected format OGG_VORBIS_320 (6c886e663073228909124159b486f88750728e80)" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="requested aes key for file 6c886e663073228909124159b486f88750728e80, gid: 7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1700" Jun 06 14:15:17 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:17+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1296" Jun 06 14:15:18 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4414. Jun 06 14:15:18 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:18 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:18 ft1905-febf volumio[30147]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:18 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:18 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:19 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:19+08:00" level=debug msg="fetched first chunk of 22, total size is 11458244 bytes" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:19 ft1905-febf volumio[29703]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 06 14:15:19 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800', Jun 06 14:15:19 ft1905-febf volumio[29703]: LOCATION: 'http://192.168.4.85:3030/upnp/root.xml', Jun 06 14:15:19 ft1905-febf volumio[29703]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 06 14:15:19 ft1905-febf volumio[29703]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:15:19 ft1905-febf volumio[29703]: NTS: 'ssdp:alive', Jun 06 14:15:19 ft1905-febf volumio[29703]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 06 14:15:19 ft1905-febf volumio[29703]: USN: Jun 06 14:15:19 ft1905-febf volumio[29703]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:15:19 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '13529719', Jun 06 14:15:19 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '13529719', Jun 06 14:15:19 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:15:19 ft1905-febf volumio[29703]: DATE: '2025-06-06T06:15:19.388201Z' } Jun 06 14:15:19 ft1905-febf volumio[29703]: Device IP: 192.168.4.85 Jun 06 14:15:19 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:19+08:00" level=debug msg="fetched chunk 20/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 19/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 18/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=trace msg="seek to 247063ms (diff: 122ms, samples: 10895478, bytes: 9596235)" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="created new output device" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=info msg="loaded track \"一路向北\" (paused: true, position: 247063ms, duration: 294240ms, prefetched: false)" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 21/21, size: 448196" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=trace msg="emitting websocket event: metadata" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=trace msg="emitting websocket event: active" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="sending successful reply for dealer request" Jun 06 14:15:20 ft1905-febf volumio[29703]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7KoqEF76fGnDYdxTSHLxcm","name":"一路向北","artist_names":["Jay Chou"],"album_name":"11月的蕭邦","album_cover_url":"https://i.scdn.co/image/ab67616d00001e022642de36b3fed494f653b015","position":247063,"duration":294240,"release_date":"year:2005 month:11 day:11","track_number":12,"disc_number":1}} Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jun 06 14:15:20 ft1905-febf volumio[29703]: SPOTIFY: received: {"type":"active","data":null} Jun 06 14:15:20 ft1905-febf volumio[29703]: info: Aligning Spotify Volume to Volumio Volume Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=trace msg="emitting websocket event: paused" Jun 06 14:15:20 ft1905-febf volumio[29703]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7KoqEF76fGnDYdxTSHLxcm","play_origin":"playlist"}} Jun 06 14:15:20 ft1905-febf volumio[29703]: info: Spotify is playing in volatile mode Jun 06 14:15:20 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:20 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:20 ft1905-febf volumio[29703]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 14:15:20 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:20 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:20 ft1905-febf volumio[29703]: verbose: SET VOLATILE Jun 06 14:15:20 ft1905-febf volumio[29703]: SPOTIFY: PUSH STATE SPOTIFY Jun 06 14:15:20 ft1905-febf volumio[29703]: SPOTIFY: {"status":"pause","service":"spop","title":"一路向北","artist":"Jay Chou","album":"11月的蕭邦","albumart":"https://i.scdn.co/image/ab67616d00001e022642de36b3fed494f653b015","uri":"spotify:track:7KoqEF76fGnDYdxTSHLxcm","trackType":"spotify","seek":247063,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 06 14:15:20 ft1905-febf volumio[29703]: this.isVolatile: true, stateService.status: pause Jun 06 14:15:20 ft1905-febf volumio[29703]: this.volatileState: {"stream":false,"trackType":"","albumart":"/albumart","repeatMode":"all"} Jun 06 14:15:20 ft1905-febf volumio[29703]: info: CoreStateMachine::pushState Jun 06 14:15:20 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 14:15:20 ft1905-febf socketio[2406]: socket send Jun 06 14:15:20 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4415. Jun 06 14:15:20 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:20 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:20 ft1905-febf go-librespot[30024]: time="2025-06-06T14:15:20+08:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:7KoqEF76fGnDYdxTSHLxcm" Jun 06 14:15:20 ft1905-febf volumio[30153]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:20 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:20 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:22 ft1905-febf volumio[29703]: Device response: { LOCATION: Jun 06 14:15:22 ft1905-febf volumio[29703]: 'http://192.168.4.154:9365/MediaServer/DeviceDescription.xml', Jun 06 14:15:22 ft1905-febf volumio[29703]: 'CONFIGID.UPNP.ORG': '0', Jun 06 14:15:22 ft1905-febf volumio[29703]: SERVER: Jun 06 14:15:22 ft1905-febf volumio[29703]: 'Mac-OS-X/13.7.4 UPnP/1.1 DLNADOC/1.5 Cyaneous-FireStream/2.0', Jun 06 14:15:22 ft1905-febf volumio[29703]: USN: Jun 06 14:15:22 ft1905-febf volumio[29703]: 'uuid:3fdad695-204c-65d4-4e83-c8f2cf400401::urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:15:22 ft1905-febf volumio[29703]: EXT: '', Jun 06 14:15:22 ft1905-febf volumio[29703]: 'BOOTID.UPNP.ORG': '1748572885', Jun 06 14:15:22 ft1905-febf volumio[29703]: DATE: 'Fri, 06 Jun 2025 06:15:19 GMT', Jun 06 14:15:22 ft1905-febf volumio[29703]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 06 14:15:22 ft1905-febf volumio[29703]: 'CACHE-CONTROL': 'max-age=1800' } Jun 06 14:15:22 ft1905-febf volumio[29703]: Device IP: 192.168.4.154 Jun 06 14:15:23 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4416. Jun 06 14:15:23 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:23 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:23 ft1905-febf volumio[30162]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:23 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:23 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:25 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4417. Jun 06 14:15:25 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:25 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:25 ft1905-febf volumio[30163]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:25 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:25 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:27 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4418. Jun 06 14:15:27 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:27 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:27 ft1905-febf volumio[30165]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:27 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:27 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:29 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4419. Jun 06 14:15:29 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:29 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:29 ft1905-febf volumio[30167]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:29 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:29 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:31 ft1905-febf volumio[29703]: info: Clearing queue after UPNP request Jun 06 14:15:31 ft1905-febf volumio[29703]: verbose: UNSET VOLATILE Jun 06 14:15:31 ft1905-febf volumio[29703]: SPOTIFY: UNSET VOLATILE Jun 06 14:15:31 ft1905-febf volumio[29703]: SPOTIFY: {"status":"pause","title":"一路向北","artist":"Jay Chou","album":"11月的蕭邦","albumart":"https://i.scdn.co/image/ab67616d00001e022642de36b3fed494f653b015","uri":"spotify:track:7KoqEF76fGnDYdxTSHLxcm","trackType":"spotify","seek":247063,"duration":294,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":0,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Jun 06 14:15:31 ft1905-febf volumio[29703]: info: Setting Spotify stop after unset volatile call Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::ClearQueue Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::stop Jun 06 14:15:31 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:31 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 14:15:31 ft1905-febf volumio[29703]: !! !! Jun 06 14:15:31 ft1905-febf volumio[29703]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 06 14:15:31 ft1905-febf volumio[29703]: verbose: UNSET VOLATILE Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::updateTrackBlock Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CorePlayQueue::getTrackBlock Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::stPlaybackTimer Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::pushState Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 14:15:31 ft1905-febf volumio[29703]: info: CoreStateMachine::serviceStop Jun 06 14:15:31 ft1905-febf volumio[29703]: info: ControllerMpd::stop Jun 06 14:15:31 ft1905-febf volumio[29703]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 14:15:31 ft1905-febf volumio[29703]: TypeError: Cannot read property 'executeOnPlugin' of undefined Jun 06 14:15:31 ft1905-febf volumio[29703]: at ControllerMpd.stop (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:2747:20) Jun 06 14:15:31 ft1905-febf volumio[29703]: at CoreStateMachine.serviceStop (webpack://Volumio2/./app/statemachine.js?:1257:24) Jun 06 14:15:31 ft1905-febf volumio[29703]: at CoreStateMachine.stop (webpack://Volumio2/./app/statemachine.js?:1235:19) Jun 06 14:15:31 ft1905-febf volumio[29703]: at CoreStateMachine.clearQueue (webpack://Volumio2/./app/statemachine.js?:250:8) Jun 06 14:15:31 ft1905-febf volumio[29703]: at Timeout.setTimeout [as _onTimeout] (webpack://Volumio2/./app/plugins/audio_interface/upnp/index.js?:317:37) Jun 06 14:15:31 ft1905-febf volumio[29703]: at ontimeout (timers.js:436:11) Jun 06 14:15:31 ft1905-febf volumio[29703]: at tryOnTimeout (timers.js:300:5) Jun 06 14:15:31 ft1905-febf volumio[29703]: at listOnTimeout (timers.js:263:5) Jun 06 14:15:31 ft1905-febf volumio[29703]: at Timer.processTimers (timers.js:223:10) Jun 06 14:15:31 ft1905-febf volumio[29703]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 14:15:32 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4420. Jun 06 14:15:32 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 06 14:15:32 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 06 14:15:32 ft1905-febf volumio[30201]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 06 14:15:32 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 06 14:15:32 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 06 14:15:34 ft1905-febf sudo[30323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-06 14:14 Jun 06 14:15:34 ft1905-febf sudo[30323]: 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