-- Logs begin at Thu 2019-02-14 17:11:58 WIB, end at Wed 2025-05-28 21:41:05 WIB. -- May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 28 21:40:00 volumio volumio[8047]: info: Adding plugin bluetooth to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin multiroom to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin metavolumio to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin cd_controller to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin qobuzconnect to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin smart_inputs to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: Adding plugin tidalconnect to MyMusic Plugins May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 28 21:40:00 volumio ntpd[790]: Soliciting pool server 103.87.66.70 May 28 21:40:00 volumio ntpd[790]: Soliciting pool server 27.54.117.72 May 28 21:40:00 volumio ntpd[790]: Soliciting pool server 14.102.153.110 May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 28 21:40:00 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:00 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:00 volumio volumio[8047]: info: Starting MyVolumio Remote Streaming Endpoints May 28 21:40:00 volumio volumio[8047]: info: MyVolumio login type: Token May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 28 21:40:00 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 28 21:40:00 volumio volumiossh-tunnel[1599]: Warning: Permanently added '[as2.myvolumio.org]:2222,[165.232.189.9]:2222' (RSA) to the list of known hosts. May 28 21:40:01 volumio volumio[8047]: info: Starting Streaming Service Transparent Proxy May 28 21:40:01 volumio volumio[8047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 28 21:40:01 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 28 21:40:01 volumio volumio[8047]: info: Streaming services startup May 28 21:40:01 volumio volumio[8047]: info: Starting Streaming Daemon May 28 21:40:01 volumio sudo[8417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 21:40:01 volumio sudo[8417]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:01 volumio sudo[8417]: pam_unix(sudo:session): session closed for user root May 28 21:40:01 volumio volumio[8047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 28 21:40:01 volumio volumio[8047]: info: BOOT COMPLETED May 28 21:40:01 volumio volumio[8047]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.114","name":"DESKTOP-6MA0402","ver":"9.1.0","uuid":"2a8d1085-ed14-4a6f-aed9-bdcd07e4ff85","jsonPort":"9000","cliPort":"9090"} May 28 21:40:01 volumio volumio[8047]: error: Cannot start Volumio Streaming Daemon May 28 21:40:01 volumio volumio[8047]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 21:40:01 volumio volumio[8047]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 21:40:01 volumio volumio[8047]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.114","name":"DESKTOP-6MA0402","ver":"9.1.0","uuid":"2a8d1085-ed14-4a6f-aed9-bdcd07e4ff85","jsonPort":"9000","cliPort":"9090"} May 28 21:40:01 volumio ntpd[790]: Soliciting pool server 103.83.142.30 May 28 21:40:02 volumio volumio[8047]: STREAMING PROXY: Starting server on port 3245 May 28 21:40:02 volumio volumio[8047]: Node JS runtime: 14 May 28 21:40:02 volumio volumio[8047]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 28 21:40:02 volumio volumiossh-tunnel[1599]: Warning: remote port forwarding failed for listen port 8091 May 28 21:40:02 volumio volumio[8047]: info: MyVolumio token set successfully May 28 21:40:02 volumio volumio[8047]: info: MYVOLUMIO: Adding device May 28 21:40:02 volumio volumio[8047]: info: MYVOLUMIO: Evaluating Server May 28 21:40:02 volumio ntpd[790]: Soliciting pool server 203.114.225.252 May 28 21:40:03 volumio volumio[8047]: error: [squeezelite_mc] Failed to start notification listener on DESKTOP-6MA0402 (192.168.1.114): Cannot connect Error: Cannot connect May 28 21:40:03 volumio volumio[8047]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:130:35) May 28 21:40:03 volumio volumio[8047]: at Object.onceWrapper (events.js:421:28) May 28 21:40:03 volumio volumio[8047]: at Socket.emit (events.js:315:20) May 28 21:40:03 volumio volumio[8047]: at Socket._onTimeout (net.js:483:8) May 28 21:40:03 volumio volumio[8047]: at listOnTimeout (internal/timers.js:554:17) May 28 21:40:03 volumio volumio[8047]: at processTimers (internal/timers.js:497:7) May 28 21:40:03 volumio volumio[8047]: error: [squeezelite_mc] An error occurred while processing discovered server: Cannot connect Error: Cannot connect May 28 21:40:03 volumio volumio[8047]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:130:35) May 28 21:40:03 volumio volumio[8047]: at Object.onceWrapper (events.js:421:28) May 28 21:40:03 volumio volumio[8047]: at Socket.emit (events.js:315:20) May 28 21:40:03 volumio volumio[8047]: at Socket._onTimeout (net.js:483:8) May 28 21:40:03 volumio volumio[8047]: at listOnTimeout (internal/timers.js:554:17) May 28 21:40:03 volumio volumio[8047]: at processTimers (internal/timers.js:497:7) May 28 21:40:03 volumio volumio[8047]: info: MyVolumio Plan changed: premium May 28 21:40:03 volumio volumio[8047]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 28 21:40:03 volumio volumio[8047]: info: Removing browser output: myVolumio user plan is not superstar May 28 21:40:03 volumio volumio[8047]: info: Removing audio output: May 28 21:40:03 volumio volumio[8047]: info: MYVOLUMIO: Adding device May 28 21:40:03 volumio volumio[8047]: info: MYVOLUMIO: Evaluating Server May 28 21:40:03 volumio volumio[8047]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.114","name":"DESKTOP-6MA0402","ver":"9.1.0","uuid":"2a8d1085-ed14-4a6f-aed9-bdcd07e4ff85","jsonPort":"9000","cliPort":"9090"} May 28 21:40:03 volumio volumio[8047]: info: Remote config written successfully May 28 21:40:03 volumio volumio[8047]: info: Starting Tunnel 1 May 28 21:40:03 volumio volumio[8047]: info: Starting Tunnel Connection Checker May 28 21:40:03 volumio volumio[8047]: info: MYVolumio Device enabled May 28 21:40:03 volumio volumio[8047]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 28 21:40:03 volumio volumio[8047]: info: MyVolumio status changed May 28 21:40:03 volumio volumio[8047]: info: Streaming services startup May 28 21:40:03 volumio volumio[8047]: info: Starting Streaming Daemon May 28 21:40:03 volumio volumio[8047]: info: Setting Geolocation for MyVolumio to as2 May 28 21:40:03 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:03 volumio sudo[8457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 21:40:03 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:03 volumio sudo[8457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:03 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 28 21:40:03 volumio sudo[8457]: pam_unix(sudo:session): session closed for user root May 28 21:40:03 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 28 21:40:03 volumio volumio[8047]: info: [1748443203743] Starting BluetoothController May 28 21:40:03 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 28 21:40:04 volumio sudo[8460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 28 21:40:04 volumio sudo[8460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio sudo[8460]: pam_unix(sudo:session): session closed for user root May 28 21:40:04 volumio volumio[8047]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 28 21:40:04 volumio volumio[8047]: info: MRS: MultiRoom plugin initialized May 28 21:40:04 volumio volumio[8047]: info: MRS: STOPPING SNAPCLIENT May 28 21:40:04 volumio volumio[8047]: info: MRS: Snap server stop May 28 21:40:04 volumio sudo[8476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 28 21:40:04 volumio volumio[8047]: info: MRS: STOPPING volumioStreaming May 28 21:40:04 volumio sudo[8476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio sudo[8478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 28 21:40:04 volumio sudo[8478]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio sudo[8482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 28 21:40:04 volumio sudo[8482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 28 21:40:04 volumio sudo[8476]: pam_unix(sudo:session): session closed for user root May 28 21:40:04 volumio sudo[8485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 28 21:40:04 volumio sudo[8485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio sudo[8485]: pam_unix(sudo:session): session closed for user root May 28 21:40:04 volumio sudo[8478]: pam_unix(sudo:session): session closed for user root May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 28 21:40:04 volumio sudo[8482]: pam_unix(sudo:session): session closed for user root May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 28 21:40:04 volumio volumio[8047]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 28 21:40:04 volumio volumio[8047]: info: Preparing to generate the ALSA configuration file May 28 21:40:04 volumio volumio[8047]: info: Updating MyVolumio device info May 28 21:40:04 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:04 volumio volumio[8047]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 28 21:40:04 volumio volumio[8047]: info: Reading ALSA contributions from plugins. May 28 21:40:04 volumio volumio[8047]: info: Setting Geolocation for MyVolumio to as2 May 28 21:40:04 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:04 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:04 volumio volumio[8047]: info: MRS: Removed streaming files May 28 21:40:04 volumio volumio[8047]: info: MRS: volumioStreaming STOPPED May 28 21:40:04 volumio volumio[8047]: info: MRS: SNAPSERVER STOPPED May 28 21:40:04 volumio volumio[8047]: info: MRS: SNAPCLIENT STOPPED May 28 21:40:04 volumio volumio[8047]: error: Cannot start Volumio Streaming Daemon May 28 21:40:04 volumio volumio[8047]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 21:40:04 volumio volumio[8047]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 21:40:04 volumio volumio[8047]: info: Asound.conf file written May 28 21:40:04 volumio sudo[8488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 28 21:40:04 volumio sudo[8488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:04 volumio sudo[8488]: pam_unix(sudo:session): session closed for user root May 28 21:40:05 volumio volumio[8047]: info: Output device has changed, restarting MPD May 28 21:40:05 volumio sudo[8493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 21:40:05 volumio sudo[8493]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:05 volumio sudo[8493]: pam_unix(sudo:session): session closed for user root May 28 21:40:05 volumio volumio[8047]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio sudo[8496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 21:40:05 volumio sudo[8496]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 28 21:40:05 volumio volumio[8047]: info: MRS: Pushing multiroomSync output for this device May 28 21:40:05 volumio volumio[8047]: info: MRS: Pushing multiroomSync output May 28 21:40:05 volumio systemd[1]: musicservicesshield.service: Succeeded. May 28 21:40:05 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 28 21:40:05 volumio volumio[8047]: info: Adding audio output: May 28 21:40:05 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 28 21:40:05 volumio systemd[1]: Stopping Music Player Daemon... May 28 21:40:05 volumio volumio[8047]: info: Adding audio output: May 28 21:40:05 volumio volumio[8047]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 28 21:40:05 volumio systemd[1]: mpd.service: Succeeded. May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio systemd[1]: Stopped Music Player Daemon. May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 28 21:40:05 volumio volumio[8047]: info: Adding inputs REST Endpoints May 28 21:40:05 volumio volumio[8047]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 28 21:40:05 volumio volumio[8047]: info: Scanning Audio Inputs May 28 21:40:05 volumio volumio[8047]: info: Checking against Known Cards name May 28 21:40:05 volumio volumio[8047]: info: Adding Server instance for streaming May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 28 21:40:05 volumio volumio[8047]: error: Hi Res Audio Failed Login: Missing Login Data May 28 21:40:05 volumio volumio[8047]: info: Adding HIGHRESAUDIO REST API Endpoints May 28 21:40:05 volumio volumio[8047]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 28 21:40:05 volumio systemd[1]: Starting Music Player Daemon... May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 28 21:40:05 volumio volumio[8047]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting May 28 21:40:05 volumio volumio[8047]: info: Stopping AccessToken refresher cron May 28 21:40:05 volumio volumio[8047]: info: AccessToken refresher cron started May 28 21:40:05 volumio volumio[8047]: info: Stopping AccessToken refresher cron for QOBUZ May 28 21:40:05 volumio volumio[8047]: info: AccessToken refresher cron started for QOBUZ May 28 21:40:05 volumio volumio[8047]: info: Adding TIDAL REST API Endpoints May 28 21:40:05 volumio volumio[8047]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 28 21:40:05 volumio volumio[8047]: info: Adding QOBUZ REST API Endpoints May 28 21:40:05 volumio volumio[8047]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 28 21:40:05 volumio sudo[8501]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 21:40:05 volumio sudo[8501]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:05 volumio sudo[8501]: pam_unix(sudo:session): session closed for user root May 28 21:40:05 volumio volumio[8047]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 28 21:40:05 volumio volumio[8047]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 28 21:40:05 volumio volumio[8047]: info: MPD Permissions set May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: MRS: Found cast device: Chromecast-79dc8e6c6c43a5199266f0776b0543e1 May 28 21:40:05 volumio volumio[8047]: info: Adding audio output: May 28 21:40:05 volumio volumio[8047]: info: MRS: Found cast device: Chromecast-2085fa7afa8ebd71e1877a0d6905116b May 28 21:40:05 volumio volumio[8047]: info: Adding audio output: May 28 21:40:05 volumio volumio[8047]: info: Successfully Updated MyVolumio device May 28 21:40:05 volumio volumio[8047]: info: Successfully Added MyVolumio device May 28 21:40:05 volumio volumio[8047]: info: Updating MyVolumio device info May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:05 volumio mpd[8507]: May 28 21:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 21:40:05 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:05 volumio systemd[1]: Started Music Player Daemon. May 28 21:40:05 volumio sudo[8496]: pam_unix(sudo:session): session closed for user root May 28 21:40:05 volumio volumio[8047]: info: Successfully Added MyVolumio device May 28 21:40:05 volumio volumio[8047]: error: updateQueue error: null May 28 21:40:05 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 28 21:40:06 volumio volumio[8516]: cset: --> shielding system active with May 28 21:40:06 volumio volumio[8516]: cset: "system" cpuset of CPUSPEC(1-3) with 117 tasks running May 28 21:40:06 volumio volumio[8516]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 28 21:40:06 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 28 21:40:06 volumio wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network May 28 21:40:06 volumio volumio[8047]: info: Successfully Updated MyVolumio device May 28 21:40:06 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:06 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:06 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:06 volumio volumio[8047]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true May 28 21:40:06 volumio volumio[8047]: warn: [squeezelite_mc] No supported ALSA formats found for card 5 May 28 21:40:06 volumio volumio[8047]: warn: [squeezelite_mc] No ALSA formats returned for card 5 May 28 21:40:07 volumio volumio[8047]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.807&uuid=84095686df6d4ede01980777812d1c66" http://updates.volumio.org/downloader-v1/track-device May 28 21:40:07 volumio volumio[8047]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 21:40:07 volumio volumio[8047]: Dload Upload Total Spent Left Speed May 28 21:40:07 volumio volumio[8047]: [2.2K blob data] May 28 21:40:07 volumio volumio[8047]: retrying in 5 seconds, trial 0 May 28 21:40:07 volumio volumio[8047]: info: Volumio Calling Home May 28 21:40:08 volumio sudo[8555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 28 21:40:08 volumio sudo[8555]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:08 volumio autossh[1600]: received signal to exit (15) May 28 21:40:08 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... May 28 21:40:08 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 28 21:40:08 volumio systemd[1]: sshtunnel.service: Succeeded. May 28 21:40:08 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. May 28 21:40:08 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 28 21:40:08 volumio sudo[8555]: pam_unix(sudo:session): session closed for user root May 28 21:40:08 volumio volumio[8047]: info: Remote SSH Started May 28 21:40:08 volumio autossh[8558]: port set to 0, monitoring disabled May 28 21:40:08 volumio autossh[8558]: starting ssh (count 1) May 28 21:40:08 volumio autossh[8558]: ssh child pid is 8561 May 28 21:40:08 volumio volumio[8047]: info: Volumio called home May 28 21:40:08 volumio volumio[8047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 28 21:40:08 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:08 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:08 volumio volumiossh-tunnel[8557]: Warning: Permanently added '[as2.myvolumio.org]:2222,[165.232.189.9]:2222' (RSA) to the list of known hosts. May 28 21:40:10 volumio volumio[8047]: info: MRS: Getting audio outputs on start May 28 21:40:10 volumio volumio[8047]: info: MRS: Requesting all other devices output May 28 21:40:15 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 21:40:15 volumio volumio[8047]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 28 21:40:15 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:15 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:16 volumio wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::volumioGetBrowseSources May 28 21:40:20 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:40:23 volumio volumio[8047]: error: error May 28 21:40:26 volumio wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network May 28 21:40:29 volumio volumio[8047]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.114","name":"DESKTOP-6MA0402","ver":"9.1.0","uuid":"2a8d1085-ed14-4a6f-aed9-bdcd07e4ff85","jsonPort":"9000","cliPort":"9090"} May 28 21:40:30 volumio volumio[8047]: info: [squeezelite_mc] Notification listener started May 28 21:40:30 volumio volumio[8047]: info: [squeezelite_mc] Getting players connected to DESKTOP-6MA0402 (192.168.1.114) May 28 21:40:30 volumio volumio[8047]: info: [squeezelite_mc] Players connected to DESKTOP-6MA0402 (192.168.1.114): [{"id":"bb:bb:dd:68:49:8a","uuid":null,"ip":"192.168.1.114","name":"Volumio-UPnP/AV","server":{"ip":"192.168.1.114","name":"DESKTOP-6MA0402","ver":"9.1.0","uuid":"2a8d1085-ed14-4a6f-aed9-bdcd07e4ff85","jsonPort":"9000","cliPort":"9090"}}] May 28 21:40:36 volumio wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network May 28 21:40:36 volumio ntpd[790]: 119.110.74.101 local addr 192.168.1.109 -> May 28 21:40:36 volumio ntpd[790]: 203.89.31.13 local addr 192.168.1.109 -> May 28 21:40:37 volumio sudo[8651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 21:40:37 volumio sudo[8651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:37 volumio sudo[8651]: pam_unix(sudo:session): session closed for user root May 28 21:40:37 volumio sudo[8654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 21:40:37 volumio sudo[8654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:37 volumio sudo[8654]: pam_unix(sudo:session): session closed for user root May 28 21:40:37 volumio volumio[8047]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.114 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 21:40:37 volumio sudo[8658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 21:40:37 volumio sudo[8658]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:37 volumio sudo[8658]: pam_unix(sudo:session): session closed for user root May 28 21:40:37 volumio sudo[8661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 21:40:37 volumio sudo[8661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:37 volumio sudo[8661]: pam_unix(sudo:session): session closed for user root May 28 21:40:37 volumio volumio[8047]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.114 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::volumioGetVisibleSources May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:37 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 21:40:37 volumio volumio[8047]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 28 21:40:37 volumio volumio[8047]: info: Received Get System Info May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:40:37 volumio volumio[8047]: info: Discovery: Getting this device information May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:37 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:37 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:37 volumio volumio[8047]: info: Listing playlists May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 21:40:37 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 21:40:38 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 21:40:38 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:38 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:38 volumio ntpd[790]: 103.42.116.124 local addr 192.168.1.109 -> May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 21:40:39 volumio volumio[8047]: info: Received Get System Info May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:40:39 volumio volumio[8047]: info: Discovery: Getting this device information May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:39 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:39 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 21:40:41 volumio volumio[8047]: info: Received Get System Info May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:40:41 volumio volumio[8047]: info: Discovery: Getting this device information May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:41 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 21:40:41 volumio volumio[8047]: Invalid card number. May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 21:40:41 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 21:40:47 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 21:40:47 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:47 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:49 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 21:40:49 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 21:40:53 volumio volumio[8047]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 21:40:53 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:53 volumio sudo[8688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:53 volumio sudo[8688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:53 volumio sudo[8688]: pam_unix(sudo:session): session closed for user root May 28 21:40:54 volumio volumio[8047]: info: CALLMETHOD: music_service squeezelite_mc configStartSqueezelite [object Object] May 28 21:40:54 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: squeezelite_mc , configStartSqueezelite May 28 21:40:54 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:54 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:40:54 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:40:54 volumio volumio[8047]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true May 28 21:40:54 volumio volumio[8047]: warn: [squeezelite_mc] No supported ALSA formats found for card 5 May 28 21:40:54 volumio volumio[8047]: warn: [squeezelite_mc] No ALSA formats returned for card 5 May 28 21:40:54 volumio volumio[8047]: info: [squeezelite_mc] Restarting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"None","mixer":null} May 28 21:40:54 volumio volumio[8047]: 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 May 28 21:40:54 volumio sudo[8694]: 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 May 28 21:40:54 volumio sudo[8694]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:54 volumio sudo[8694]: pam_unix(sudo:session): session closed for user root May 28 21:40:54 volumio volumio[8047]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 28 21:40:54 volumio sudo[8698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 28 21:40:54 volumio sudo[8698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:54 volumio sudo[8698]: pam_unix(sudo:session): session closed for user root May 28 21:40:54 volumio volumio[8047]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 28 21:40:54 volumio sudo[8702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 28 21:40:54 volumio sudo[8702]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:54 volumio sudo[8702]: pam_unix(sudo:session): session closed for user root May 28 21:40:54 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 28 21:40:54 volumio sudo[8705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 21:40:54 volumio sudo[8705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:54 volumio systemd[1]: Reloading. May 28 21:40:54 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 28 21:40:55 volumio sudo[8705]: pam_unix(sudo:session): session closed for user root May 28 21:40:55 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:55 volumio sudo[8721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:55 volumio sudo[8721]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:55 volumio sudo[8721]: pam_unix(sudo:session): session closed for user root May 28 21:40:55 volumio volumio[8047]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 28 21:40:55 volumio sudo[8725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 28 21:40:55 volumio sudo[8725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:55 volumio sudo[8725]: pam_unix(sudo:session): session closed for user root May 28 21:40:55 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 28 21:40:55 volumio sudo[8728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 28 21:40:55 volumio sudo[8728]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:55 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 28 21:40:55 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:55 volumio sudo[8728]: pam_unix(sudo:session): session closed for user root May 28 21:40:55 volumio systemd[1]: squeezelite.service: Succeeded. May 28 21:40:55 volumio systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:40:55 volumio systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. May 28 21:40:55 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:55 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 28 21:40:55 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:55 volumio systemd[1]: squeezelite.service: Succeeded. May 28 21:40:55 volumio systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:40:55 volumio systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 2. May 28 21:40:55 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:56 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 28 21:40:56 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio sudo[8739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:56 volumio sudo[8739]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:56 volumio sudo[8739]: pam_unix(sudo:session): session closed for user root May 28 21:40:56 volumio systemd[1]: squeezelite.service: Succeeded. May 28 21:40:56 volumio wpa_supplicant[906]: RRM: Ignoring radio measurement request: Not RRM network May 28 21:40:56 volumio systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 3. May 28 21:40:56 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 28 21:40:56 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Succeeded. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 4. May 28 21:40:56 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 28 21:40:56 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Succeeded. May 28 21:40:56 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:56 volumio sudo[8747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:56 volumio sudo[8747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:56 volumio sudo[8747]: pam_unix(sudo:session): session closed for user root May 28 21:40:56 volumio systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 5. May 28 21:40:56 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Start request repeated too quickly. May 28 21:40:56 volumio systemd[1]: squeezelite.service: Failed with result 'start-limit-hit'. May 28 21:40:56 volumio systemd[1]: Failed to start Squeezelite lightweight headless Squeezebox emulator. May 28 21:40:57 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:57 volumio sudo[8764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:57 volumio sudo[8764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:57 volumio sudo[8764]: pam_unix(sudo:session): session closed for user root May 28 21:40:57 volumio volumio[8047]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 21:40:57 volumio volumio[8047]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 28 21:40:57 volumio sudo[8767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 28 21:40:57 volumio sudo[8767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:40:57 volumio sudo[8767]: pam_unix(sudo:session): session closed for user root May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 21:40:58 volumio volumio[8047]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 28 21:40:58 volumio volumio[8047]: info: Received Get System Version May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:40:58 volumio volumio[8047]: info: Received Get System Info May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 21:40:58 volumio volumio[8047]: info: Discovery: Getting this device information May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::volumioGetState May 28 21:40:58 volumio volumio[8047]: info: CorePlayQueue::getTrack 0 May 28 21:40:58 volumio volumio[8047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 21:40:59 volumio dhcpcd[917]: wlan0: carrier lost May 28 21:40:59 volumio wpa_supplicant[906]: wlan0: Associated with 10:7b:44:ac:f2:59 May 28 21:40:59 volumio wpa_supplicant[906]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:7b:44:ac:f2:59 completed [id=0 id_str=] May 28 21:40:59 volumio wpa_supplicant[906]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 28 21:40:59 volumio avahi-daemon[630]: Withdrawing address record for 192.168.1.109 on wlan0. May 28 21:40:59 volumio avahi-daemon[630]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.109. May 28 21:40:59 volumio avahi-daemon[630]: Interface wlan0.IPv4 no longer relevant for mDNS. May 28 21:40:59 volumio dhcpcd[917]: wlan0: deleting route to 192.168.1.0/24 May 28 21:40:59 volumio dhcpcd[917]: wlan0: deleting default route via 192.168.1.1 May 28 21:40:59 volumio volumio[8047]: info: Discovery: A device disappeared from network May 28 21:40:59 volumio volumio[8047]: info: Discovery: Device volumio disappeared from network May 28 21:40:59 volumio dhcpcd[917]: wlan0: carrier acquired May 28 21:40:59 volumio dhcpcd[917]: wlan0: IAID dd:68:49:8a May 28 21:40:59 volumio dhcpcd[917]: wlan0: soliciting an IPv6 router May 28 21:40:59 volumio volumio[8047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:40:59 volumio volumio[8047]: Error: send ENETUNREACH 255.255.255.255:3483 May 28 21:40:59 volumio volumio[8047]: at doSend (dgram.js:692:16) May 28 21:40:59 volumio volumio[8047]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 28 21:40:59 volumio volumio[8047]: at afterDns (dgram.js:638:5) May 28 21:40:59 volumio volumio[8047]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 28 21:40:59 volumio volumio[8047]: errno: -101, May 28 21:40:59 volumio volumio[8047]: code: 'ENETUNREACH', May 28 21:40:59 volumio volumio[8047]: syscall: 'send', May 28 21:40:59 volumio volumio[8047]: address: '255.255.255.255', May 28 21:40:59 volumio volumio[8047]: port: 3483 May 28 21:40:59 volumio volumio[8047]: } May 28 21:40:59 volumio volumio[8047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:41:00 volumio sudo[8807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 21:40 May 28 21:41:00 volumio sudo[8807]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:00 volumio sudo[8807]: pam_unix(sudo:session): session closed for user root May 28 21:41:00 volumio volumio-remote-updater[594]: [2025-05-28 21:41:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 28 21:41:00 volumio volumio-remote-updater[594]: [2025-05-28 21:41:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 28 21:41:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 28 21:41:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 28 21:41:00 volumio systemd[1]: Started dynamicswap service. May 28 21:41:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 28 21:41:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 28 21:41:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. May 28 21:41:00 volumio systemd[1]: Started dynamicswap service. May 28 21:41:00 volumio systemd[1]: Stopped Volumio Backend Module. May 28 21:41:00 volumio systemd[1]: Started Volumio Backend Module. May 28 21:41:00 volumio dhcpcd[917]: wlan0: rebinding lease of 192.168.1.109 May 28 21:41:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 28 21:41:00 volumio dhcpcd[917]: wlan0: probing address 192.168.1.109/24 May 28 21:41:00 volumio ntpd[790]: Deleting interface #4 wlan0, 192.168.1.109#123, interface stats: received=86, sent=93, dropped=0, active_time=94 secs May 28 21:41:00 volumio ntpd[790]: 203.114.225.252 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 103.83.142.30 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 27.54.117.72 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 14.102.153.110 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 103.87.66.70 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 103.28.56.14 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 160.30.225.225 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 172.232.235.123 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 103.169.192.230 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 202.65.114.202 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 203.114.74.17 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 203.114.224.252 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 202.162.32.12 local addr 192.168.1.109 -> May 28 21:41:00 volumio ntpd[790]: 103.169.192.229 local addr 192.168.1.109 -> May 28 21:41:01 volumio volumio[8821]: info: ------------------------------------------- May 28 21:41:01 volumio volumio[8821]: info: ----- Volumio3 ---- May 28 21:41:01 volumio volumio[8821]: info: ------------------------------------------- May 28 21:41:01 volumio volumio[8821]: info: ----- System startup ---- May 28 21:41:01 volumio volumio[8821]: info: ------------------------------------------- May 28 21:41:01 volumio volumio[8821]: info: MYVOLUMIO Environment detected May 28 21:41:01 volumio volumio[8821]: info: Plugin folders cleanup May 28 21:41:01 volumio volumio[8821]: info: Scanning into folder /volumio/app/plugins/ May 28 21:41:01 volumio volumio[8821]: info: Scanning category audio_interface May 28 21:41:01 volumio volumio[8821]: info: Scanning category miscellanea May 28 21:41:01 volumio volumio[8821]: info: Scanning category music_service May 28 21:41:01 volumio volumio[8821]: info: Scanning category plugins.json May 28 21:41:01 volumio volumio[8821]: info: Scanning category system_controller May 28 21:41:01 volumio volumio[8821]: info: Scanning category user_interface May 28 21:41:01 volumio volumio[8821]: info: Scanning into folder /data/plugins/ May 28 21:41:01 volumio volumio[8821]: info: Scanning category music_service May 28 21:41:01 volumio volumio[8821]: info: Scanning category system_hardware May 28 21:41:01 volumio volumio[8821]: info: Plugin folders cleanup completed May 28 21:41:01 volumio volumio[8821]: info: ------------------------------------------- May 28 21:41:01 volumio volumio[8821]: info: ----- Core plugins startup ---- May 28 21:41:01 volumio volumio[8821]: info: ------------------------------------------- May 28 21:41:01 volumio volumio[8821]: info: Loading plugins from folder /volumio/app/plugins/ May 28 21:41:01 volumio volumio[8821]: info: Adding plugin upnp to MyMusic Plugins May 28 21:41:01 volumio volumio[8821]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 21:41:01 volumio volumio[8821]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 21:41:01 volumio volumio[8821]: info: Loading plugins from folder /data/plugins/ May 28 21:41:01 volumio volumio[8821]: info: Loading plugin "system"... May 28 21:41:01 volumio volumio[8821]: info: Loading plugin "appearance"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "network"... May 28 21:41:02 volumio volumio[8821]: info: Refreshing Cached IP Addresses May 28 21:41:02 volumio sudo[8847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 21:41:02 volumio sudo[8847]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:02 volumio sudo[8847]: pam_unix(sudo:session): session closed for user root May 28 21:41:02 volumio sudo[8849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 21:41:02 volumio sudo[8849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:02 volumio sudo[8849]: pam_unix(sudo:session): session closed for user root May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "services"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "alsa_controller"... May 28 21:41:02 volumio sudo[8858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 28 21:41:02 volumio sudo[8858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:02 volumio volumio[8821]: warn: Unable to locate the audio output device FiiO K11 R2R. Please configure a valid output device. May 28 21:41:02 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "wizard"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "networkfs"... May 28 21:41:02 volumio volumio[8821]: info: Starting Udev Watcher for removable devices May 28 21:41:02 volumio volumio[8821]: info: Ignoring mount for partition: boot May 28 21:41:02 volumio volumio[8821]: info: Ignoring mount for partition: volumio May 28 21:41:02 volumio volumio[8821]: info: Ignoring mount for partition: volumio_data May 28 21:41:02 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "volumio_command_line_client"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "upnp"... May 28 21:41:02 volumio volumio[8821]: info: [1748443262606] Starting Upmpd Daemon May 28 21:41:02 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "my_music"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "mpd"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "upnp_browser"... May 28 21:41:02 volumio volumio[8821]: info: Loading plugin "alarm-clock"... May 28 21:41:03 volumio volumio[8821]: info: Plugin airplay_emulation is not enabled May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "last_100"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "webradio"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "i2s_dacs"... May 28 21:41:03 volumio volumio[8821]: info: I2S DAC not set, start Auto-detection May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "volumiodiscovery"... May 28 21:41:03 volumio volumio[8821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 21:41:03 volumio volumio[8821]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 21:41:03 volumio volumio[8821]: *** WARNING *** For more information see May 28 21:41:03 volumio node[8821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 21:41:03 volumio volumio[8821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 21:41:03 volumio volumio[8821]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 21:41:03 volumio volumio[8821]: *** WARNING *** For more information see May 28 21:41:03 volumio node[8821]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 21:41:03 volumio node[8821]: *** WARNING *** For more information see May 28 21:41:03 volumio node[8821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 21:41:03 volumio node[8821]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 21:41:03 volumio node[8821]: *** WARNING *** For more information see May 28 21:41:03 volumio volumio[8821]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 21:41:03 volumio volumio[8821]: info: Discovery: Started advertising with name: Volumio May 28 21:41:03 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "squeezelite_mc"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "outputs"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "albumart"... May 28 21:41:03 volumio volumio[8821]: info: Plugin example_plugin is not enabled May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "inputs"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "updater_comm"... May 28 21:41:03 volumio volumio[8821]: info: Plugin mpdemulation is not enabled May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "rest_api"... May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "websocket"... May 28 21:41:03 volumio volumio[8821]: info: Starting Socket.io Server version 2.3.0 May 28 21:41:03 volumio volumio[8821]: info: Loading plugin "music_services_shield"... May 28 21:41:03 volumio volumio[8821]: Forking 3 albumart workers May 28 21:41:03 volumio volumio[8821]: info: Applying required configuration parameters for plugin music_services_shield May 28 21:41:03 volumio volumio[8821]: info: Loading i18n strings for locale en May 28 21:41:03 volumio volumio[8821]: Updating browse sources language May 28 21:41:03 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::initPlayerControls May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:41:04 volumio volumio[8821]: Express server listening on port 3000 May 28 21:41:04 volumio volumio[8821]: [Metrics] WebUI: 3s 127.60ms May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::resetVolumioState May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::getcurrentVolume May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::volumioRetrievevolume May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::pushState May 28 21:41:04 volumio volumio[8821]: info: CorePlayQueue::getTrack 0 May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::volumioPushState May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: Cannot read play queue from file May 28 21:41:04 volumio volumio[8821]: Starting albumart workers May 28 21:41:04 volumio volumio[8821]: Starting albumart workers May 28 21:41:04 volumio volumio[8821]: info: Volumio Network Manager: Network status updated: 0 May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::setRepeat null single undefined May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::pushState May 28 21:41:04 volumio volumio[8821]: info: CorePlayQueue::getTrack 0 May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::volumioPushState May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::setRandom null May 28 21:41:04 volumio volumio[8821]: info: CoreStateMachine::pushState May 28 21:41:04 volumio volumio[8821]: info: CorePlayQueue::getTrack 0 May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::volumioPushState May 28 21:41:04 volumio volumio[8821]: info: Setting Device type: Raspberry PI May 28 21:41:04 volumio volumio[8821]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 28 21:41:04 volumio volumio[8821]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 May 28 21:41:04 volumio volumio[8821]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 28 21:41:04 volumio volumio[8821]: info: Completed loading Core Plugins May 28 21:41:04 volumio volumio[8821]: info: Preparing to generate the ALSA configuration file May 28 21:41:04 volumio volumio[8821]: info: Asound.conf file written May 28 21:41:04 volumio volumio[8821]: Starting albumart workers May 28 21:41:04 volumio sudo[8915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 28 21:41:04 volumio sudo[8915]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:04 volumio sudo[8915]: pam_unix(sudo:session): session closed for user root May 28 21:41:04 volumio volumio[8821]: info: Output device has changed, restarting MPD May 28 21:41:04 volumio sudo[8923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 21:41:04 volumio sudo[8923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:04 volumio sudo[8923]: pam_unix(sudo:session): session closed for user root May 28 21:41:04 volumio volumio[8821]: info: ___________ START PLUGINS ___________ May 28 21:41:04 volumio volumio[8821]: info: ControllerMpd::onStart: Initializing MPD May 28 21:41:04 volumio sudo[8926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 21:41:04 volumio volumio[8821]: info: Creating MPD Configuration file May 28 21:41:04 volumio sudo[8926]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:04 volumio systemd[1]: musicservicesshield.service: Succeeded. May 28 21:41:04 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 28 21:41:04 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 28 21:41:04 volumio sudo[8929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 21:41:04 volumio systemd[1]: Stopping Music Player Daemon... May 28 21:41:04 volumio sudo[8929]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:04 volumio systemd[1]: mpd.service: Succeeded. May 28 21:41:04 volumio systemd[1]: Stopped Music Player Daemon. May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 21:41:04 volumio volumio[8821]: info: [1748443264967] CoreMusicLibrary::Adding element Media Servers May 28 21:41:04 volumio sudo[8929]: pam_unix(sudo:session): session closed for user root May 28 21:41:04 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:41:04 volumio sudo[8937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 21:41:04 volumio sudo[8937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:05 volumio systemd[1]: Starting Music Player Daemon... May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 21:41:05 volumio volumio[8821]: info: [1748443264985] CoreMusicLibrary::Adding element Last_100 May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 21:41:05 volumio volumio[8821]: info: [1748443264990] CoreMusicLibrary::Adding element Webradio May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:41:05 volumio volumio[8821]: info: Initializing BBC Radios May 28 21:41:05 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 28 21:41:05 volumio systemd[1]: mpd.service: Succeeded. May 28 21:41:05 volumio systemd[1]: Stopped Music Player Daemon. May 28 21:41:05 volumio systemd[1]: Starting Music Player Daemon... May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 21:41:05 volumio volumio[8821]: info: [squeezelite_mc] Starting proxy server... May 28 21:41:05 volumio sudo[8946]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 21:41:05 volumio sudo[8946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:05 volumio sudo[8946]: pam_unix(sudo:session): session closed for user root May 28 21:41:05 volumio volumio[8821]: info: Volumio Calling Home May 28 21:41:05 volumio volumio-remote-updater[594]: [2025-05-28 21:41:05] [connect] Successful connection May 28 21:41:05 volumio sudo[8990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 28 21:41:05 volumio volumio[8821]: info: [squeezelite_mc] Proxy server started on port 40401 May 28 21:41:05 volumio sudo[8990]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::volumioRetrievevolume May 28 21:41:05 volumio volumio[8821]: info: CoreStateMachine::pushState May 28 21:41:05 volumio volumio[8821]: info: CorePlayQueue::getTrack 0 May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::volumioPushState May 28 21:41:05 volumio volumio[8821]: info: [squeezelite_mc] Server discovery started May 28 21:41:05 volumio volumio[8821]: info: [squeezelite_mc] Player finder started May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 21:41:05 volumio volumio[8821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 21:41:05 volumio volumio[8821]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true May 28 21:41:05 volumio volumio[8821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:41:05 volumio volumio[8821]: Error: send ENETUNREACH 255.255.255.255:3483 May 28 21:41:05 volumio volumio[8821]: at doSend (dgram.js:692:16) May 28 21:41:05 volumio volumio[8821]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 28 21:41:05 volumio volumio[8821]: at afterDns (dgram.js:638:5) May 28 21:41:05 volumio volumio[8821]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 28 21:41:05 volumio volumio[8821]: errno: -101, May 28 21:41:05 volumio volumio[8821]: code: 'ENETUNREACH', May 28 21:41:05 volumio volumio[8821]: syscall: 'send', May 28 21:41:05 volumio volumio[8821]: address: '255.255.255.255', May 28 21:41:05 volumio volumio[8821]: port: 3483 May 28 21:41:05 volumio volumio[8821]: } May 28 21:41:05 volumio volumio[8821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 21:41:05 volumio dhcpcd[917]: wlan0: leased 192.168.1.109 for 14400 seconds May 28 21:41:05 volumio avahi-daemon[630]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.109. May 28 21:41:05 volumio dhcpcd[917]: wlan0: adding route to 192.168.1.0/24 May 28 21:41:05 volumio avahi-daemon[630]: New relevant interface wlan0.IPv4 for mDNS. May 28 21:41:05 volumio avahi-daemon[630]: Registering new address record for 192.168.1.109 on wlan0.IPv4. May 28 21:41:05 volumio dhcpcd[917]: wlan0: adding default route via 192.168.1.1 May 28 21:41:05 volumio sudo[8990]: pam_unix(sudo:session): session closed for user root May 28 21:41:05 volumio sudo[9040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 21:40 May 28 21:41:05 volumio sudo[9040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 21:41:05 volumio mpd[8956]: May 28 21:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"