-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2025-12-29 19:21:03 CST. -- Dec 29 19:20:00 so1905-bfc4 sudo[15235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:00 so1905-bfc4 sudo[15239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 19:20:00 so1905-bfc4 sudo[15235]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:00 so1905-bfc4 sudo[15239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:00 so1905-bfc4 sudo[15239]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [alsa_controller] Creating shared var alsa.outputdevice='2' Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: Loading plugin "wizard"... Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: Loading plugin "volumio_command_line_client"... Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: Loading plugin "upnp"... Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: [1767007200467] Starting Upmpd Daemon Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: Loading plugin "my_music"... Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Dec 29 19:20:00 so1905-bfc4 volumio[15193]: info: Loading plugin "mpd"... Dec 29 19:20:00 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Dec 29 19:20:01 so1905-bfc4 volumio[15193]: error: !!!! WARNING !!!! Dec 29 19:20:01 so1905-bfc4 volumio[15193]: error: The plugin music_service/mpd failed to load, setting it to stopped. Error: Error: package.json does not exist at /node_modules/udev/package.json Dec 29 19:20:01 so1905-bfc4 volumio[15193]: error: Stack trace: Error: package.json does not exist at /node_modules/udev/package.json Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at Object.exports.find (webpack://Volumio2/./node_modules/udev/node_modules/node-pre-gyp/lib/pre-binding.js?:18:15) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at eval (webpack://Volumio2/./node_modules/udev/udev.js?:4:27) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at Object../node_modules/udev/udev.js (/volumio/index.js:19:102) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at __webpack_require__ (/volumio/index.js:6550:2825) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:14:12) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at Object../app/plugins/music_service/mpd/index.js (/volumio/index.js:103:128) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at __webpack_require__ (/volumio/index.js:6550:2825) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at webpackContext (webpack://Volumio2/./app/plugins/_sync_index\.js$?:41:9) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at PluginManager.loadCorePlugin (webpack://Volumio2/./app/pluginmanager.js?:169:31) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: at eval (webpack://Volumio2/./app/pluginmanager.js?:277:34) Dec 29 19:20:01 so1905-bfc4 volumio[15193]: error: !!!! WARNING !!!! Dec 29 19:20:01 so1905-bfc4 volumio[15193]: info: Loading plugin "upnp_browser"... Dec 29 19:20:01 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Dec 29 19:20:01 so1905-bfc4 volumio[15193]: info: Loading plugin "networkfs"... Dec 29 19:20:01 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Dec 29 19:20:01 so1905-bfc4 volumio[15193]: [networkfs] Starting IP address watcher Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [networkfs] Started network interface ip monitor watcher Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: Loading plugin "alarm-clock"... Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: Loading plugin "airplay_emulation"... Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Dec 29 19:20:02 so1905-bfc4 volumio[15193]: Shairport Sync D-Bus 服务已注册。 Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [airplay_emulation] Starting Shairport Sync Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [airplay_emulation] [AirPlay] Started coverart directory watcher Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: Loading plugin "last_100"... Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: Loading plugin "spop"... Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/spop/index.js Dec 29 19:20:02 so1905-bfc4 volumio[15193]: info: Loading plugin "webradio"... Dec 29 19:20:02 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Dec 29 19:20:02 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 29 19:20:02 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:02 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:03 so1905-bfc4 go-librespot[15267]: go-librespot daemon starting... Dec 29 19:20:03 so1905-bfc4 go-librespot[15269]: time="2025-12-29T19:20:03+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:03 so1905-bfc4 go-librespot[15269]: time="2025-12-29T19:20:03+08:00" level=debug msg="app state loaded" Dec 29 19:20:03 so1905-bfc4 go-librespot[15269]: time="2025-12-29T19:20:03+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:03 so1905-bfc4 go-librespot[15269]: time="2025-12-29T19:20:03+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:03 so1905-bfc4 go-librespot[15269]: time="2025-12-29T19:20:03+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:03 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:03 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:05 so1905-bfc4 volumio[15193]: info: Loading plugin "yandex_browser"... Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [yandex_browser] Yandex plugin started Dec 29 19:20:05 so1905-bfc4 volumio[15193]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Dec 29 19:20:05 so1905-bfc4 volumio[15193]: info: Loading plugin "i2s_dacs"... Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [i2s_dacs] I2S DAC not set, start Auto-detection Dec 29 19:20:05 so1905-bfc4 volumio[15193]: info: Loading plugin "volumiodiscovery"... Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** For more information see Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 19:20:05 so1905-bfc4 volumio[15193]: *** WARNING *** For more information see Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** For more information see Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 29 19:20:05 so1905-bfc4 node[15193]: *** WARNING *** For more information see Dec 29 19:20:05 so1905-bfc4 volumio[15193]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 29 19:20:05 so1905-bfc4 volumio[15193]: [volumiodiscovery] Discovery: Started advertising with name: SO1905-bfc4 Dec 29 19:20:05 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "outputs"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "albumart"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Plugin example_plugin is not enabled Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "inputs"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "pandora"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Applying required configuration parameters for plugin pandora Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "podcast"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [podcast] ControllerPodcast::constructor Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "qobuz"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Dec 29 19:20:06 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 29 19:20:06 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:06 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:06 so1905-bfc4 go-librespot[15280]: go-librespot daemon starting... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "tidal"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [tidal] [1767007206973] ControllerTidalPlugin::constructor Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: [1767007206976] ControllerTidalPlugin::onVolumioStart Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Plugin volspotconnect2 is not enabled Dec 29 19:20:06 so1905-bfc4 volumio[15193]: info: Loading plugin "updater_comm"... Dec 29 19:20:06 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Dec 29 19:20:07 so1905-bfc4 go-librespot[15282]: time="2025-12-29T19:20:07+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:07 so1905-bfc4 go-librespot[15282]: time="2025-12-29T19:20:07+08:00" level=debug msg="app state loaded" Dec 29 19:20:07 so1905-bfc4 go-librespot[15282]: time="2025-12-29T19:20:07+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:07 so1905-bfc4 go-librespot[15282]: time="2025-12-29T19:20:07+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:07 so1905-bfc4 go-librespot[15282]: time="2025-12-29T19:20:07+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:07 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:07 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: Plugin mpdemulation is not enabled Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: Loading plugin "rest_api"... Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: Loading plugin "websocket"... Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: ___________ START PLUGINS ___________ Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207307] CoreMusicLibrary::Adding element Media Servers Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: SSDP Client initialized. Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Starting initial SSDP search... Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Discovering on interface: lo Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Discovering on interface: wlan0 Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207459] CoreMusicLibrary::Adding element Last_100 Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [spop] Creating Spotify config file Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207514] CoreMusicLibrary::Adding element Webradio Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [yandex_browser] Yandex plugin started Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207548] ControllerYandexPlugin::addToBrowseSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207549] CoreMusicLibrary::Adding element Yandex Music Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:printToastMessage Dec 29 19:20:07 so1905-bfc4 volumio[15193]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207567] CoreMusicLibrary::Adding element Pandora Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [pandora] [1767007207575] ControllerPandora::initializeMQTT Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [pandora] [1767007207577] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207591] CoreMusicLibrary::Adding element Podcast Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207599] CoreMusicLibrary::Adding element Qobuz Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207614] ControllerTidalPlugin::onStart Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207623] ControllerTidalPlugin::addToBrowseSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: [1767007207624] CoreMusicLibrary::Adding element Tidal Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: Loading i18n strings for locale en Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Updating browse sources language Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Cannot find translation for sourceMedia Servers Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Cannot find translation for sourceYandex Music Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Cannot find translation for sourcePodcast Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::initPlayerControls Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: BOOT COMPLETED Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [Metrics] CommandRouter: 10s 546.66ms Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 19:20:07 so1905-bfc4 volumio[15193]: aplay: main:852: audio open error: Device or resource busy Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Cannot play startup sound Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::Close All Modals sent Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::Close All Modals sent Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:07 so1905-bfc4 volumio[15193]: Express server listening on port 3000 Dec 29 19:20:07 so1905-bfc4 volumio[15193]: [Metrics] WebUI: 11s 360.78ms Dec 29 19:20:07 so1905-bfc4 volumio[15193]: (node:15193) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [pandora] [1767007208126] ControllerPandora::validateAndSetAccountOptions Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [pandora] [1767007208135] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreStateMachine::resetVolumioState Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreStateMachine::getcurrentVolume Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioRetrievevolume Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: this.stateMachine.currentVolume: null, vol: 0 Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [airplay_emulation] Sending DBus command: /usr/bin/dbus-send --system --print-reply --dest=org.gnome.ShairportSync "/org/gnome/ShairportSync" org.gnome.ShairportSync.RemoteControl.SetAirplayVolume double:-30.00 Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreStateMachine::pushState Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 19:20:08 so1905-bfc4 volumio[15193]: [rest_api] API:pushState Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreStateMachine::updateTrackBlock Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CorePlayQueue::getTrackBlock Dec 29 19:20:08 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioRetrievevolume Dec 29 19:20:09 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:09 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:09 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:09 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:09 so1905-bfc4 volumio[15193]: [airplay_emulation] DBus command succeeded: SetAirplayVolume Dec 29 19:20:09 so1905-bfc4 volumio[15193]: [networkfs] IP address changed from "" to "192.168.2.115" Dec 29 19:20:09 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 29 19:20:09 so1905-bfc4 volumio[15316]: Forking 1 albumart workers Dec 29 19:20:09 so1905-bfc4 sudo[15337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 29 19:20:10 so1905-bfc4 sudo[15337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:10 so1905-bfc4 sudo[15353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 29 19:20:10 so1905-bfc4 sudo[15346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 29 19:20:10 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 29 19:20:10 so1905-bfc4 sudo[15337]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:10 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:10 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:10 so1905-bfc4 volumio[15193]: [networkfs] Connecting to wireless device: undefined Dec 29 19:20:10 so1905-bfc4 go-librespot[15374]: go-librespot daemon starting... Dec 29 19:20:10 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 29 19:20:10 so1905-bfc4 sudo[15346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:10 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 29 19:20:10 so1905-bfc4 sudo[15353]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:10 so1905-bfc4 sudo[15380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 19:20:10 so1905-bfc4 sudo[15353]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:10 so1905-bfc4 sudo[15369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 19:20:10 so1905-bfc4 sudo[15346]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:10 so1905-bfc4 sudo[15380]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:10 so1905-bfc4 sudo[15369]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:10 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 29 19:20:10 so1905-bfc4 sudo[15380]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:10 so1905-bfc4 sudo[15369]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:10 so1905-bfc4 go-librespot[15405]: time="2025-12-29T19:20:10+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:10 so1905-bfc4 go-librespot[15405]: time="2025-12-29T19:20:10+08:00" level=debug msg="app state loaded" Dec 29 19:20:10 so1905-bfc4 go-librespot[15405]: time="2025-12-29T19:20:10+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:10 so1905-bfc4 go-librespot[15405]: time="2025-12-29T19:20:10+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:10 so1905-bfc4 go-librespot[15405]: time="2025-12-29T19:20:10+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:10 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:10 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:11 so1905-bfc4 sudo[15416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 19:20:11 so1905-bfc4 sudo[15416]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:11 so1905-bfc4 sudo[15416]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:11 so1905-bfc4 sudo[15426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 19:20:11 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 19:20:11 so1905-bfc4 sudo[15426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:11 so1905-bfc4 volumio[15193]: [spop] Spotify config file written Dec 29 19:20:11 so1905-bfc4 sudo[15426]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:11 so1905-bfc4 sudo[15436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Dec 29 19:20:11 so1905-bfc4 sudo[15436]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:11 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:11 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:11 so1905-bfc4 sudo[15436]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:11 so1905-bfc4 go-librespot[15443]: go-librespot daemon starting... Dec 29 19:20:12 so1905-bfc4 go-librespot[15448]: time="2025-12-29T19:20:12+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:12 so1905-bfc4 go-librespot[15448]: time="2025-12-29T19:20:12+08:00" level=debug msg="app state loaded" Dec 29 19:20:12 so1905-bfc4 go-librespot[15448]: time="2025-12-29T19:20:12+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:12 so1905-bfc4 go-librespot[15448]: time="2025-12-29T19:20:12+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:12 so1905-bfc4 go-librespot[15448]: time="2025-12-29T19:20:12+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:12 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:12 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:12 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:12 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:12 so1905-bfc4 volumio[15349]: Starting albumart workers Dec 29 19:20:12 so1905-bfc4 volumio[15193]: [rest_api] API:emitFavourites Dec 29 19:20:12 so1905-bfc4 volumio[15193]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Dec 29 19:20:12 so1905-bfc4 volumio[15193]: sudo: /usr/sbin/i2cdetect: command not found Dec 29 19:20:12 so1905-bfc4 volumio[15193]: [spop] No need to fix Spotify hosts Dec 29 19:20:13 so1905-bfc4 volumio[15193]: (node:15193) UnhandledPromiseRejectionWarning: Error: GET请求失败: Error: Client network socket disconnected before secure TLS connection was established Dec 29 19:20:13 so1905-bfc4 volumio[15193]: at YandexRequest.get (webpack://Volumio2/./app/plugins/music_service/yandex_browser/utils/request.js?:106:19) Dec 29 19:20:13 so1905-bfc4 volumio[15193]: at process._tickCallback (internal/process/next_tick.js:68:7) Dec 29 19:20:13 so1905-bfc4 volumio[15193]: (node:15193) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). (rejection id: 2) Dec 29 19:20:13 so1905-bfc4 volumio[15193]: (node:15193) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 29 19:20:13 so1905-bfc4 volumio[15193]: Upnp client error 2: Error: This socket has been ended by the other party Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:13 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:13 so1905-bfc4 volumio[15193]: [rest_api] API:printToastMessage Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreStateMachine::setRepeat null single undefined Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreStateMachine::pushState Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 19:20:13 so1905-bfc4 volumio[15193]: [rest_api] API:pushState Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreStateMachine::setRandom null Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreStateMachine::pushState Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 19:20:13 so1905-bfc4 volumio[15193]: [rest_api] API:pushState Dec 29 19:20:13 so1905-bfc4 volumio[15193]: Upnp client error 2: Error: This socket has been ended by the other party Dec 29 19:20:13 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:13 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [airplay_emulation] Starting Shairport Sync Dec 29 19:20:14 so1905-bfc4 volumio[15193]: Discovery: adding 1262c1f4-267b-4e25-a601-bdb1d988c032 Dec 29 19:20:14 so1905-bfc4 volumio[15193]: info: mDNS: Found device FT1905-c7dc Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:14 so1905-bfc4 sudo[15474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 19:20:14 so1905-bfc4 sudo[15474]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:14 so1905-bfc4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 29 19:20:14 so1905-bfc4 volumio[15193]: Discovery: adding 959727d2-66e3-4c30-97a8-ae668933bfc4 Dec 29 19:20:14 so1905-bfc4 volumio[15193]: info: mDNS: Found device SO1905-bfc4 Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:14 so1905-bfc4 volumio[15193]: Discovery: this is already registered, 959727d2-66e3-4c30-97a8-ae668933bfc4 Dec 29 19:20:14 so1905-bfc4 volumio[15193]: info: mDNS: Found device SO1905-bfc4 Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:14 so1905-bfc4 sudo[15484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 29 19:20:14 so1905-bfc4 sudo[15484]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 19:20:14 so1905-bfc4 sudo[15484]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:14 so1905-bfc4 systemd[1]: shairport-sync.service: Succeeded. Dec 29 19:20:14 so1905-bfc4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 29 19:20:14 so1905-bfc4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 29 19:20:14 so1905-bfc4 sudo[15474]: pam_unix(sudo:session): session closed for user root Dec 29 19:20:14 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:14 so1905-bfc4 volumio[15193]: [rest_api] API:pushMultiroomDevices Dec 29 19:20:15 so1905-bfc4 volumio[15193]: [airplay_emulation] Shairport-Sync Started Dec 29 19:20:15 so1905-bfc4 volumio[15193]: Error adding Membership: Error: addMembership EINVAL Dec 29 19:20:15 so1905-bfc4 volumio[15193]: [upnp] Upmpdcli Daemon Started Dec 29 19:20:15 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 29 19:20:15 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:15 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:15 so1905-bfc4 go-librespot[15515]: go-librespot daemon starting... Dec 29 19:20:16 so1905-bfc4 go-librespot[15519]: time="2025-12-29T19:20:16+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:16 so1905-bfc4 go-librespot[15519]: time="2025-12-29T19:20:16+08:00" level=debug msg="app state loaded" Dec 29 19:20:16 so1905-bfc4 go-librespot[15519]: time="2025-12-29T19:20:16+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:16 so1905-bfc4 go-librespot[15519]: time="2025-12-29T19:20:16+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:16 so1905-bfc4 go-librespot[15519]: time="2025-12-29T19:20:16+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:16 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:16 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:16 so1905-bfc4 volumio[15193]: [spop] go-librespot daemon successfully initialized Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:18 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 19:20:18 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"desc":"getToneInfo","index":5,"getinfo":4} Dec 29 19:20:18 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"desc":"getSrctotal","index":5,"getinfo":0} Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 19:20:18 so1905-bfc4 volumio[15193]: error: Could not retrieve plugin audio_interface multiroom Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioGetQueue Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CoreStateMachine::getQueue Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: CorePlayQueue::getQueue Dec 29 19:20:18 so1905-bfc4 volumio[15193]: info: Listing playlists Dec 29 19:20:19 so1905-bfc4 volumio[15193]: [spop] Initializing connection to go-librespot Websocket Dec 29 19:20:19 so1905-bfc4 volumio[15193]: [spop] Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 19:20:19 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 29 19:20:19 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:19 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:19 so1905-bfc4 go-librespot[15539]: go-librespot daemon starting... Dec 29 19:20:19 so1905-bfc4 go-librespot[15541]: time="2025-12-29T19:20:19+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:19 so1905-bfc4 go-librespot[15541]: time="2025-12-29T19:20:19+08:00" level=debug msg="app state loaded" Dec 29 19:20:19 so1905-bfc4 go-librespot[15541]: time="2025-12-29T19:20:19+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:19 so1905-bfc4 go-librespot[15541]: time="2025-12-29T19:20:19+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:19 so1905-bfc4 go-librespot[15541]: time="2025-12-29T19:20:19+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:19 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:19 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:22 so1905-bfc4 volumio[15193]: [spop] Initializing connection to go-librespot Websocket Dec 29 19:20:22 so1905-bfc4 volumio[15193]: [spop] Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 19:20:22 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 29 19:20:22 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:22 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:23 so1905-bfc4 go-librespot[15553]: go-librespot daemon starting... Dec 29 19:20:23 so1905-bfc4 go-librespot[15555]: time="2025-12-29T19:20:23+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:23 so1905-bfc4 go-librespot[15555]: time="2025-12-29T19:20:23+08:00" level=debug msg="app state loaded" Dec 29 19:20:23 so1905-bfc4 go-librespot[15555]: time="2025-12-29T19:20:23+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:23 so1905-bfc4 go-librespot[15555]: time="2025-12-29T19:20:23+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:23 so1905-bfc4 go-librespot[15555]: time="2025-12-29T19:20:23+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:23 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:23 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:25 so1905-bfc4 volumio[15193]: [spop] Initializing connection to go-librespot Websocket Dec 29 19:20:25 so1905-bfc4 volumio[15193]: [spop] Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 19:20:26 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 29 19:20:26 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:26 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:26 so1905-bfc4 go-librespot[15569]: go-librespot daemon starting... Dec 29 19:20:26 so1905-bfc4 go-librespot[15573]: time="2025-12-29T19:20:26+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:26 so1905-bfc4 go-librespot[15573]: time="2025-12-29T19:20:26+08:00" level=debug msg="app state loaded" Dec 29 19:20:26 so1905-bfc4 go-librespot[15573]: time="2025-12-29T19:20:26+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:26 so1905-bfc4 go-librespot[15573]: time="2025-12-29T19:20:26+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:26 so1905-bfc4 go-librespot[15573]: time="2025-12-29T19:20:26+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 29 19:20:26 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 19:20:26 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 19:20:28 so1905-bfc4 volumio[15193]: [spop] Initializing connection to go-librespot Websocket Dec 29 19:20:28 so1905-bfc4 volumio[15193]: [spop] Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 19:20:29 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 29 19:20:29 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 29 19:20:29 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 29 19:20:30 so1905-bfc4 go-librespot[15671]: go-librespot daemon starting... Dec 29 19:20:30 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:30+08:00" level=info msg="running go-librespot 0.0.0" Dec 29 19:20:30 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:30+08:00" level=debug msg="app state loaded" Dec 29 19:20:30 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:30+08:00" level=debug msg="stored credentials not found" Dec 29 19:20:30 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:30+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:31+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:31+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:31+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:31+08:00" level=info msg="zeroconf server listening on port 37941" Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: 2025/12/29 19:20:31 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Dec 29 19:20:31 so1905-bfc4 volumio[15193]: [spop] Initializing connection to go-librespot Websocket Dec 29 19:20:31 so1905-bfc4 go-librespot[15674]: time="2025-12-29T19:20:31+08:00" level=debug msg="new websocket client" Dec 29 19:20:31 so1905-bfc4 volumio[15193]: [spop] Connection to go-librespot Websocket established Dec 29 19:20:34 so1905-bfc4 volumio[15193]: [spop] Getting Spotify volume Dec 29 19:20:34 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:34 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:37 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 19:20:37 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 19:20:44 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 29 19:20:44 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 29 19:20:44 so1905-bfc4 volumio[15193]: [websocket] [pushSrctotal] {"total":8,"cur":0,"curId":"stream:1","source":[{"id":"stream:1","name":"Streaming","code":"stream","type":0},{"id":"bt:1","name":"Bluetooth","code":"bt","type":4},{"id":"aux:1","name":"AUX","code":"aux","type":6},{"id":"optic:1","name":"DIGITAL OPT1","code":"optic","type":5},{"id":"optic:2","name":"DIGITAL OPT2","code":"optic","type":5},{"id":"arc:1","name":"HDMI ARC","code":"arc","type":5}]} Dec 29 19:20:44 so1905-bfc4 volumio[15193]: [websocket] [pushCmd] {"desc":"getSourceInfo","index":5,"getinfo":1} Dec 29 19:20:44 so1905-bfc4 volumio[15193]: [websocket] [pushSourceInfo] {"source":0,"sourceCode":"stream","sourceId":"stream:1"} Dec 29 19:21:02 so1905-bfc4 volumio[15193]: info: CoreCommandRouter::volumioReplaceAndPlayItems Dec 29 19:21:02 so1905-bfc4 volumio[15193]: info: CoreStateMachine::ClearQueue Dec 29 19:21:02 so1905-bfc4 volumio[15193]: info: CoreStateMachine::stop Dec 29 19:21:02 so1905-bfc4 volumio[15193]: info: CoreStateMachine::serviceStop Dec 29 19:21:02 so1905-bfc4 volumio[15193]: info: CoreStateMachine::serviceStop trackBlock undefined Dec 29 19:21:02 so1905-bfc4 volumio[15193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 19:21:02 so1905-bfc4 volumio[15193]: TypeError: Cannot read property 'stop' of null Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at CoreStateMachine.serviceStop (webpack://Volumio2/./app/statemachine.js?:1312:24) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at CoreStateMachine.stop (webpack://Volumio2/./app/statemachine.js?:1292:17) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at CoreStateMachine.clearQueue (webpack://Volumio2/./app/statemachine.js?:269:10) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at CoreCommandRouter.replaceAndPlay (webpack://Volumio2/./app/index.js?:497:21) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at CoreCommandRouter.playItemsList (webpack://Volumio2/./app/index.js?:487:17) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at Socket.eval (webpack://Volumio2/./app/plugins/user_interface/websocket/index.js?:116:35) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at Socket.emit (events.js:198:13) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at eval (webpack://Volumio2/./node_modules/socket.io/lib/socket.js?:503:12) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: at process._tickCallback (internal/process/next_tick.js:61:11) Dec 29 19:21:02 so1905-bfc4 volumio[15193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 19:21:03 so1905-bfc4 sudo[15747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 19:20 Dec 29 19:21:03 so1905-bfc4 sudo[15747]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal BRAND_NAME="PREMIERA" MODEL_NAME="FT1905" IMG_VERSION="1.100.006" VOLUMIO_BUILD_VERSION="" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.5.6.30 VOLUMIO_BUILD_DATE=2025-12-25 11:00:29 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system