-- Logs begin at Wed 2024-11-20 23:14:09 CET, end at Thu 2024-11-21 00:02:06 CET. -- Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Starting Streaming Service Transparent Proxy Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Plugin tidalconnect is not enabled Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Loading plugin "webradio"... Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Loading plugin "i2s_dacs"... Nov 21 00:01:00 minidsp-shd volumio[4124]: info: I2S DAC not set, start Auto-detection Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Loading plugin "volumiodiscovery"... Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** For more information see Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:00 minidsp-shd volumio[4124]: *** WARNING *** For more information see Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** For more information see Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:00 minidsp-shd node[4124]: *** WARNING *** For more information see Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Discovery: Started advertising with name: miniDSP SHD Nov 21 00:01:00 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:00 minidsp-shd volumio[4124]: info: Loading plugin "squeezelite_mc"... Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Plugin multiroom is not enabled Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Loading plugin "outputs"... Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Loading plugin "albumart"... Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Plugin ms_surface_dial is not enabled Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Plugin example_plugin is not enabled Nov 21 00:01:01 minidsp-shd volumio[4124]: info: Loading plugin "hi_res_audio"... Nov 21 00:01:01 minidsp-shd volumio[4124]: STREAMING PROXY: Starting server on port 3245 Nov 21 00:01:01 minidsp-shd volumio[4124]: Node JS runtime: 14 Nov 21 00:01:02 minidsp-shd volumio[4124]: Forking 3 albumart workers Nov 21 00:01:02 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:02] [connect] Successful connection Nov 21 00:01:03 minidsp-shd volumio[4124]: info: Applying required configuration parameters for plugin hi_res_audio Nov 21 00:01:03 minidsp-shd volumio[4124]: info: Loading plugin "inputs"... Nov 21 00:01:03 minidsp-shd volumio[4124]: Starting albumart workers Nov 21 00:01:03 minidsp-shd volumio[4124]: Starting albumart workers Nov 21 00:01:03 minidsp-shd volumio[4124]: Starting albumart workers Nov 21 00:01:04 minidsp-shd volumio[4124]: info: Loading plugin "qobuz"... Nov 21 00:01:04 minidsp-shd ntpd[641]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 21 00:01:05 minidsp-shd volumio[4124]: info: Loading plugin "tidal"... Nov 21 00:01:05 minidsp-shd ntpd[641]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 21 00:01:07 minidsp-shd volumio[4124]: info: Loading plugin "oem_helper"... Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Applying required configuration parameters for plugin oem_helper Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Loading plugin "updater_comm"... Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Plugin mpdemulation is not enabled Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Loading plugin "rest_api"... Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Loading plugin "websocket"... Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Loading i18n strings for locale de Nov 21 00:01:08 minidsp-shd volumio[4124]: Updating browse sources language Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::initPlayerControls Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: Express server listening on port 3000 Nov 21 00:01:08 minidsp-shd volumio[4124]: [Metrics] WebUI: 14s 38.74ms Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreStateMachine::resetVolumioState Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreStateMachine::getcurrentVolume Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreStateMachine::pushState Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:08 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:08 minidsp-shd volumio[4124]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Completed loading Core Plugins Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Preparing to generate the ALSA configuration file Nov 21 00:01:08 minidsp-shd volumio[4124]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 21 00:01:08 minidsp-shd volumio[4124]: wlan0 Interface doesn't support scanning. Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Cannot use regular scanning, forcing with ap-force Nov 21 00:01:08 minidsp-shd sudo[4236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 21 00:01:08 minidsp-shd sudo[4236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:08 minidsp-shd sudo[4236]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:08 minidsp-shd volumio[4124]: command failed: No such device (-19) Nov 21 00:01:08 minidsp-shd volumio[4124]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 21 00:01:08 minidsp-shd volumio[4124]: command failed: No such device (-19) Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Reloading queue from file Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Asound.conf file unchanged, so no further update is needed Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Output device has changed, restarting MPD Nov 21 00:01:08 minidsp-shd sudo[4245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:08 minidsp-shd sudo[4245]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:08 minidsp-shd sudo[4245]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:08 minidsp-shd volumio[4124]: info: ___________ START PLUGINS ___________ Nov 21 00:01:08 minidsp-shd volumio[4124]: info: ControllerMpd::onStart: Initializing MPD Nov 21 00:01:08 minidsp-shd volumio[4124]: info: Creating MPD Configuration file Nov 21 00:01:08 minidsp-shd sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:08 minidsp-shd sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:08 minidsp-shd systemd[1]: Stopping Music Player Daemon... Nov 21 00:01:09 minidsp-shd sudo[4251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:09 minidsp-shd sudo[4251]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding Manifest REST API Endpoints Nov 21 00:01:09 minidsp-shd sudo[4251]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [1732143669034] CoreMusicLibrary::Adding element Last_100 Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Streaming services startup Nov 21 00:01:09 minidsp-shd sudo[4254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:09 minidsp-shd sudo[4254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Starting Streaming Daemon Nov 21 00:01:09 minidsp-shd systemd[1]: mpd.service: Succeeded. Nov 21 00:01:09 minidsp-shd systemd[1]: Stopped Music Player Daemon. Nov 21 00:01:09 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 21 00:01:09 minidsp-shd sudo[4257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 00:01:09 minidsp-shd sudo[4257]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:09 minidsp-shd sudo[4257]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [1732143669181] CoreMusicLibrary::Adding element Webradio Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Starting proxy server... Nov 21 00:01:09 minidsp-shd volumio[4124]: error: Hi Res Audio Failed Login: Missing Login Data Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Volumio Calling Home Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Proxy server started on port 44369 Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Stopping AccessToken refresher cron for QOBUZ Nov 21 00:01:09 minidsp-shd volumio[4124]: info: AccessToken refresher cron started for QOBUZ Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Stopping AccessToken refresher cron Nov 21 00:01:09 minidsp-shd volumio[4124]: info: AccessToken refresher cron started Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding TIDAL REST API Endpoints Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding QOBUZ REST API Endpoints Nov 21 00:01:09 minidsp-shd volumio[4124]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreStateMachine::pushState Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Server discovery started Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Player finder started Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:09 minidsp-shd volumio[4124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"-o squeezelite -C 1 -n \"minidsp-shd\" -D 3:dop -f /tmp/squeezelite.log -p 45","card":"0","mixerType":"None","mixer":null} Nov 21 00:01:09 minidsp-shd volumio[4124]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:09 minidsp-shd volumio[4124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:09 minidsp-shd volumio[4124]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 21 00:01:09 minidsp-shd volumio[4124]: at doSend (dgram.js:714:16) Nov 21 00:01:09 minidsp-shd volumio[4124]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 21 00:01:09 minidsp-shd volumio[4124]: at afterDns (dgram.js:660:5) Nov 21 00:01:09 minidsp-shd volumio[4124]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 21 00:01:09 minidsp-shd volumio[4124]: errno: -101, Nov 21 00:01:09 minidsp-shd volumio[4124]: code: 'ENETUNREACH', Nov 21 00:01:09 minidsp-shd volumio[4124]: syscall: 'send', Nov 21 00:01:09 minidsp-shd volumio[4124]: address: '255.255.255.255', Nov 21 00:01:09 minidsp-shd volumio[4124]: port: 3483 Nov 21 00:01:09 minidsp-shd volumio[4124]: } Nov 21 00:01:09 minidsp-shd volumio[4124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:09 minidsp-shd sudo[4282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:09 minidsp-shd sudo[4282]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:09 minidsp-shd sudo[4282]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:10 minidsp-shd sudo[4293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 00:00 Nov 21 00:01:10 minidsp-shd sudo[4293]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:10 minidsp-shd sudo[4293]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:11 minidsp-shd systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 00:01:11 minidsp-shd sudo[4254]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:11 minidsp-shd sudo[4248]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:11 minidsp-shd systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 21 00:01:11 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:11 minidsp-shd volumiologrotate[440]: ls: cannot access '/var/log/samba/log.wb-MINIDSP': No such file or directory Nov 21 00:01:11 minidsp-shd volumiologrotate[440]: ls: cannot access 'SHD': No such file or directory Nov 21 00:01:11 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:11 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 00:01:11 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 188. Nov 21 00:01:11 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:11 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Nov 21 00:01:11 minidsp-shd systemd[1]: Started Volumio Backend Module. Nov 21 00:01:11 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:11 minidsp-shd mpd[4258]: Nov 21 00:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 00:01:11 minidsp-shd systemd[1]: Started Music Player Daemon. Nov 21 00:01:13 minidsp-shd volumio[4348]: info: ------------------------------------------- Nov 21 00:01:13 minidsp-shd volumio[4348]: info: ----- Volumio3 ---- Nov 21 00:01:13 minidsp-shd volumio[4348]: info: ------------------------------------------- Nov 21 00:01:13 minidsp-shd volumio[4348]: info: ----- System startup ---- Nov 21 00:01:13 minidsp-shd volumio[4348]: info: ------------------------------------------- Nov 21 00:01:14 minidsp-shd volumio[4348]: info: MYVOLUMIO Environment detected Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Plugin folders cleanup Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning into folder /volumio/app/plugins/ Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category audio_interface Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category miscellanea Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category music_service Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category plugins.json Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category system_controller Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category user_interface Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning into folder /data/plugins/ Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Scanning category music_service Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Plugin folders cleanup completed Nov 21 00:01:14 minidsp-shd volumio[4348]: info: ------------------------------------------- Nov 21 00:01:14 minidsp-shd volumio[4348]: info: ----- Core plugins startup ---- Nov 21 00:01:14 minidsp-shd volumio[4348]: info: ------------------------------------------- Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin multiroom to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin upnp to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin metavolumio to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin ms_surface_dial to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin raat to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Loading plugins from folder /data/plugins/ Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Loading plugin "system"... Nov 21 00:01:14 minidsp-shd volumio[4348]: info: Loading plugin "appearance"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "network"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Refreshing Cached IP Addresses Nov 21 00:01:16 minidsp-shd sudo[4376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 00:01:16 minidsp-shd sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:16 minidsp-shd sudo[4376]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:16 minidsp-shd sudo[4378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 00:01:16 minidsp-shd sudo[4378]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "services"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "alsa_controller"... Nov 21 00:01:16 minidsp-shd sudo[4381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 00:01:16 minidsp-shd sudo[4381]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:16 minidsp-shd sudo[4378]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:16 minidsp-shd sudo[4381]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:16 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "wizard"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "networkfs"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Starting Udev Watcher for removable devices Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Ignoring mount for partition: BOOT Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Ignoring mount for partition: volumio Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Ignoring mount for partition: volumio_data Nov 21 00:01:16 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "volumio_command_line_client"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Plugin upnp is not enabled Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "my_music"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "mpd"... Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Plugin upnp_browser is not enabled Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Plugin bluetooth is not enabled Nov 21 00:01:16 minidsp-shd volumio[4348]: info: Loading plugin "alarm-clock"... Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Loading plugin "manifestui"... Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Plugin metavolumio is not enabled Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Plugin airplay_emulation is not enabled Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Plugin cd_controller is not enabled Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Loading plugin "last_100"... Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Plugin raat is not enabled Nov 21 00:01:17 minidsp-shd volumio[4348]: info: Loading plugin "streaming_services"... Nov 21 00:01:17 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:17] [connect] Successful connection Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Starting Streaming Service Transparent Proxy Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Plugin tidalconnect is not enabled Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Loading plugin "webradio"... Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Loading plugin "i2s_dacs"... Nov 21 00:01:18 minidsp-shd volumio[4348]: info: I2S DAC not set, start Auto-detection Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Loading plugin "volumiodiscovery"... Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** For more information see Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:18 minidsp-shd volumio[4348]: *** WARNING *** For more information see Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** For more information see Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:18 minidsp-shd node[4348]: *** WARNING *** For more information see Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Discovery: Started advertising with name: miniDSP SHD Nov 21 00:01:18 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:18 minidsp-shd volumio[4348]: info: Loading plugin "squeezelite_mc"... Nov 21 00:01:19 minidsp-shd volumio[4348]: info: Plugin multiroom is not enabled Nov 21 00:01:19 minidsp-shd volumio[4348]: info: Loading plugin "outputs"... Nov 21 00:01:19 minidsp-shd volumio[4348]: info: Loading plugin "albumart"... Nov 21 00:01:20 minidsp-shd volumio[4348]: info: Plugin ms_surface_dial is not enabled Nov 21 00:01:20 minidsp-shd volumio[4348]: info: Plugin example_plugin is not enabled Nov 21 00:01:20 minidsp-shd volumio[4348]: info: Loading plugin "hi_res_audio"... Nov 21 00:01:20 minidsp-shd volumio[4348]: STREAMING PROXY: Starting server on port 3245 Nov 21 00:01:20 minidsp-shd volumio[4348]: Node JS runtime: 14 Nov 21 00:01:20 minidsp-shd volumio[4348]: Forking 3 albumart workers Nov 21 00:01:21 minidsp-shd volumio[4348]: info: Applying required configuration parameters for plugin hi_res_audio Nov 21 00:01:21 minidsp-shd volumio[4348]: info: Loading plugin "inputs"... Nov 21 00:01:21 minidsp-shd volumio[4348]: Starting albumart workers Nov 21 00:01:22 minidsp-shd volumio[4348]: Starting albumart workers Nov 21 00:01:22 minidsp-shd volumio[4348]: Starting albumart workers Nov 21 00:01:22 minidsp-shd volumio[4348]: info: Loading plugin "qobuz"... Nov 21 00:01:24 minidsp-shd volumio[4348]: info: Loading plugin "tidal"... Nov 21 00:01:25 minidsp-shd volumio[4348]: info: Loading plugin "oem_helper"... Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Applying required configuration parameters for plugin oem_helper Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Loading plugin "updater_comm"... Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Plugin mpdemulation is not enabled Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Loading plugin "rest_api"... Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Loading plugin "websocket"... Nov 21 00:01:26 minidsp-shd volumio[4348]: info: Loading i18n strings for locale de Nov 21 00:01:26 minidsp-shd volumio[4348]: Updating browse sources language Nov 21 00:01:26 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:26 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:26 minidsp-shd volumio[4348]: info: CoreCommandRouter::initPlayerControls Nov 21 00:01:26 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: Express server listening on port 3000 Nov 21 00:01:27 minidsp-shd volumio[4348]: [Metrics] WebUI: 14s 43.04ms Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreStateMachine::resetVolumioState Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreStateMachine::getcurrentVolume Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreStateMachine::pushState Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Completed loading Core Plugins Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Preparing to generate the ALSA configuration file Nov 21 00:01:27 minidsp-shd volumio[4348]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 21 00:01:27 minidsp-shd volumio[4348]: wlan0 Interface doesn't support scanning. Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Cannot use regular scanning, forcing with ap-force Nov 21 00:01:27 minidsp-shd sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 21 00:01:27 minidsp-shd sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd sudo[4458]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:27 minidsp-shd volumio[4348]: command failed: No such device (-19) Nov 21 00:01:27 minidsp-shd volumio[4348]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 21 00:01:27 minidsp-shd volumio[4348]: command failed: No such device (-19) Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Reloading queue from file Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Asound.conf file unchanged, so no further update is needed Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Output device has changed, restarting MPD Nov 21 00:01:27 minidsp-shd sudo[4467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:27 minidsp-shd sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd sudo[4467]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:27 minidsp-shd volumio[4348]: info: ___________ START PLUGINS ___________ Nov 21 00:01:27 minidsp-shd volumio[4348]: info: ControllerMpd::onStart: Initializing MPD Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Creating MPD Configuration file Nov 21 00:01:27 minidsp-shd sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:27 minidsp-shd sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd systemd[1]: Stopping Music Player Daemon... Nov 21 00:01:27 minidsp-shd sudo[4473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:27 minidsp-shd sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Adding Manifest REST API Endpoints Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 21 00:01:27 minidsp-shd sudo[4473]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:27 minidsp-shd volumio[4348]: info: [1732143687553] CoreMusicLibrary::Adding element Last_100 Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Streaming services startup Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Starting Streaming Daemon Nov 21 00:01:27 minidsp-shd sudo[4476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:27 minidsp-shd sudo[4476]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd systemd[1]: mpd.service: Succeeded. Nov 21 00:01:27 minidsp-shd systemd[1]: Stopped Music Player Daemon. Nov 21 00:01:27 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 21 00:01:27 minidsp-shd sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 00:01:27 minidsp-shd sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:27 minidsp-shd sudo[4479]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:27 minidsp-shd volumio[4348]: info: [1732143687656] CoreMusicLibrary::Adding element Webradio Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:27 minidsp-shd volumio[4348]: info: [squeezelite_mc] Starting proxy server... Nov 21 00:01:27 minidsp-shd volumio[4348]: error: Hi Res Audio Failed Login: Missing Login Data Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:27 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Volumio Calling Home Nov 21 00:01:27 minidsp-shd volumio[4348]: info: [squeezelite_mc] Proxy server started on port 36293 Nov 21 00:01:27 minidsp-shd volumio[4348]: info: Stopping AccessToken refresher cron for QOBUZ Nov 21 00:01:28 minidsp-shd volumio[4348]: info: AccessToken refresher cron started for QOBUZ Nov 21 00:01:28 minidsp-shd volumio[4348]: info: Stopping AccessToken refresher cron Nov 21 00:01:28 minidsp-shd volumio[4348]: info: AccessToken refresher cron started Nov 21 00:01:28 minidsp-shd volumio[4348]: info: Adding TIDAL REST API Endpoints Nov 21 00:01:28 minidsp-shd volumio[4348]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 21 00:01:28 minidsp-shd volumio[4348]: info: Adding QOBUZ REST API Endpoints Nov 21 00:01:28 minidsp-shd volumio[4348]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CoreStateMachine::pushState Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:28 minidsp-shd volumio[4348]: info: [squeezelite_mc] Server discovery started Nov 21 00:01:28 minidsp-shd volumio[4348]: info: [squeezelite_mc] Player finder started Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:28 minidsp-shd volumio[4348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:28 minidsp-shd volumio[4348]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"-o squeezelite -C 1 -n \"minidsp-shd\" -D 3:dop -f /tmp/squeezelite.log -p 45","card":"0","mixerType":"None","mixer":null} Nov 21 00:01:28 minidsp-shd volumio[4348]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:28 minidsp-shd volumio[4348]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:28 minidsp-shd volumio[4348]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 21 00:01:28 minidsp-shd volumio[4348]: at doSend (dgram.js:714:16) Nov 21 00:01:28 minidsp-shd volumio[4348]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 21 00:01:28 minidsp-shd volumio[4348]: at afterDns (dgram.js:660:5) Nov 21 00:01:28 minidsp-shd volumio[4348]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 21 00:01:28 minidsp-shd volumio[4348]: errno: -101, Nov 21 00:01:28 minidsp-shd volumio[4348]: code: 'ENETUNREACH', Nov 21 00:01:28 minidsp-shd volumio[4348]: syscall: 'send', Nov 21 00:01:28 minidsp-shd volumio[4348]: address: '255.255.255.255', Nov 21 00:01:28 minidsp-shd volumio[4348]: port: 3483 Nov 21 00:01:28 minidsp-shd volumio[4348]: } Nov 21 00:01:28 minidsp-shd volumio[4348]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:28 minidsp-shd sudo[4501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:28 minidsp-shd sudo[4501]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:28 minidsp-shd sudo[4501]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:29 minidsp-shd sudo[4512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 00:00 Nov 21 00:01:29 minidsp-shd sudo[4512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:29 minidsp-shd sudo[4512]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:29 minidsp-shd systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 00:01:29 minidsp-shd sudo[4470]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:29 minidsp-shd sudo[4476]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:29 minidsp-shd systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 21 00:01:29 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:29 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:29 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 00:01:29 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 189. Nov 21 00:01:29 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Nov 21 00:01:29 minidsp-shd systemd[1]: Started Volumio Backend Module. Nov 21 00:01:29 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:29 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:29 minidsp-shd mpd[4480]: Nov 21 00:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 00:01:29 minidsp-shd systemd[1]: Started Music Player Daemon. Nov 21 00:01:32 minidsp-shd volumio[4525]: info: ------------------------------------------- Nov 21 00:01:32 minidsp-shd volumio[4525]: info: ----- Volumio3 ---- Nov 21 00:01:32 minidsp-shd volumio[4525]: info: ------------------------------------------- Nov 21 00:01:32 minidsp-shd volumio[4525]: info: ----- System startup ---- Nov 21 00:01:32 minidsp-shd volumio[4525]: info: ------------------------------------------- Nov 21 00:01:32 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:32] [connect] Successful connection Nov 21 00:01:33 minidsp-shd volumio[4525]: info: MYVOLUMIO Environment detected Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Plugin folders cleanup Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning into folder /volumio/app/plugins/ Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category audio_interface Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category miscellanea Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category music_service Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category plugins.json Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category system_controller Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category user_interface Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning into folder /data/plugins/ Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Scanning category music_service Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Plugin folders cleanup completed Nov 21 00:01:33 minidsp-shd volumio[4525]: info: ------------------------------------------- Nov 21 00:01:33 minidsp-shd volumio[4525]: info: ----- Core plugins startup ---- Nov 21 00:01:33 minidsp-shd volumio[4525]: info: ------------------------------------------- Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin multiroom to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin upnp to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin metavolumio to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin ms_surface_dial to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin raat to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Loading plugins from folder /data/plugins/ Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Loading plugin "system"... Nov 21 00:01:33 minidsp-shd volumio[4525]: info: Loading plugin "appearance"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "network"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Refreshing Cached IP Addresses Nov 21 00:01:34 minidsp-shd sudo[4554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 00:01:34 minidsp-shd sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:34 minidsp-shd sudo[4554]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:34 minidsp-shd sudo[4556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 00:01:34 minidsp-shd sudo[4556]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "services"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "alsa_controller"... Nov 21 00:01:34 minidsp-shd sudo[4559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 00:01:34 minidsp-shd sudo[4559]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:34 minidsp-shd sudo[4556]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:34 minidsp-shd sudo[4559]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:34 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "wizard"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "networkfs"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Starting Udev Watcher for removable devices Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Ignoring mount for partition: BOOT Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Ignoring mount for partition: volumio Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Ignoring mount for partition: volumio_data Nov 21 00:01:34 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "volumio_command_line_client"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Plugin upnp is not enabled Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "my_music"... Nov 21 00:01:34 minidsp-shd volumio[4525]: info: Loading plugin "mpd"... Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin upnp_browser is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin bluetooth is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Loading plugin "alarm-clock"... Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Loading plugin "manifestui"... Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin metavolumio is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin airplay_emulation is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin cd_controller is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Loading plugin "last_100"... Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Plugin raat is not enabled Nov 21 00:01:35 minidsp-shd volumio[4525]: info: Loading plugin "streaming_services"... Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Starting Streaming Service Transparent Proxy Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Plugin tidalconnect is not enabled Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Loading plugin "webradio"... Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Loading plugin "i2s_dacs"... Nov 21 00:01:37 minidsp-shd volumio[4525]: info: I2S DAC not set, start Auto-detection Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Loading plugin "volumiodiscovery"... Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** For more information see Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:37 minidsp-shd volumio[4525]: *** WARNING *** For more information see Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** For more information see Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:37 minidsp-shd node[4525]: *** WARNING *** For more information see Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Discovery: Started advertising with name: miniDSP SHD Nov 21 00:01:37 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:37 minidsp-shd volumio[4525]: info: Loading plugin "squeezelite_mc"... Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Plugin multiroom is not enabled Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Loading plugin "outputs"... Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Loading plugin "albumart"... Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Plugin ms_surface_dial is not enabled Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Plugin example_plugin is not enabled Nov 21 00:01:38 minidsp-shd volumio[4525]: info: Loading plugin "hi_res_audio"... Nov 21 00:01:38 minidsp-shd volumio[4525]: STREAMING PROXY: Starting server on port 3245 Nov 21 00:01:38 minidsp-shd volumio[4525]: Node JS runtime: 14 Nov 21 00:01:39 minidsp-shd volumio[4525]: Forking 3 albumart workers Nov 21 00:01:40 minidsp-shd volumio[4525]: info: Applying required configuration parameters for plugin hi_res_audio Nov 21 00:01:40 minidsp-shd volumio[4525]: info: Loading plugin "inputs"... Nov 21 00:01:40 minidsp-shd volumio[4525]: Starting albumart workers Nov 21 00:01:40 minidsp-shd volumio[4525]: Starting albumart workers Nov 21 00:01:40 minidsp-shd volumio[4525]: Starting albumart workers Nov 21 00:01:41 minidsp-shd volumio[4525]: info: Loading plugin "qobuz"... Nov 21 00:01:42 minidsp-shd volumio[4525]: info: Loading plugin "tidal"... Nov 21 00:01:44 minidsp-shd volumio[4525]: info: Loading plugin "oem_helper"... Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Applying required configuration parameters for plugin oem_helper Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Loading plugin "updater_comm"... Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Plugin mpdemulation is not enabled Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Loading plugin "rest_api"... Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Loading plugin "websocket"... Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Loading i18n strings for locale de Nov 21 00:01:45 minidsp-shd volumio[4525]: Updating browse sources language Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::initPlayerControls Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: Express server listening on port 3000 Nov 21 00:01:45 minidsp-shd volumio[4525]: [Metrics] WebUI: 14s 58.43ms Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreStateMachine::resetVolumioState Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreStateMachine::getcurrentVolume Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreStateMachine::pushState Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:45 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:45 minidsp-shd volumio[4525]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Completed loading Core Plugins Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Preparing to generate the ALSA configuration file Nov 21 00:01:45 minidsp-shd volumio[4525]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 21 00:01:45 minidsp-shd volumio[4525]: wlan0 Interface doesn't support scanning. Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Cannot use regular scanning, forcing with ap-force Nov 21 00:01:45 minidsp-shd sudo[4636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 21 00:01:45 minidsp-shd sudo[4636]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:45 minidsp-shd sudo[4636]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:45 minidsp-shd volumio[4525]: command failed: No such device (-19) Nov 21 00:01:45 minidsp-shd volumio[4525]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 21 00:01:45 minidsp-shd volumio[4525]: command failed: No such device (-19) Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Reloading queue from file Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Asound.conf file unchanged, so no further update is needed Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Output device has changed, restarting MPD Nov 21 00:01:45 minidsp-shd sudo[4645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:45 minidsp-shd sudo[4645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:45 minidsp-shd sudo[4645]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:45 minidsp-shd volumio[4525]: info: ___________ START PLUGINS ___________ Nov 21 00:01:45 minidsp-shd volumio[4525]: info: ControllerMpd::onStart: Initializing MPD Nov 21 00:01:45 minidsp-shd volumio[4525]: info: Creating MPD Configuration file Nov 21 00:01:45 minidsp-shd sudo[4648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:45 minidsp-shd sudo[4648]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:46 minidsp-shd systemd[1]: Stopping Music Player Daemon... Nov 21 00:01:46 minidsp-shd sudo[4651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:01:46 minidsp-shd sudo[4651]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:46 minidsp-shd sudo[4651]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding Manifest REST API Endpoints Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [1732143706059] CoreMusicLibrary::Adding element Last_100 Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Streaming services startup Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Starting Streaming Daemon Nov 21 00:01:46 minidsp-shd sudo[4654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:01:46 minidsp-shd sudo[4654]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:46 minidsp-shd systemd[1]: mpd.service: Succeeded. Nov 21 00:01:46 minidsp-shd systemd[1]: Stopped Music Player Daemon. Nov 21 00:01:46 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 21 00:01:46 minidsp-shd sudo[4657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 00:01:46 minidsp-shd sudo[4657]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:46 minidsp-shd sudo[4657]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [1732143706162] CoreMusicLibrary::Adding element Webradio Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Starting proxy server... Nov 21 00:01:46 minidsp-shd volumio[4525]: error: Hi Res Audio Failed Login: Missing Login Data Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Volumio Calling Home Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Proxy server started on port 39425 Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Stopping AccessToken refresher cron for QOBUZ Nov 21 00:01:46 minidsp-shd volumio[4525]: info: AccessToken refresher cron started for QOBUZ Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Stopping AccessToken refresher cron Nov 21 00:01:46 minidsp-shd volumio[4525]: info: AccessToken refresher cron started Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding TIDAL REST API Endpoints Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding QOBUZ REST API Endpoints Nov 21 00:01:46 minidsp-shd volumio[4525]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreStateMachine::pushState Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CorePlayQueue::getTrack 0 Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::volumioPushState Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Server discovery started Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Player finder started Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:46 minidsp-shd volumio[4525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"-o squeezelite -C 1 -n \"minidsp-shd\" -D 3:dop -f /tmp/squeezelite.log -p 45","card":"0","mixerType":"None","mixer":null} Nov 21 00:01:46 minidsp-shd volumio[4525]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:46 minidsp-shd volumio[4525]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:46 minidsp-shd volumio[4525]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 21 00:01:46 minidsp-shd volumio[4525]: at doSend (dgram.js:714:16) Nov 21 00:01:46 minidsp-shd volumio[4525]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 21 00:01:46 minidsp-shd volumio[4525]: at afterDns (dgram.js:660:5) Nov 21 00:01:46 minidsp-shd volumio[4525]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 21 00:01:46 minidsp-shd volumio[4525]: errno: -101, Nov 21 00:01:46 minidsp-shd volumio[4525]: code: 'ENETUNREACH', Nov 21 00:01:46 minidsp-shd volumio[4525]: syscall: 'send', Nov 21 00:01:46 minidsp-shd volumio[4525]: address: '255.255.255.255', Nov 21 00:01:46 minidsp-shd volumio[4525]: port: 3483 Nov 21 00:01:46 minidsp-shd volumio[4525]: } Nov 21 00:01:46 minidsp-shd volumio[4525]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:01:46 minidsp-shd sudo[4680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:01:46 minidsp-shd sudo[4680]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:46 minidsp-shd sudo[4680]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:47 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:47] [connect] Successful connection Nov 21 00:01:47 minidsp-shd sudo[4691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 00:00 Nov 21 00:01:47 minidsp-shd sudo[4691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:47 minidsp-shd sudo[4691]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:48 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:48] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Nov 21 00:01:48 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:48] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Nov 21 00:01:48 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 21 00:01:48 minidsp-shd systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 00:01:48 minidsp-shd sudo[4648]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:48 minidsp-shd sudo[4654]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:48 minidsp-shd systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 21 00:01:48 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:48 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:48 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 00:01:48 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 190. Nov 21 00:01:48 minidsp-shd systemd[1]: Started dynamicswap service. Nov 21 00:01:48 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Nov 21 00:01:48 minidsp-shd systemd[1]: Started Volumio Backend Module. Nov 21 00:01:48 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Nov 21 00:01:48 minidsp-shd mpd[4658]: Nov 21 00:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 00:01:48 minidsp-shd systemd[1]: Started Music Player Daemon. Nov 21 00:01:50 minidsp-shd volumio[4705]: info: ------------------------------------------- Nov 21 00:01:50 minidsp-shd volumio[4705]: info: ----- Volumio3 ---- Nov 21 00:01:50 minidsp-shd volumio[4705]: info: ------------------------------------------- Nov 21 00:01:50 minidsp-shd volumio[4705]: info: ----- System startup ---- Nov 21 00:01:50 minidsp-shd volumio[4705]: info: ------------------------------------------- Nov 21 00:01:51 minidsp-shd volumio[4705]: info: MYVOLUMIO Environment detected Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Plugin folders cleanup Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning into folder /volumio/app/plugins/ Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category audio_interface Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category miscellanea Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category music_service Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category plugins.json Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category system_controller Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category user_interface Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning into folder /data/plugins/ Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Scanning category music_service Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Plugin folders cleanup completed Nov 21 00:01:51 minidsp-shd volumio[4705]: info: ------------------------------------------- Nov 21 00:01:51 minidsp-shd volumio[4705]: info: ----- Core plugins startup ---- Nov 21 00:01:51 minidsp-shd volumio[4705]: info: ------------------------------------------- Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin multiroom to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin upnp to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin metavolumio to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin ms_surface_dial to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin raat to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Loading plugins from folder /data/plugins/ Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Loading plugin "system"... Nov 21 00:01:51 minidsp-shd volumio[4705]: info: Loading plugin "appearance"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "network"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Refreshing Cached IP Addresses Nov 21 00:01:53 minidsp-shd sudo[4733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 00:01:53 minidsp-shd sudo[4733]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:53 minidsp-shd sudo[4733]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:53 minidsp-shd sudo[4735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 00:01:53 minidsp-shd sudo[4735]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "services"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "alsa_controller"... Nov 21 00:01:53 minidsp-shd sudo[4738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 00:01:53 minidsp-shd sudo[4738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:01:53 minidsp-shd sudo[4735]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:53 minidsp-shd sudo[4738]: pam_unix(sudo:session): session closed for user root Nov 21 00:01:53 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "wizard"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "networkfs"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Starting Udev Watcher for removable devices Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Ignoring mount for partition: BOOT Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Ignoring mount for partition: volumio Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Ignoring mount for partition: volumio_data Nov 21 00:01:53 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "volumio_command_line_client"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Plugin upnp is not enabled Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "my_music"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "mpd"... Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Plugin upnp_browser is not enabled Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Plugin bluetooth is not enabled Nov 21 00:01:53 minidsp-shd volumio[4705]: info: Loading plugin "alarm-clock"... Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Loading plugin "manifestui"... Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Plugin metavolumio is not enabled Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Plugin airplay_emulation is not enabled Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Plugin cd_controller is not enabled Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Loading plugin "last_100"... Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Plugin raat is not enabled Nov 21 00:01:54 minidsp-shd volumio[4705]: info: Loading plugin "streaming_services"... Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Starting Streaming Service Transparent Proxy Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Plugin tidalconnect is not enabled Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Loading plugin "webradio"... Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Loading plugin "i2s_dacs"... Nov 21 00:01:55 minidsp-shd volumio[4705]: info: I2S DAC not set, start Auto-detection Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Loading plugin "volumiodiscovery"... Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** For more information see Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:55 minidsp-shd volumio[4705]: *** WARNING *** For more information see Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** For more information see Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 00:01:55 minidsp-shd node[4705]: *** WARNING *** For more information see Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Discovery: Started advertising with name: miniDSP SHD Nov 21 00:01:55 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 00:01:55 minidsp-shd volumio[4705]: info: Loading plugin "squeezelite_mc"... Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Plugin multiroom is not enabled Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Loading plugin "outputs"... Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Loading plugin "albumart"... Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Plugin ms_surface_dial is not enabled Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Plugin example_plugin is not enabled Nov 21 00:01:56 minidsp-shd volumio[4705]: info: Loading plugin "hi_res_audio"... Nov 21 00:01:57 minidsp-shd volumio[4705]: STREAMING PROXY: Starting server on port 3245 Nov 21 00:01:57 minidsp-shd volumio[4705]: Node JS runtime: 14 Nov 21 00:01:57 minidsp-shd volumio[4705]: Forking 3 albumart workers Nov 21 00:01:58 minidsp-shd volumio-remote-updater[452]: [2024-11-21 00:01:58] [connect] Successful connection Nov 21 00:01:58 minidsp-shd volumio[4705]: info: Applying required configuration parameters for plugin hi_res_audio Nov 21 00:01:58 minidsp-shd volumio[4705]: info: Loading plugin "inputs"... Nov 21 00:01:58 minidsp-shd volumio[4705]: Starting albumart workers Nov 21 00:01:58 minidsp-shd volumio[4705]: Starting albumart workers Nov 21 00:01:59 minidsp-shd volumio[4705]: Starting albumart workers Nov 21 00:01:59 minidsp-shd dhcpcd[507]: eth0: carrier acquired Nov 21 00:01:59 minidsp-shd kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Nov 21 00:01:59 minidsp-shd volumio[4705]: info: Loading plugin "qobuz"... Nov 21 00:01:59 minidsp-shd dhcpcd[507]: eth0: IAID c1:61:03:3b Nov 21 00:01:59 minidsp-shd dhcpcd[507]: eth0: rebinding lease of 192.168.178.61 Nov 21 00:01:59 minidsp-shd dhcpcd[507]: eth0: probing address 192.168.178.61/24 Nov 21 00:01:59 minidsp-shd ntpd[641]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 21 00:02:00 minidsp-shd dhcpcd[507]: eth0: soliciting an IPv6 router Nov 21 00:02:00 minidsp-shd ifplugd(eth0)[634]: Link beat detected. Nov 21 00:02:00 minidsp-shd ifplugd(eth0)[634]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 21 00:02:00 minidsp-shd ifplugd(eth0)[634]: client: command failed: No such device (-19) Nov 21 00:02:00 minidsp-shd ifplugd(eth0)[634]: client: sending commands to master dhcpcd process Nov 21 00:02:00 minidsp-shd dhcpcd[4833]: sending commands to master dhcpcd process Nov 21 00:02:00 minidsp-shd dhcpcd[507]: control command: /sbin/dhcpcd eth0 Nov 21 00:02:01 minidsp-shd ifplugd(eth0)[634]: Program executed successfully. Nov 21 00:02:01 minidsp-shd volumio[4705]: info: Loading plugin "tidal"... Nov 21 00:02:01 minidsp-shd ntpd[641]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 21 00:02:02 minidsp-shd volumio[4705]: info: Loading plugin "oem_helper"... Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Applying required configuration parameters for plugin oem_helper Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Loading plugin "updater_comm"... Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Plugin mpdemulation is not enabled Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Loading plugin "rest_api"... Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Loading plugin "websocket"... Nov 21 00:02:03 minidsp-shd volumio[4705]: info: Loading i18n strings for locale de Nov 21 00:02:03 minidsp-shd volumio[4705]: Updating browse sources language Nov 21 00:02:03 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:02:03 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:02:03 minidsp-shd volumio[4705]: info: CoreCommandRouter::initPlayerControls Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: Express server listening on port 3000 Nov 21 00:02:04 minidsp-shd volumio[4705]: [Metrics] WebUI: 14s 61.34ms Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreStateMachine::resetVolumioState Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreStateMachine::getcurrentVolume Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreStateMachine::pushState Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CorePlayQueue::getTrack 0 Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioPushState Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Completed loading Core Plugins Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Preparing to generate the ALSA configuration file Nov 21 00:02:04 minidsp-shd volumio[4705]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 21 00:02:04 minidsp-shd volumio[4705]: wlan0 Interface doesn't support scanning. Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Cannot use regular scanning, forcing with ap-force Nov 21 00:02:04 minidsp-shd sudo[4863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 21 00:02:04 minidsp-shd sudo[4863]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd sudo[4863]: pam_unix(sudo:session): session closed for user root Nov 21 00:02:04 minidsp-shd volumio[4705]: command failed: No such device (-19) Nov 21 00:02:04 minidsp-shd volumio[4705]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 21 00:02:04 minidsp-shd volumio[4705]: command failed: No such device (-19) Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Reloading queue from file Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Asound.conf file unchanged, so no further update is needed Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Output device has changed, restarting MPD Nov 21 00:02:04 minidsp-shd sudo[4872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:02:04 minidsp-shd sudo[4872]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd sudo[4872]: pam_unix(sudo:session): session closed for user root Nov 21 00:02:04 minidsp-shd volumio[4705]: info: ___________ START PLUGINS ___________ Nov 21 00:02:04 minidsp-shd volumio[4705]: info: ControllerMpd::onStart: Initializing MPD Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Creating MPD Configuration file Nov 21 00:02:04 minidsp-shd sudo[4875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:02:04 minidsp-shd sudo[4875]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd systemd[1]: Stopping Music Player Daemon... Nov 21 00:02:04 minidsp-shd sudo[4878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 00:02:04 minidsp-shd sudo[4878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd sudo[4878]: pam_unix(sudo:session): session closed for user root Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Adding Manifest REST API Endpoints Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:02:04 minidsp-shd volumio[4705]: info: [1732143724556] CoreMusicLibrary::Adding element Last_100 Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:02:04 minidsp-shd sudo[4881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 00:02:04 minidsp-shd sudo[4881]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Streaming services startup Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Starting Streaming Daemon Nov 21 00:02:04 minidsp-shd systemd[1]: mpd.service: Succeeded. Nov 21 00:02:04 minidsp-shd systemd[1]: Stopped Music Player Daemon. Nov 21 00:02:04 minidsp-shd systemd[1]: Starting Music Player Daemon... Nov 21 00:02:04 minidsp-shd sudo[4884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 00:02:04 minidsp-shd sudo[4884]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:04 minidsp-shd sudo[4884]: pam_unix(sudo:session): session closed for user root Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 00:02:04 minidsp-shd volumio[4705]: info: [1732143724666] CoreMusicLibrary::Adding element Webradio Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:02:04 minidsp-shd volumio[4705]: info: [squeezelite_mc] Starting proxy server... Nov 21 00:02:04 minidsp-shd volumio[4705]: error: Hi Res Audio Failed Login: Missing Login Data Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 00:02:04 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Volumio Calling Home Nov 21 00:02:04 minidsp-shd volumio[4705]: info: [squeezelite_mc] Proxy server started on port 36589 Nov 21 00:02:04 minidsp-shd volumio[4705]: info: Stopping AccessToken refresher cron for QOBUZ Nov 21 00:02:05 minidsp-shd volumio[4705]: info: AccessToken refresher cron started for QOBUZ Nov 21 00:02:05 minidsp-shd volumio[4705]: info: Stopping AccessToken refresher cron Nov 21 00:02:05 minidsp-shd volumio[4705]: info: AccessToken refresher cron started Nov 21 00:02:05 minidsp-shd volumio[4705]: info: Adding TIDAL REST API Endpoints Nov 21 00:02:05 minidsp-shd volumio[4705]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 21 00:02:05 minidsp-shd volumio[4705]: info: Adding QOBUZ REST API Endpoints Nov 21 00:02:05 minidsp-shd volumio[4705]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CoreStateMachine::pushState Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CorePlayQueue::getTrack 0 Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CoreCommandRouter::volumioPushState Nov 21 00:02:05 minidsp-shd volumio[4705]: info: [squeezelite_mc] Server discovery started Nov 21 00:02:05 minidsp-shd volumio[4705]: info: [squeezelite_mc] Player finder started Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:05 minidsp-shd volumio[4705]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 00:02:05 minidsp-shd volumio[4705]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"-o squeezelite -C 1 -n \"minidsp-shd\" -D 3:dop -f /tmp/squeezelite.log -p 45","card":"0","mixerType":"None","mixer":null} Nov 21 00:02:05 minidsp-shd volumio[4705]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:02:05 minidsp-shd volumio[4705]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:02:05 minidsp-shd volumio[4705]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 21 00:02:05 minidsp-shd volumio[4705]: at doSend (dgram.js:714:16) Nov 21 00:02:05 minidsp-shd volumio[4705]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 21 00:02:05 minidsp-shd volumio[4705]: at afterDns (dgram.js:660:5) Nov 21 00:02:05 minidsp-shd volumio[4705]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 21 00:02:05 minidsp-shd volumio[4705]: errno: -101, Nov 21 00:02:05 minidsp-shd volumio[4705]: code: 'ENETUNREACH', Nov 21 00:02:05 minidsp-shd volumio[4705]: syscall: 'send', Nov 21 00:02:05 minidsp-shd volumio[4705]: address: '255.255.255.255', Nov 21 00:02:05 minidsp-shd volumio[4705]: port: 3483 Nov 21 00:02:05 minidsp-shd volumio[4705]: } Nov 21 00:02:05 minidsp-shd volumio[4705]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 00:02:05 minidsp-shd sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Nov 21 00:02:05 minidsp-shd sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 00:02:05 minidsp-shd sudo[4906]: pam_unix(sudo:session): session closed for user root Nov 21 00:02:05 minidsp-shd dhcpcd[507]: eth0: leased 192.168.178.61 for 864000 seconds Nov 21 00:02:05 minidsp-shd dhcpcd[507]: eth0: adding route to 192.168.178.0/24 Nov 21 00:02:05 minidsp-shd dhcpcd[507]: eth0: adding default route via 192.168.178.1 Nov 21 00:02:05 minidsp-shd avahi-daemon[467]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.61. Nov 21 00:02:05 minidsp-shd avahi-daemon[467]: New relevant interface eth0.IPv4 for mDNS. Nov 21 00:02:05 minidsp-shd avahi-daemon[467]: Registering new address record for 192.168.178.61 on eth0.IPv4. Nov 21 00:02:05 minidsp-shd ntpd[641]: ntpd exiting on signal 15 (Terminated) Nov 21 00:02:05 minidsp-shd systemd[1]: Stopping Network Time Service... Nov 21 00:02:06 minidsp-shd systemd[1]: ntp.service: Succeeded. Nov 21 00:02:06 minidsp-shd systemd[1]: Stopped Network Time Service. Nov 21 00:02:06 minidsp-shd systemd[1]: Starting Network Time Service... Nov 21 00:02:06 minidsp-shd ntpd[4949]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 21 00:02:06 minidsp-shd ntpd[4949]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 21 00:02:06 minidsp-shd systemd[1]: Started Network Time Service. Nov 21 00:02:06 minidsp-shd ntpd[4955]: proto: precision = 1.208 usec (-20) Nov 21 00:02:06 minidsp-shd ntpd[4955]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 21 00:02:06 minidsp-shd ntpd[4955]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 21 00:02:06 minidsp-shd ntpd[4955]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 694 days ago Nov 21 00:02:06 minidsp-shd ntpd[4955]: Listen and drop on 0 v6wildcard [::]:123 Nov 21 00:02:06 minidsp-shd ntpd[4955]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 21 00:02:06 minidsp-shd ntpd[4955]: Listen normally on 2 lo 127.0.0.1:123 Nov 21 00:02:06 minidsp-shd ntpd[4955]: Listen normally on 3 eth0 192.168.178.61:123 Nov 21 00:02:06 minidsp-shd ntpd[4955]: Listening on routing socket on fd #20 for interface updates Nov 21 00:02:06 minidsp-shd ntpd[4955]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Nov 21 00:02:06 minidsp-shd ntpd[4955]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Nov 21 00:02:06 minidsp-shd sudo[4958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 00:01 Nov 21 00:02:06 minidsp-shd sudo[4958]: 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:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"