-- Logs begin at Sat 2025-08-30 16:04:58 MDT, end at Sat 2025-08-30 18:35:09 MDT. -- Aug 30 18:34:00 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:00 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:01 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2526. Aug 30 18:34:02 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:02 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:02 minidsp-shd go-librespot[16706]: go-librespot daemon starting... Aug 30 18:34:02 minidsp-shd go-librespot[16706]: time="2025-08-30T18:34:02-06: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" Aug 30 18:34:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:03 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:03 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2527. Aug 30 18:34:05 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:05 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:05 minidsp-shd go-librespot[16724]: go-librespot daemon starting... Aug 30 18:34:05 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:05 minidsp-shd go-librespot[16724]: time="2025-08-30T18:34:05-06: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" Aug 30 18:34:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:06 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:06 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:08 minidsp-shd sudo[16733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 18:34:08 minidsp-shd sudo[16733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:34:08 minidsp-shd sudo[16733]: pam_unix(sudo:session): session closed for user root Aug 30 18:34:08 minidsp-shd sudo[16736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:34:08 minidsp-shd sudo[16736]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:34:08 minidsp-shd sudo[16736]: pam_unix(sudo:session): session closed for user root Aug 30 18:34:08 minidsp-shd volumio[712]: verbose: New Socket.io Connection to 192.168.68.222 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Total Clients: 9 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 30 18:34:08 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:08 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetQueue Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreStateMachine::getQueue Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getQueue Aug 30 18:34:08 minidsp-shd volumio[712]: info: Listing playlists Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 30 18:34:08 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:08 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:08 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:08 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 18:34:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2528. Aug 30 18:34:08 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:08 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:08 minidsp-shd go-librespot[16740]: go-librespot daemon starting... Aug 30 18:34:08 minidsp-shd go-librespot[16740]: time="2025-08-30T18:34:08-06: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" Aug 30 18:34:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:08 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:09 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:09 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:09 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:10 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 18:34:10 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:10 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:10 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:10 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:10 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:10 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:10 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2529. Aug 30 18:34:12 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:12 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:12 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:12 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:12 minidsp-shd go-librespot[16747]: go-librespot daemon starting... Aug 30 18:34:12 minidsp-shd go-librespot[16747]: time="2025-08-30T18:34:12-06: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" Aug 30 18:34:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:12 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:13 minidsp-shd sudo[16755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 18:34:13 minidsp-shd sudo[16755]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:34:13 minidsp-shd sudo[16755]: pam_unix(sudo:session): session closed for user root Aug 30 18:34:13 minidsp-shd sudo[16758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:34:13 minidsp-shd sudo[16758]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:34:13 minidsp-shd sudo[16758]: pam_unix(sudo:session): session closed for user root Aug 30 18:34:13 minidsp-shd volumio[712]: verbose: New Socket.io Connection to 192.168.68.222 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Total Clients: 9 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 30 18:34:13 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:13 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetQueue Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreStateMachine::getQueue Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getQueue Aug 30 18:34:13 minidsp-shd volumio[712]: info: Listing playlists Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 30 18:34:13 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:13 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: Received Get System Info Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:34:13 minidsp-shd volumio[712]: info: Discovery: Getting this device information Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetState Aug 30 18:34:13 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:13 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 18:34:15 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:15 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2530. Aug 30 18:34:15 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:15 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:15 minidsp-shd go-librespot[16762]: go-librespot daemon starting... Aug 30 18:34:15 minidsp-shd go-librespot[16762]: time="2025-08-30T18:34:15-06: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" Aug 30 18:34:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:16 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:16 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:16 minidsp-shd volumio[712]: info: Retrieving Cloud Streaming UI Aug 30 18:34:16 minidsp-shd volumio[712]: info: Getting Tidal Cloud Configuration Aug 30 18:34:16 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:16 minidsp-shd volumio[712]: info: Getting Qobuz Cloud Configuration Aug 30 18:34:16 minidsp-shd volumio[712]: info: Asking plugin for UI Config Aug 30 18:34:16 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:17 minidsp-shd volumio[712]: info: Getting Spotify Cloud Configuration Aug 30 18:34:17 minidsp-shd volumio[712]: info: Asking plugin for UI Config Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:17 minidsp-shd volumio[712]: info: Saving Spotify Acccount Aug 30 18:34:17 minidsp-shd volumio[712]: info: Got it Aug 30 18:34:17 minidsp-shd volumio[712]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 18:34:17 minidsp-shd volumio[712]: info: Got Tidal Cloud Configuration Aug 30 18:34:17 minidsp-shd volumio[712]: info: Got it Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:17 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 18:34:18 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:18 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:18 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 18:34:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2531. Aug 30 18:34:18 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 18:34:18 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:18 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:18 minidsp-shd go-librespot[16769]: go-librespot daemon starting... Aug 30 18:34:18 minidsp-shd go-librespot[16769]: time="2025-08-30T18:34:18-06: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" Aug 30 18:34:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:21 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:21 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2532. Aug 30 18:34:21 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:21 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:21 minidsp-shd go-librespot[16776]: go-librespot daemon starting... Aug 30 18:34:21 minidsp-shd go-librespot[16776]: time="2025-08-30T18:34:21-06: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" Aug 30 18:34:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:21 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioNext Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::next Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::stop Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::stPlaybackTimer Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::updateTrackBlock Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrackBlock Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:23 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::serviceStop Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::serviceStop Aug 30 18:34:23 minidsp-shd volumio[712]: info: ControllerMpd::stop Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:23 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:23 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:23 minidsp-shd volumio[712]: info: Aug 30 18:34:23 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:23 minidsp-shd volumio[712]: info: sendMpdCommand stop took 38 milliseconds Aug 30 18:34:23 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 1 Aug 30 18:34:23 minidsp-shd volumio[712]: info: sendMpdCommand status took 18 milliseconds Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:23 minidsp-shd volumio[712]: info: Aug 30 18:34:23 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:23 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:23 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 30 18:34:23 minidsp-shd volumio[712]: info: sendMpdCommand status took 6 milliseconds Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:23 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:23 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:23 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:23 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:23 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:23 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:24 minidsp-shd volumio[712]: info: No code Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:24 minidsp-shd volumio[712]: info: ------------------------------ 84ms Aug 30 18:34:24 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 68 milliseconds Aug 30 18:34:24 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:24 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:24 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:24 minidsp-shd volumio[712]: info: No code Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:24 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:24 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:24 minidsp-shd volumio[712]: info: ------------------------------ 121ms Aug 30 18:34:24 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:24 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:24 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2533. Aug 30 18:34:25 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:25 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:25 minidsp-shd go-librespot[16784]: go-librespot daemon starting... Aug 30 18:34:25 minidsp-shd go-librespot[16784]: time="2025-08-30T18:34:25-06: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" Aug 30 18:34:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:25 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioSeek Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::seek Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: TRACKBLOCK {"uri":"mnt/USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","service":"mpd","name":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=74&web=April%20Cantelo%2C%20London%20Philharmonic%20Orchestra%2C%20Walter%20S%C3%BCsskind/Messiah/extralarge&path=%2FUSB%2FSamsung_T3_External_Hard_Drive%2FCDs%2FThe%20Messiah%2FHandel's%20Messiah%20London%20Philharmonic&metadata=false","duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::startPlaybackTimer Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::seek Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: error: null Aug 30 18:34:26 minidsp-shd volumio[712]: info: Aug 30 18:34:26 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:26 minidsp-shd volumio[712]: info: Aug 30 18:34:26 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:26 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:26 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:26 minidsp-shd volumio[712]: info: Aug 30 18:34:26 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand status took 40 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand status took 33 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: Aug 30 18:34:26 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand status took 22 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand status took 10 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:26 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: ------------------------------ 125ms Aug 30 18:34:26 minidsp-shd volumio[712]: info: ------------------------------ 125ms Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 103 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 100 milliseconds Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187388,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"613 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:26 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187388,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"613 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:26 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:26 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:26 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:26 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:26 minidsp-shd volumio[712]: info: ------------------------------ 209ms Aug 30 18:34:26 minidsp-shd volumio[712]: info: ------------------------------ 196ms Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:27 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioNext Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::next Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::stPlaybackTimer Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::updateTrackBlock Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrackBlock Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::serviceStop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::serviceStop Aug 30 18:34:28 minidsp-shd volumio[712]: info: ControllerMpd::stop Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: info: Aug 30 18:34:28 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand stop took 29 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 1 Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand status took 13 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:28 minidsp-shd volumio[712]: info: Aug 30 18:34:28 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:28 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand status took 5 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:28 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: No code Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: ------------------------------ 67ms Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 61 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:28 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: No code Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:28 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:28 minidsp-shd volumio[712]: info: ------------------------------ 109ms Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2534. Aug 30 18:34:28 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:28 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:28 minidsp-shd go-librespot[16791]: go-librespot daemon starting... Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd go-librespot[16791]: time="2025-08-30T18:34:28-06: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" Aug 30 18:34:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: Retrieving Cloud Streaming UI Aug 30 18:34:28 minidsp-shd volumio[712]: info: Getting Tidal Cloud Configuration Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: Getting Qobuz Cloud Configuration Aug 30 18:34:28 minidsp-shd volumio[712]: info: Asking plugin for UI Config Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: Getting Spotify Cloud Configuration Aug 30 18:34:28 minidsp-shd volumio[712]: info: Asking plugin for UI Config Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: Saving Spotify Acccount Aug 30 18:34:28 minidsp-shd volumio[712]: info: Got it Aug 30 18:34:28 minidsp-shd volumio[712]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 18:34:28 minidsp-shd volumio[712]: info: Got Tidal Cloud Configuration Aug 30 18:34:28 minidsp-shd volumio[712]: info: Got it Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPlay Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreStateMachine::startPlaybackTimer Aug 30 18:34:28 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:28 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand stop took 2 milliseconds Aug 30 18:34:28 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 18:34:28 minidsp-shd volumio[712]: info: Aug 30 18:34:28 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:28 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:28 minidsp-shd volumio[712]: info: sendMpdCommand clear took 4 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac" Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:29 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:29 minidsp-shd volumio[712]: error: updateQueue error: null Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:29 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 11ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand add "USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac" took 8 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 7ms Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand play Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:29 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 13ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand play took 9 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 8ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 13 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 8 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 18 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 8 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:29 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 89ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 104ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 70 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:29 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:29 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 174ms Aug 30 18:34:29 minidsp-shd volumio[712]: info: ------------------------------ 162ms Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioSeek Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::seek Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: TRACKBLOCK {"uri":"mnt/USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","service":"mpd","name":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=74&web=April%20Cantelo%2C%20London%20Philharmonic%20Orchestra%2C%20Walter%20S%C3%BCsskind/Messiah/extralarge&path=%2FUSB%2FSamsung_T3_External_Hard_Drive%2FCDs%2FThe%20Messiah%2FHandel's%20Messiah%20London%20Philharmonic&metadata=false","duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::startPlaybackTimer Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::seek Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:29 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:29 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:29 minidsp-shd volumio[712]: error: null Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:29 minidsp-shd volumio[712]: info: Aug 30 18:34:29 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:29 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 34 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: info: sendMpdCommand status took 17 milliseconds Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:29 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:30 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 21 milliseconds Aug 30 18:34:30 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:30 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:30 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:30 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:30 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:30 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:30 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:30 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:30 minidsp-shd volumio[712]: info: ------------------------------ 137ms Aug 30 18:34:30 minidsp-shd volumio[712]: info: ------------------------------ 121ms Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:30 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2535. Aug 30 18:34:31 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:31 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:31 minidsp-shd go-librespot[16799]: go-librespot daemon starting... Aug 30 18:34:31 minidsp-shd go-librespot[16799]: time="2025-08-30T18:34:31-06: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" Aug 30 18:34:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:33 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:33 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:33 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2536. Aug 30 18:34:34 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:34 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:34 minidsp-shd go-librespot[16844]: go-librespot daemon starting... Aug 30 18:34:34 minidsp-shd go-librespot[16844]: time="2025-08-30T18:34:34-06: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" Aug 30 18:34:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioNext Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::next Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::stop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::stPlaybackTimer Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::updateTrackBlock Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrackBlock Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::serviceStop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::serviceStop Aug 30 18:34:35 minidsp-shd volumio[712]: info: ControllerMpd::stop Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: info: Aug 30 18:34:35 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:35 minidsp-shd volumio[712]: info: sendMpdCommand stop took 28 milliseconds Aug 30 18:34:35 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 1 Aug 30 18:34:35 minidsp-shd volumio[712]: info: sendMpdCommand status took 12 milliseconds Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:35 minidsp-shd volumio[712]: info: Aug 30 18:34:35 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:35 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:35 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 18:34:35 minidsp-shd volumio[712]: info: sendMpdCommand status took 4 milliseconds Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:35 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: No code Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: ------------------------------ 66ms Aug 30 18:34:35 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 65 milliseconds Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:35 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:35 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: No code Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:35 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:35 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:35 minidsp-shd volumio[712]: info: ------------------------------ 106ms Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:35 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:36 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:36 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPlay Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::startPlaybackTimer Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::clearAddPlayTracks USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand stop took 5 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:37 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand clear took 6 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand add "USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac" Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:37 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:37 minidsp-shd volumio[712]: error: updateQueue error: null Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:37 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 20ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand add "USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac" took 13 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 13ms Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand play Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces system playlist update Aug 30 18:34:37 minidsp-shd volumio[712]: info: Ignoring MPD Status Update Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 19ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand play took 12 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 12ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 19 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 12 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 18 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 7 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:37 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 96ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 108ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 73 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 70 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:37 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService play Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:34:37 minidsp-shd volumio[712]: info: Received an update from plugin. extracting info from payload Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 175ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 164ms Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioNext Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::next Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::stPlaybackTimer Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::updateTrackBlock Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrackBlock Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::serviceStop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::serviceStop Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::stop Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand stop took 28 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::play index undefined Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 1 Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 13 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: Aug 30 18:34:37 minidsp-shd volumio[712]: ---------------------------- MPD announces state update: player Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::getState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand status took 4 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseState Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: No code Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: ------------------------------ 63ms Aug 30 18:34:37 minidsp-shd volumio[712]: info: sendMpdCommand playlistinfo took 63 milliseconds Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: ControllerMpd::parseTrackInfo Aug 30 18:34:37 minidsp-shd volumio[712]: info: ControllerMpd::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Messiah: He shall feed His flock like a shepherd","artist":"April Cantelo, London Philharmonic Orchestra, Walter Süsskind","album":"Messiah","uri":"USB/Samsung_T3_External_Hard_Drive/CDs/The Messiah/Handel's Messiah London Philharmonic/April Cantelo, London Philharmonic Orchestra, Walter Süsskind-Messiah-(18) -Messiah- He shall feed His flock like a shepherd - .flac","trackType":"flac"} Aug 30 18:34:37 minidsp-shd volumio[712]: verbose: CURRENT POSITION 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState stateService stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::syncState currentStatus stop Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:37 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:37 minidsp-shd volumio[712]: info: No code Aug 30 18:34:37 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:38 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:38 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:38 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:38 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:38 minidsp-shd volumio[712]: info: ------------------------------ 105ms Aug 30 18:34:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2537. Aug 30 18:34:38 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:38 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:38 minidsp-shd go-librespot[16851]: go-librespot daemon starting... Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd go-librespot[16851]: time="2025-08-30T18:34:38-06: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" Aug 30 18:34:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:38 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:38 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:39 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:39 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:41 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:34:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2538. Aug 30 18:34:41 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:41 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:41 minidsp-shd go-librespot[16858]: go-librespot daemon starting... Aug 30 18:34:41 minidsp-shd go-librespot[16858]: time="2025-08-30T18:34:41-06: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" Aug 30 18:34:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:41 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:42 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:42 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2539. Aug 30 18:34:44 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:44 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:44 minidsp-shd go-librespot[16866]: go-librespot daemon starting... Aug 30 18:34:44 minidsp-shd go-librespot[16866]: time="2025-08-30T18:34:44-06: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" Aug 30 18:34:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:44 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:45 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:45 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2540. Aug 30 18:34:47 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:47 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:47 minidsp-shd go-librespot[16874]: go-librespot daemon starting... Aug 30 18:34:47 minidsp-shd go-librespot[16874]: time="2025-08-30T18:34:47-06: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" Aug 30 18:34:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:47 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:48 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:48 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2541. Aug 30 18:34:51 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:51 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:51 minidsp-shd go-librespot[16881]: go-librespot daemon starting... Aug 30 18:34:51 minidsp-shd go-librespot[16881]: time="2025-08-30T18:34:51-06: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" Aug 30 18:34:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:51 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:51 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:51 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2542. Aug 30 18:34:54 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:54 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:54 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:54 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:54 minidsp-shd go-librespot[16888]: go-librespot daemon starting... Aug 30 18:34:54 minidsp-shd go-librespot[16888]: time="2025-08-30T18:34:54-06: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" Aug 30 18:34:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:54 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Aug 30 18:34:55 minidsp-shd volumio[712]: info: CorePlayQueue::getTrack 0 Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:34:55 minidsp-shd volumio[712]: Cannot find translation for source Presets Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioStop Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreStateMachine::stop Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:55 minidsp-shd volumio[712]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"USB","disableUiControls":true,"albumart":"/albumart"} Aug 30 18:34:55 minidsp-shd volumio[712]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 18:34:55 minidsp-shd volumio[712]: info: Preload queue cleared Aug 30 18:34:56 minidsp-shd volumio[712]: info: CoreCommandRouter::servicePushState Aug 30 18:34:56 minidsp-shd volumio[712]: info: CoreStateMachine::pushState Aug 30 18:34:56 minidsp-shd volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:34:56 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioPushState Aug 30 18:34:56 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:34:56 minidsp-shd volumio[712]: info: MRS: Pushing multiroomSync output Aug 30 18:34:56 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:56 minidsp-shd volumio[712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Aug 30 18:34:57 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:34:57 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:34:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:34:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2543. Aug 30 18:34:57 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:34:57 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:34:57 minidsp-shd go-librespot[16895]: go-librespot daemon starting... Aug 30 18:34:57 minidsp-shd go-librespot[16895]: time="2025-08-30T18:34:57-06: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" Aug 30 18:34:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:34:57 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:35:00 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:35:00 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:35:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:35:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2544. Aug 30 18:35:00 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:35:00 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:35:00 minidsp-shd go-librespot[16903]: go-librespot daemon starting... Aug 30 18:35:00 minidsp-shd go-librespot[16903]: time="2025-08-30T18:35:00-06: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" Aug 30 18:35:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:35:00 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:35:03 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:35:03 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:35:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:35:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2545. Aug 30 18:35:04 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:35:04 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:35:04 minidsp-shd go-librespot[16911]: go-librespot daemon starting... Aug 30 18:35:04 minidsp-shd go-librespot[16911]: time="2025-08-30T18:35:04-06: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" Aug 30 18:35:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:35:04 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:35:06 minidsp-shd volumio[712]: info: Initializing connection to go-librespot Websocket Aug 30 18:35:06 minidsp-shd volumio[712]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 18:35:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 30 18:35:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2546. Aug 30 18:35:07 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Aug 30 18:35:07 minidsp-shd systemd[1]: Started go-librespot Daemon. Aug 30 18:35:07 minidsp-shd go-librespot[16919]: go-librespot daemon starting... Aug 30 18:35:07 minidsp-shd go-librespot[16919]: time="2025-08-30T18:35:07-06: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" Aug 30 18:35:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:35:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 18:35:08 minidsp-shd volumio[712]: info: CoreCommandRouter::volumioSeek Aug 30 18:35:08 minidsp-shd volumio[712]: info: CoreStateMachine::seek Aug 30 18:35:08 minidsp-shd volumio[712]: info: [1756600508937] inputs::seek to 0 Aug 30 18:35:08 minidsp-shd volumio[712]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:35:08 minidsp-shd volumio[712]: TypeError: this.sendSpopCommand is not a function Aug 30 18:35:08 minidsp-shd volumio[712]: at inputs.seek (/volumio/app/plugins/music_service/inputs/inputs_real:1:2486) Aug 30 18:35:08 minidsp-shd volumio[712]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1084:20) Aug 30 18:35:08 minidsp-shd volumio[712]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1421:28) Aug 30 18:35:08 minidsp-shd volumio[712]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:153:33) Aug 30 18:35:08 minidsp-shd volumio[712]: at Socket.emit (events.js:400:28) Aug 30 18:35:08 minidsp-shd volumio[712]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 30 18:35:08 minidsp-shd volumio[712]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 30 18:35:08 minidsp-shd volumio[712]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:35:09 minidsp-shd sudo[16936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 18:34 Aug 30 18:35:09 minidsp-shd sudo[16936]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"