-- Logs begin at Sun 2025-09-14 14:08:02 CEST, end at Sun 2025-09-14 15:27:10 CEST. -- Sep 14 15:26:00 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:00 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:00 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:00 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956039. Sep 14 15:26:01 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:01 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:01 volumio go-librespot[16919]: go-librespot daemon starting... Sep 14 15:26:01 volumio go-librespot[16919]: time="2025-09-14T15:26:01+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:03 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:03 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:04 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956040. Sep 14 15:26:05 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:05 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:05 volumio go-librespot[16985]: go-librespot daemon starting... Sep 14 15:26:05 volumio go-librespot[16985]: time="2025-09-14T15:26:05+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:05 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:05 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:05 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:06 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:06 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:06 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:06 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:06 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:06 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:06 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:06 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:06 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:06 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:06 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:06 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:06 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:06 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:06 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:06 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:06 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:06 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:06 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:06 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:06 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:06 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:06 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:06 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956041. Sep 14 15:26:08 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:08 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:08 volumio go-librespot[17020]: go-librespot daemon starting... Sep 14 15:26:08 volumio go-librespot[17020]: time="2025-09-14T15:26:08+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:09 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:09 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:10 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:11 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:11 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:11 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:11 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:11 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956042. Sep 14 15:26:11 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:11 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:11 volumio go-librespot[17090]: go-librespot daemon starting... Sep 14 15:26:11 volumio go-librespot[17090]: time="2025-09-14T15:26:11+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:11 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:12 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:12 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956043. Sep 14 15:26:14 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:14 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:14 volumio go-librespot[17122]: go-librespot daemon starting... Sep 14 15:26:14 volumio go-librespot[17122]: time="2025-09-14T15:26:14+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:15 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:15 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:16 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:16 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:16 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:16 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:16 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:16 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:16 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:16 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:16 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:16 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:16 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:16 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:16 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:16 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:16 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:16 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:16 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:16 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:16 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:16 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:16 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:16 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:16 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:16 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:17 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:17 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:17 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:17 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:17 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956044. Sep 14 15:26:18 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:18 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:18 volumio go-librespot[17193]: go-librespot daemon starting... Sep 14 15:26:18 volumio go-librespot[17193]: time="2025-09-14T15:26:18+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:18 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:18 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:21 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:21 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956045. Sep 14 15:26:21 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:21 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:21 volumio go-librespot[17211]: go-librespot daemon starting... Sep 14 15:26:21 volumio go-librespot[17211]: time="2025-09-14T15:26:21+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:21 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:21 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:21 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:21 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:22 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:22 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:22 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:22 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:22 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:22 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:22 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:22 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:22 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:22 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:23 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:23 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:24 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:24 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956046. Sep 14 15:26:24 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:24 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:24 volumio go-librespot[17295]: go-librespot daemon starting... Sep 14 15:26:24 volumio go-librespot[17295]: time="2025-09-14T15:26:24+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:26 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:26 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:26 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:26 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:26 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:26 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:26 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:26 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:26 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:26 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:26 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:26 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:26 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:26 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:26 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:26 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:26 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:26 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:26 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:26 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:26 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:27 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:27 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:27 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:27 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:27 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:27 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956047. Sep 14 15:26:27 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:27 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:27 volumio go-librespot[17346]: go-librespot daemon starting... Sep 14 15:26:27 volumio go-librespot[17346]: time="2025-09-14T15:26:27+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:28 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:28 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:28 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:28 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:28 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:28 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:28 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:28 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:28 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:28 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:29 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:29 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:30 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:30 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956048. Sep 14 15:26:31 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:31 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:31 volumio go-librespot[17383]: go-librespot daemon starting... Sep 14 15:26:31 volumio go-librespot[17383]: time="2025-09-14T15:26:31+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:33 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:33 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:33 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:33 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:33 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:33 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:34 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:34 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:34 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:34 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:34 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956049. Sep 14 15:26:34 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:34 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:34 volumio go-librespot[17459]: go-librespot daemon starting... Sep 14 15:26:34 volumio go-librespot[17459]: time="2025-09-14T15:26:34+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:34 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:35 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:36 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:36 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:36 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:36 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:36 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:36 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:36 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:36 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:36 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:36 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:36 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:36 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:36 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:36 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:36 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:36 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:36 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:36 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:36 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:36 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:36 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956050. Sep 14 15:26:37 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:37 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:37 volumio go-librespot[17537]: go-librespot daemon starting... Sep 14 15:26:37 volumio go-librespot[17537]: time="2025-09-14T15:26:37+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:39 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:39 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:39 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:39 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:39 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:39 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:39 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:40 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:40 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:40 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:40 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956051. Sep 14 15:26:40 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:40 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:40 volumio go-librespot[17611]: go-librespot daemon starting... Sep 14 15:26:40 volumio go-librespot[17611]: time="2025-09-14T15:26:40+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:40 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:42 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:42 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:43 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:43 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:43 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:43 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956052. Sep 14 15:26:44 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:44 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:44 volumio go-librespot[17675]: go-librespot daemon starting... Sep 14 15:26:44 volumio go-librespot[17675]: time="2025-09-14T15:26:44+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:44 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:44 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:44 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:44 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:44 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:44 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:44 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:44 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:44 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:44 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:45 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:45 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:45 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:45 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:46 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:46 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:46 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:46 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:46 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:46 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:46 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:46 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:46 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:46 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:46 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:46 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:46 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:46 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:46 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:46 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:46 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:46 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:46 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:46 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:46 volumio volumio[1291]: info: Listing playlists Sep 14 15:26:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956053. Sep 14 15:26:47 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:47 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:47 volumio go-librespot[17712]: go-librespot daemon starting... Sep 14 15:26:47 volumio go-librespot[17712]: time="2025-09-14T15:26:47+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:48 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:48 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:49 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:49 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:49 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:49 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:50 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:50 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:50 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:50 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:50 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956054. Sep 14 15:26:50 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:50 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:50 volumio go-librespot[17783]: go-librespot daemon starting... Sep 14 15:26:50 volumio go-librespot[17783]: time="2025-09-14T15:26:50+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:50 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:51 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:51 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:51 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956055. Sep 14 15:26:53 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:53 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:53 volumio go-librespot[17811]: go-librespot daemon starting... Sep 14 15:26:53 volumio go-librespot[17811]: time="2025-09-14T15:26:53+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:54 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:54 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:54 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:54 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:54 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:54 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:55 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:55 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:55 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:55 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:55 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:26:55 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:26:56 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:26:56 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:26:56 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:26:56 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:26:56 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:26:56 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:26:56 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:56 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:26:56 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:26:56 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:56 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:26:56 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:56 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:56 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:26:56 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:26:56 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:26:56 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:26:56 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:26:56 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:26:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:26:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956056. Sep 14 15:26:57 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:26:57 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:26:57 volumio go-librespot[17883]: go-librespot daemon starting... Sep 14 15:26:57 volumio go-librespot[17883]: time="2025-09-14T15:26:57+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:26:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:26:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:26:57 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:26:57 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:26:58 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: refresh po_token Sep 14 15:26:58 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:26:59 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:26:59 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Sep 14 15:26:59 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Sep 14 15:26:59 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:26:59 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:26:59 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:27:00 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:27:00 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:27:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:27:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956057. Sep 14 15:27:00 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:27:00 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:27:00 volumio go-librespot[17954]: go-librespot daemon starting... Sep 14 15:27:00 volumio go-librespot[17954]: time="2025-09-14T15:27:00+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:27:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:27:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:27:00 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:27:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:27:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956058. Sep 14 15:27:03 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:27:03 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:27:03 volumio go-librespot[17979]: go-librespot daemon starting... Sep 14 15:27:03 volumio go-librespot[17979]: time="2025-09-14T15:27:03+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:27:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:27:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:27:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:27:04 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:27:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:27:04 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:27:05 volumio wpa_supplicant[923]: wlan0: Trying to associate with SSID 'Salej' Sep 14 15:27:05 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:27:05 volumio wpa_supplicant[923]: wlan0: Associated with a0:36:bc:55:73:e8 Sep 14 15:27:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:36:bc:55:73:e8 completed [id=0 id_str=] Sep 14 15:27:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 14 15:27:05 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Sep 14 15:27:05 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:27:05 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:27:05 volumio dhcpcd[1006]: wlan0: carrier acquired Sep 14 15:27:05 volumio dhcpcd[1006]: wlan0: IAID eb:37:1c:48 Sep 14 15:27:06 volumio dhcpcd[1006]: wlan0: soliciting an IPv6 router Sep 14 15:27:06 volumio dhcpcd[1006]: wlan0: soliciting a DHCP lease Sep 14 15:27:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:27:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956059. Sep 14 15:27:06 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:27:06 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:27:06 volumio go-librespot[18042]: go-librespot daemon starting... Sep 14 15:27:06 volumio go-librespot[18042]: time="2025-09-14T15:27:06+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:27:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:27:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:27:08 volumio volumio[1291]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Sep 14 15:27:08 volumio volumio[1291]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Sep 14 15:27:08 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Sep 14 15:27:08 volumio volumio[1291]: Upnp client error: Error: This socket has been ended by the other party Sep 14 15:27:08 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 14 15:27:08 volumio volumio[1291]: Upnp client error: Error: This socket has been ended by the other party Sep 14 15:27:08 volumio volumio[1291]: Upnp client error: Error: This socket has been ended by the other party Sep 14 15:27:08 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:27:08 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:27:08 volumio volumio[1291]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 14 15:27:08 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:27:08 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:27:08 volumio volumio[1291]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 14 15:27:08 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:27:08 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 14 15:27:08 volumio volumio[1291]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 14 15:27:08 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:27:08 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 14 15:27:08 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:27:08 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:27:08 volumio volumio[1291]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Sep 14 15:27:08 volumio volumio[1291]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 14 15:27:08 volumio volumio[1291]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Sep 14 15:27:08 volumio volumio[1291]: at /volumio/node_modules/express/lib/router/index.js:284:7 Sep 14 15:27:08 volumio volumio[1291]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 14 15:27:08 volumio volumio[1291]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 14 15:27:08 volumio volumio[1291]: info: Listing playlists Sep 14 15:27:08 volumio volumio[1291]: info: Listing playlists Sep 14 15:27:09 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 14 15:27:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 14 15:27:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956060. Sep 14 15:27:10 volumio systemd[1]: Stopped go-librespot Daemon. Sep 14 15:27:10 volumio systemd[1]: Started go-librespot Daemon. Sep 14 15:27:10 volumio go-librespot[18054]: go-librespot daemon starting... Sep 14 15:27:10 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 15:27:10 volumio go-librespot[18054]: time="2025-09-14T15:27:10+02:00" level=fatal msg="failed loading config" error="failed to unmarshal configuration: 3 error(s) decoding:\n\n* cannot parse 'audio_buffer_time' as int: strconv.ParseInt: parsing \"${audio_buffer_time}\": invalid syntax\n* cannot parse 'audio_period_count' as int: strconv.ParseInt: parsing \"${audio_period_count}\": invalid syntax\n* cannot parse 'disable_autoplay' as bool: strconv.ParseBool: parsing \"${disable_autoplay}\": invalid syntax" Sep 14 15:27:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 14 15:27:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 14 15:27:10 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Sep 14 15:27:10 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Sep 14 15:27:10 volumio volumio[1291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 15:27:10 volumio volumio[1291]: TypeError: Cannot read property 'name' of undefined Sep 14 15:27:10 volumio volumio[1291]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 14 15:27:10 volumio volumio[1291]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Sep 14 15:27:10 volumio volumio[1291]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Sep 14 15:27:10 volumio volumio[1291]: at Socket.emit (events.js:315:20) Sep 14 15:27:10 volumio volumio[1291]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 14 15:27:10 volumio volumio[1291]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 14 15:27:10 volumio volumio[1291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 15:27:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:36:bc:55:73:e8 reason=0 locally_generated=1 Sep 14 15:27:10 volumio dhcpcd[1006]: wlan0: carrier lost Sep 14 15:27:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 14 15:27:10 volumio wpa_supplicant[923]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 14 15:27:10 volumio sudo[18110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 15:26 Sep 14 15:27:10 volumio sudo[18110]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"