-- Logs begin at Thu 2025-06-05 13:34:40 CST, end at Thu 2025-06-05 15:19:25 CST. -- Jun 05 15:18:00 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:00+08:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:01 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7398. Jun 05 15:18:01 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:01 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:01 ft1905-febf volumio[29592]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:01 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:01 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:03 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7399. Jun 05 15:18:03 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:03 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:03 ft1905-febf volumio[29593]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:03 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:03 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:05 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7400. Jun 05 15:18:05 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:05 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:05 ft1905-febf volumio[29599]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:05 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:05 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:07 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7401. Jun 05 15:18:07 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:07 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:07 ft1905-febf volumio[29603]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:07 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:07 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="fetched chunk 10/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="fetched chunk 9/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=trace msg="seek to 134891ms (diff: 235ms, samples: 5948693, bytes: 4188419)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="created new output device" Jun 05 15:18:08 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Jun 05 15:18:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:08+08:00" level=info msg="loaded track \"紅\" (paused: false, position: 134891ms, duration: 269245ms, prefetched: false)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:09 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:09+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 05 15:18:09 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:09+08:00" level=trace msg="scheduling prefetch in 104s" Jun 05 15:18:09 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:09+08:00" level=trace msg="emitting websocket event: metadata" Jun 05 15:18:09 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:09+08:00" level=trace msg="emitting websocket event: active" Jun 05 15:18:09 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:09+08:00" level=debug msg="sending successful reply for dealer request" Jun 05 15:18:09 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","name":"紅","artist_names":["告五人"],"album_name":"我肯定在幾百年前就說過愛你","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","position":134891,"duration":269245,"release_date":"year:2019 month:6 day:14","track_number":4,"disc_number":1}} Jun 05 15:18:09 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"active","data":null} Jun 05 15:18:09 ft1905-febf volumio[29076]: info: Aligning Spotify Volume to Volumio Volume Jun 05 15:18:10 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7402. Jun 05 15:18:10 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:10 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:10 ft1905-febf volumio[29604]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:10 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:10 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:10 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:10+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 05 15:18:10 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:10+08:00" level=trace msg="emitting websocket event: playing" Jun 05 15:18:10 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","play_origin":"playlist"}} Jun 05 15:18:10 ft1905-febf volumio[29076]: info: Spotify is playing in volatile mode Jun 05 15:18:10 ft1905-febf volumio[29076]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 05 15:18:10 ft1905-febf volumio[29076]: !! !! Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 05 15:18:10 ft1905-febf volumio[29076]: !! !! Jun 05 15:18:10 ft1905-febf volumio[29076]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 05 15:18:10 ft1905-febf volumio[29076]: verbose: SET VOLATILE Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::stop Jun 05 15:18:10 ft1905-febf volumio[29076]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 05 15:18:10 ft1905-febf volumio[29076]: !! !! Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 05 15:18:10 ft1905-febf volumio[29076]: !! !! Jun 05 15:18:10 ft1905-febf volumio[29076]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Jun 05 15:18:10 ft1905-febf volumio[29076]: verbose: UNSET VOLATILE Jun 05 15:18:10 ft1905-febf volumio[29076]: SPOTIFY: PUSH STATE SPOTIFY Jun 05 15:18:10 ft1905-febf volumio[29076]: SPOTIFY: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":134891,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 05 15:18:10 ft1905-febf volumio[29076]: this.isVolatile: false, stateService.status: play Jun 05 15:18:10 ft1905-febf volumio[29076]: this.volatileState: {} Jun 05 15:18:10 ft1905-febf volumio[29076]: set volatileService undefined Jun 05 15:18:10 ft1905-febf volumio[29076]: info: volatileService: undefined Jun 05 15:18:10 ft1905-febf volumio[29076]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":134891,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 05 15:18:10 ft1905-febf volumio[29076]: verbose: CURRENT POSITION 0 Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::syncState stateService play Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::syncState currentStatus stop Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::pushState Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:18:10 ft1905-febf socketio[2397]: socket send Jun 05 15:18:10 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:10+08:00" level=debug msg="handling transfer player command from 13db9b7c8f69fb0f08b58b2838ef8017b5b60605" Jun 05 15:18:10 ft1905-febf volumio[29076]: SPOTIFY: PUSH STATE SPOTIFY Jun 05 15:18:10 ft1905-febf volumio[29076]: SPOTIFY: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":134891,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 05 15:18:10 ft1905-febf volumio[29076]: this.isVolatile: true, stateService.status: play Jun 05 15:18:10 ft1905-febf volumio[29076]: this.volatileState: {} Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreStateMachine::pushState Jun 05 15:18:10 ft1905-febf volumio[29076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:18:10 ft1905-febf socketio[2397]: socket send Jun 05 15:18:11 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:11+08:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO3RBGU0" Jun 05 15:18:11 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:11+08:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1DZ06evO3RBGU0" Jun 05 15:18:11 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:11+08:00" level=debug msg="loading track (paused: false, position: 137136ms)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:11 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:11+08:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:12 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:12+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 05 15:18:12 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:12+08:00" level=trace msg="emitting websocket event: will_play" Jun 05 15:18:12 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","play_origin":"playlist"}} Jun 05 15:18:12 ft1905-febf volumio[29076]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 05 15:18:12 ft1905-febf volumio[29076]: 'CACHE-CONTROL': 'max-age=1800', Jun 05 15:18:12 ft1905-febf volumio[29076]: LOCATION: 'http://192.168.100.104:3030/upnp/root.xml', Jun 05 15:18:12 ft1905-febf volumio[29076]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 05 15:18:12 ft1905-febf volumio[29076]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:18:12 ft1905-febf volumio[29076]: NTS: 'ssdp:alive', Jun 05 15:18:12 ft1905-febf volumio[29076]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 05 15:18:12 ft1905-febf volumio[29076]: USN: Jun 05 15:18:12 ft1905-febf volumio[29076]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:18:12 ft1905-febf volumio[29076]: 'BOOTID.UPNP.ORG': '13447092', Jun 05 15:18:12 ft1905-febf volumio[29076]: 'CONFIGID.UPNP.ORG': '13447092', Jun 05 15:18:12 ft1905-febf volumio[29076]: EXT: '', Jun 05 15:18:12 ft1905-febf volumio[29076]: DATE: '2025-06-05T07:18:12.206633Z' } Jun 05 15:18:12 ft1905-febf volumio[29076]: Device IP: 192.168.100.104 Jun 05 15:18:12 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7403. Jun 05 15:18:12 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:12 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:12 ft1905-febf volumio[29608]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:12 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:12 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:12 ft1905-febf volumio[29076]: Device response: { EXT: '', Jun 05 15:18:12 ft1905-febf volumio[29076]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:18:12 ft1905-febf volumio[29076]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 05 15:18:12 ft1905-febf volumio[29076]: USN: Jun 05 15:18:12 ft1905-febf volumio[29076]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:18:12 ft1905-febf volumio[29076]: 'CACHE-CONTROL': 'max-age=1800', Jun 05 15:18:12 ft1905-febf volumio[29076]: LOCATION: Jun 05 15:18:12 ft1905-febf volumio[29076]: 'http://192.168.100.104:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 05 15:18:12 ft1905-febf volumio[29076]: Device IP: 192.168.100.104 Jun 05 15:18:12 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:12+08:00" level=debug msg="selected format OGG_VORBIS_320 (0ad783229e893b6cb3c371c6c4d762f44db468e9)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:12 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:12+08:00" level=debug msg="requested aes key for file 0ad783229e893b6cb3c371c6c4d762f44db468e9, gid: 2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:12 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:12+08:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:14 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:14+08:00" level=trace msg="sent dealer ping" Jun 05 15:18:14 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7404. Jun 05 15:18:14 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:14 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:14 ft1905-febf volumio[29627]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:14 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:14 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:16 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7405. Jun 05 15:18:16 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:16 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:16 ft1905-febf volumio[29630]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:16 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:16 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:19 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7406. Jun 05 15:18:19 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:19 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:19 ft1905-febf volumio[29648]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:19 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:19 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:20 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:20+08:00" level=debug msg="fetched first chunk of 17, total size is 8836668 bytes" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:21 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7407. Jun 05 15:18:21 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:21 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:21 ft1905-febf volumio[29764]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:21 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:21 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:23 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7408. Jun 05 15:18:23 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:23 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:23 ft1905-febf volumio[29765]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:23 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:23 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:25 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7409. Jun 05 15:18:25 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:25 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:25 ft1905-febf volumio[29766]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:25 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:25 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:27 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:27+08:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:27 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:27+08:00" level=debug msg="fetched chunk 10/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:28 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7410. Jun 05 15:18:28 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:28 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:28 ft1905-febf volumio[29769]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:28 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:28 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:30 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7411. Jun 05 15:18:30 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:30 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:30 ft1905-febf volumio[29778]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:30 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:30 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:30 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:30+08:00" level=debug msg="fetched chunk 9/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:31 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:31+08:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:32 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7412. Jun 05 15:18:32 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:32 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:32 ft1905-febf volumio[29779]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:32 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:32 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:33 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:33+08:00" level=debug msg="fetched chunk 12/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:33 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:33+08:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:34 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:34+08:00" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:34 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:34+08:00" level=trace msg="seek to 137136ms (diff: 163ms, samples: 6047697, bytes: 4254921)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:34 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:34+08:00" level=info msg="loaded track \"紅\" (paused: false, position: 137136ms, duration: 269245ms, prefetched: false)" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:34 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7413. Jun 05 15:18:34 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:34 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:34 ft1905-febf volumio[29780]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:34 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:34 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=trace msg="scheduling prefetch in 101s" Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=trace msg="emitting websocket event: metadata" Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=trace msg="emitting websocket event: active" Jun 05 15:18:35 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","name":"紅","artist_names":["告五人"],"album_name":"我肯定在幾百年前就說過愛你","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","position":137136,"duration":269245,"release_date":"year:2019 month:6 day:14","track_number":4,"disc_number":1}} Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=debug msg="sending successful reply for dealer request" Jun 05 15:18:35 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"active","data":null} Jun 05 15:18:35 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:35+08:00" level=trace msg="received dealer pong" Jun 05 15:18:35 ft1905-febf volumio[29076]: info: Aligning Spotify Volume to Volumio Volume Jun 05 15:18:36 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:36+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 05 15:18:36 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:36+08:00" level=trace msg="emitting websocket event: playing" Jun 05 15:18:36 ft1905-febf volumio[29076]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","play_origin":"playlist"}} Jun 05 15:18:36 ft1905-febf volumio[29076]: SPOTIFY: PUSH STATE SPOTIFY Jun 05 15:18:36 ft1905-febf volumio[29076]: SPOTIFY: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":138136,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 05 15:18:36 ft1905-febf volumio[29076]: this.isVolatile: true, stateService.status: play Jun 05 15:18:36 ft1905-febf volumio[29076]: this.volatileState: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":138136,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 05 15:18:36 ft1905-febf volumio[29076]: info: CoreStateMachine::pushState Jun 05 15:18:36 ft1905-febf volumio[29076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:18:36 ft1905-febf socketio[2397]: socket send Jun 05 15:18:36 ft1905-febf volumio[29076]: SPOTIFY: PUSH STATE SPOTIFY Jun 05 15:18:36 ft1905-febf volumio[29076]: SPOTIFY: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":138136,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 05 15:18:36 ft1905-febf volumio[29076]: this.isVolatile: true, stateService.status: play Jun 05 15:18:36 ft1905-febf volumio[29076]: this.volatileState: {"status":"play","service":"spop","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":138136,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 05 15:18:36 ft1905-febf volumio[29076]: info: CoreStateMachine::pushState Jun 05 15:18:36 ft1905-febf volumio[29076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 15:18:36 ft1905-febf socketio[2397]: socket send Jun 05 15:18:37 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7414. Jun 05 15:18:37 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:37 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:37 ft1905-febf volumio[29784]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:37 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:37 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:39 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7415. Jun 05 15:18:39 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:39 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:39 ft1905-febf volumio[29786]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:39 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:39 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:41 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7416. Jun 05 15:18:41 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:41 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:41 ft1905-febf volumio[29787]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:41 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:41 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:43 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7417. Jun 05 15:18:43 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:43 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:43 ft1905-febf volumio[29788]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:43 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:43 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:44 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:44+08:00" level=trace msg="sent dealer ping" Jun 05 15:18:44 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:44+08:00" level=trace msg="received dealer pong" Jun 05 15:18:46 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7418. Jun 05 15:18:46 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:46 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:46 ft1905-febf volumio[29795]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:46 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:46 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:48 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7419. Jun 05 15:18:48 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:48 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:48 ft1905-febf volumio[29796]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:48 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:48 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:50 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7420. Jun 05 15:18:50 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:50 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:50 ft1905-febf volumio[29800]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:50 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:50 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:52 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7421. Jun 05 15:18:52 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:52 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:52 ft1905-febf volumio[29801]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:52 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:52 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:53 ft1905-febf go-librespot[29263]: time="2025-06-05T15:18:53+08:00" level=debug msg="fetched chunk 12/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:18:55 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7422. Jun 05 15:18:55 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:55 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:55 ft1905-febf volumio[29802]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:55 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:55 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:57 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7423. Jun 05 15:18:57 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:57 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:57 ft1905-febf volumio[29804]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:57 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:57 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:18:59 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7424. Jun 05 15:18:59 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:18:59 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:18:59 ft1905-febf volumio[29805]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:18:59 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:18:59 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:01 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7425. Jun 05 15:19:01 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:01 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:01 ft1905-febf volumio[29815]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:01 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:01 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:02 ft1905-febf volumio[29076]: Device response: { METHOD: 'HTTP/1.1 200 OK', Jun 05 15:19:02 ft1905-febf volumio[29076]: 'CACHE-CONTROL': 'max-age=1800', Jun 05 15:19:02 ft1905-febf volumio[29076]: LOCATION: 'http://192.168.100.104:3030/upnp/root.xml', Jun 05 15:19:02 ft1905-febf volumio[29076]: SERVER: 'Synaudio Music Service/1.0 UPnP/1.1 MusicService/1.0', Jun 05 15:19:02 ft1905-febf volumio[29076]: NT: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:19:02 ft1905-febf volumio[29076]: NTS: 'ssdp:alive', Jun 05 15:19:02 ft1905-febf volumio[29076]: UDN: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd', Jun 05 15:19:02 ft1905-febf volumio[29076]: USN: Jun 05 15:19:02 ft1905-febf volumio[29076]: 'uuid:4a19d95b-5cca-5e42-ad08-dc352c14e5bd::urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:19:02 ft1905-febf volumio[29076]: 'BOOTID.UPNP.ORG': '13447142', Jun 05 15:19:02 ft1905-febf volumio[29076]: 'CONFIGID.UPNP.ORG': '13447142', Jun 05 15:19:02 ft1905-febf volumio[29076]: EXT: '', Jun 05 15:19:02 ft1905-febf volumio[29076]: DATE: '2025-06-05T07:19:02.274592Z' } Jun 05 15:19:02 ft1905-febf volumio[29076]: Device IP: 192.168.100.104 Jun 05 15:19:04 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7426. Jun 05 15:19:04 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:04 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:04 ft1905-febf volumio[29816]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:04 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:04 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:04 ft1905-febf volumio[29076]: Device response: { EXT: '', Jun 05 15:19:04 ft1905-febf volumio[29076]: ST: 'urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:19:04 ft1905-febf volumio[29076]: SERVER: 'Linux/5.4.259-qgki-g6f25851aad0d UPnP/1.0 BubbleUPnP/4.4.9', Jun 05 15:19:04 ft1905-febf volumio[29076]: USN: Jun 05 15:19:04 ft1905-febf volumio[29076]: 'uuid:da1e268e-e6e8-4a0f-ac9a-688b73f80eac::urn:schemas-upnp-org:device:MediaServer:1', Jun 05 15:19:04 ft1905-febf volumio[29076]: 'CACHE-CONTROL': 'max-age=1800', Jun 05 15:19:04 ft1905-febf volumio[29076]: LOCATION: Jun 05 15:19:04 ft1905-febf volumio[29076]: 'http://192.168.100.104:58645/dev/da1e268e-e6e8-4a0f-ac9a-688b73f80eac/desc.xml' } Jun 05 15:19:04 ft1905-febf volumio[29076]: Device IP: 192.168.100.104 Jun 05 15:19:06 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7427. Jun 05 15:19:06 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:06 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:06 ft1905-febf volumio[29817]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:06 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:06 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:08 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7428. Jun 05 15:19:08 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:08 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:08 ft1905-febf volumio[29818]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:08 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:08 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:08 ft1905-febf go-librespot[29263]: time="2025-06-05T15:19:08+08:00" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:2StAeGaKy1gBOllUoAjqxV" Jun 05 15:19:10 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7429. Jun 05 15:19:10 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:10 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:10 ft1905-febf volumio[29822]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:10 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:10 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:13 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7430. Jun 05 15:19:13 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:13 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:13 ft1905-febf volumio[29823]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:13 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:13 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:14 ft1905-febf go-librespot[29263]: time="2025-06-05T15:19:14+08:00" level=trace msg="sent dealer ping" Jun 05 15:19:14 ft1905-febf go-librespot[29263]: time="2025-06-05T15:19:14+08:00" level=trace msg="received dealer pong" Jun 05 15:19:15 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7431. Jun 05 15:19:15 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:15 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:15 ft1905-febf volumio[29828]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:15 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:15 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:17 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7432. Jun 05 15:19:17 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:17 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:17 ft1905-febf volumio[29831]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:17 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:17 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:19 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7433. Jun 05 15:19:19 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:19 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:19 ft1905-febf volumio[29832]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:19 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:19 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:22 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7434. Jun 05 15:19:22 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:22 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:22 ft1905-febf volumio[29960]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:22 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:22 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:23 ft1905-febf shairport-sync[29315]: warning: The output device "plughw:2,0" is busy and can't be used by Shairport Sync at present. Jun 05 15:19:23 ft1905-febf volumio[29076]: info: Airplay started streaming, receiving metadatas Jun 05 15:19:23 ft1905-febf volumio[29076]: info: [AirPlay] Preparing playback. Current service: spop Jun 05 15:19:23 ft1905-febf volumio[29076]: info: [AirPlay] Stopping previous service: Jun 05 15:19:23 ft1905-febf volumio[29076]: verbose: SET VOLATILE Jun 05 15:19:23 ft1905-febf volumio[29076]: info: CoreStateMachine::stop Jun 05 15:19:23 ft1905-febf volumio[29076]: info: CoreStateMachine::serviceStop Jun 05 15:19:23 ft1905-febf volumio[29076]: info: CoreCommandRouter::serviceStop Jun 05 15:19:23 ft1905-febf shairport-sync[29966]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 05 15:19:23 ft1905-febf volumio[29076]: info: Spotify Stop Jun 05 15:19:23 ft1905-febf volumio[29076]: SPOTIFY: SPOTIFY STOP Jun 05 15:19:23 ft1905-febf shairport-sync[29966]: Dload Upload Total Spent Left Speed Jun 05 15:19:23 ft1905-febf volumio[29076]: SPOTIFY: {"status":"play","title":"紅","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://i.scdn.co/image/ab67616d00001e027a2aff746e4093fecd950130","uri":"spotify:track:2StAeGaKy1gBOllUoAjqxV","trackType":"spotify","seek":138136,"duration":269,"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,"volatile":true,"service":"spop"} Jun 05 15:19:23 ft1905-febf volumio[29076]: info: Sending Spotify command to local API: /player/pause Jun 05 15:19:23 ft1905-febf volumio[29076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:19:23 ft1905-febf volumio[29076]: TypeError: this.restartLibrespotDaemon(...).then(...).catch is not a function Jun 05 15:19:23 ft1905-febf volumio[29076]: at ControllerSpotify.stop (webpack://Volumio2/./app/plugins/music_service/spop/index.js?:490:19) Jun 05 15:19:23 ft1905-febf volumio[29076]: at CoreCommandRouter.serviceStop (webpack://Volumio2/./app/index.js?:386:25) Jun 05 15:19:23 ft1905-febf volumio[29076]: at CoreStateMachine.serviceStop (webpack://Volumio2/./app/statemachine.js?:1247:31) Jun 05 15:19:23 ft1905-febf volumio[29076]: at CoreStateMachine.stop (webpack://Volumio2/./app/statemachine.js?:1213:17) Jun 05 15:19:23 ft1905-febf volumio[29076]: at CoreStateMachine.setVolatile (webpack://Volumio2/./app/statemachine.js?:1534:15) Jun 05 15:19:23 ft1905-febf volumio[29076]: at AirPlayInterface.prepareAirplayPlayback (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/index.js?:524:45) Jun 05 15:19:23 ft1905-febf volumio[29076]: at ShairportSyncReaderUDP.eval (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/index.js?:235:12) Jun 05 15:19:23 ft1905-febf volumio[29076]: at ShairportSyncReaderUDP.emit (events.js:198:13) Jun 05 15:19:23 ft1905-febf volumio[29076]: at ShairportSyncReaderUDP.useData (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js?:86:16) Jun 05 15:19:23 ft1905-febf volumio[29076]: at Socket.ShairportSyncReaderUDP._source.on.msg (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js?:21:12) Jun 05 15:19:23 ft1905-febf volumio[29076]: at Socket.emit (events.js:198:13) Jun 05 15:19:23 ft1905-febf volumio[29076]: at UDP.onMessage [as onmessage] (dgram.js:628:8) Jun 05 15:19:23 ft1905-febf volumio[29076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 15:19:23 ft1905-febf shairport-sync[29966]: [79B blob data] Jun 05 15:19:23 ft1905-febf shairport-sync[29967]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 05 15:19:23 ft1905-febf shairport-sync[29967]: Dload Upload Total Spent Left Speed Jun 05 15:19:23 ft1905-febf sudo[29969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jun 05 15:19:23 ft1905-febf sudo[29969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:19:23 ft1905-febf systemd[1]: Stopping go-librespot Daemon... Jun 05 15:19:23 ft1905-febf systemd[1]: go-librespot-daemon.service: Killing process 29763 (go-librespot) with signal SIGKILL. Jun 05 15:19:23 ft1905-febf systemd[1]: go-librespot-daemon.service: Succeeded. Jun 05 15:19:23 ft1905-febf systemd[1]: Stopped go-librespot Daemon. Jun 05 15:19:23 ft1905-febf systemd[1]: Started go-librespot Daemon. Jun 05 15:19:23 ft1905-febf sudo[29969]: pam_unix(sudo:session): session closed for user root Jun 05 15:19:23 ft1905-febf go-librespot[29983]: go-librespot daemon starting... Jun 05 15:19:23 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:23+08:00" level=info msg="running go-librespot 0.2.0" Jun 05 15:19:23 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:23+08:00" level=debug msg="app state loaded" Jun 05 15:19:23 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:23+08:00" level=debug msg="stored credentials not found" Jun 05 15:19:23 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:23+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 05 15:19:24 ft1905-febf shairport-sync[29967]: [79B blob data] Jun 05 15:19:24 ft1905-febf systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 7435. Jun 05 15:19:24 ft1905-febf systemd[1]: Stopped Volspotconnect2 Daemon. Jun 05 15:19:24 ft1905-febf systemd[1]: Started Volspotconnect2 Daemon. Jun 05 15:19:24 ft1905-febf volumio[29992]: /bin/bash: /volumio/app/plugins/music_service/volspotconnect2/startconnect.sh: No such file or directory Jun 05 15:19:24 ft1905-febf systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=127/n/a Jun 05 15:19:24 ft1905-febf systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. Jun 05 15:19:24 ft1905-febf shairport-sync[29966]: [79B blob data] Jun 05 15:19:25 ft1905-febf shairport-sync[29967]: [79B blob data] Jun 05 15:19:25 ft1905-febf shairport-sync[29315]: warning: The output device "plughw:2,0" is no longer busy and will be used by Shairport Sync. Jun 05 15:19:25 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Jun 05 15:19:25 ft1905-febf sudo[29995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 15:18 Jun 05 15:19:25 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:25+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 05 15:19:25 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:25+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 05 15:19:25 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:25+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 05 15:19:25 ft1905-febf sudo[29995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 15:19:25 ft1905-febf go-librespot[29985]: time="2025-06-05T15:19:25+08:00" level=info msg="zeroconf server listening on port 44863" 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