-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2024-04-19 10:41:35 UTC. -- Apr 19 10:40:01 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 19 10:40:01 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="xuyd2" auth_failures=2 duration=23 reason=CONN_FAILED Apr 19 10:40:07 volumio sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 10:40:07 volumio sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:07 volumio sudo[994]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:07 volumio sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 10:40:07 volumio sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:07 volumio sudo[996]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:07 volumio sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 10:40:07 volumio sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 10:40:08 volumio sudo[999]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:08 volumio volumio[856]: info: Upmpdcli Daemon Started Apr 19 10:40:08 volumio volumio[856]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 19 10:40:12 volumio volumio[856]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 19 10:40:12 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:18 volumio volumio[856]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 19 10:40:18 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:24 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="xuyd2" Apr 19 10:40:24 volumio wpa_supplicant[773]: wlan0: Trying to associate with SSID 'xuyd2' Apr 19 10:40:25 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Apr 19 10:40:25 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="xuyd2" auth_failures=3 duration=46 reason=CONN_FAILED Apr 19 10:40:28 volumio volumio[856]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 19 10:40:29 volumio volumio[856]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 19 10:40:29 volumio volumio[856]: info: Preparing to generate the ALSA configuration file Apr 19 10:40:29 volumio volumio[856]: info: Asound.conf file unchanged, so no further update is needed Apr 19 10:40:29 volumio volumio[856]: info: Output device has changed, restarting MPD Apr 19 10:40:29 volumio volumio[856]: info: Output device has changed, restarting Shairport Sync Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 10:40:29 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:29 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:29 volumio sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 10:40:29 volumio sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 10:40:29 volumio volumio[856]: info: ___________ START PLUGINS ___________ Apr 19 10:40:29 volumio volumio[856]: info: ControllerMpd::onStart: Initializing MPD Apr 19 10:40:29 volumio volumio[856]: info: Creating MPD Configuration file Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:40:29 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 10:40:29 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio volumio[856]: info: [1713523229181] CoreMusicLibrary::Adding element 媒体服务器 Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:29 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:29 volumio sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 10:40:29 volumio sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio systemd[1]: Listening on mpd.socket. Apr 19 10:40:29 volumio systemd[1]: Starting Music Player Daemon... Apr 19 10:40:29 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 10:40:29 volumio systemd[1]: mpd.service: Succeeded. Apr 19 10:40:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 10:40:29 volumio systemd[1]: Starting Music Player Daemon... Apr 19 10:40:29 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:40:29 volumio volumio[856]: info: [1713523229269] CoreMusicLibrary::Adding element Last_100 Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:40:29 volumio volumio[856]: info: [1713523229272] CoreMusicLibrary::Adding element Webradio Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 10:40:29 volumio volumio[856]: info: Volumio Calling Home Apr 19 10:40:29 volumio volumio[856]: Unhandled rejection Error: No sockets available, cannot start. Apr 19 10:40:29 volumio volumio[856]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 19 10:40:29 volumio volumio[856]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 19 10:40:29 volumio volumio[856]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 19 10:40:29 volumio volumio[856]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 19 10:40:29 volumio volumio[856]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 19 10:40:29 volumio volumio[856]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 19 10:40:29 volumio volumio[856]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 19 10:40:29 volumio volumio[856]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 19 10:40:29 volumio volumio[856]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 19 10:40:29 volumio volumio[856]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 19 10:40:29 volumio volumio[856]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 19 10:40:29 volumio volumio[856]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 19 10:40:29 volumio volumio[856]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 19 10:40:29 volumio volumio[856]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 19 10:40:29 volumio volumio[856]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 19 10:40:29 volumio volumio[856]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 19 10:40:29 volumio volumio[856]: info: MPD Permissions set Apr 19 10:40:29 volumio volumio[856]: info: MPD Permissions set Apr 19 10:40:29 volumio volumio[856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:29 volumio volumio[856]: info: Starting Shairport Sync Apr 19 10:40:29 volumio volumio[856]: info: Starting Shairport Sync Apr 19 10:40:29 volumio volumio[856]: info: Starting Shairport Sync Apr 19 10:40:29 volumio sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 10:40:29 volumio sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 10:40:29 volumio sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 10:40:29 volumio sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:40:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 10:40:29 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 10:40:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 10:40:29 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 19 10:40:29 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 19 10:40:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 10:40:29 volumio sudo[1059]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 10:40:29 volumio volumio[856]: info: Shairport-Sync Started Apr 19 10:40:29 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 19 10:40:29 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 10:40:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 10:40:29 volumio volumio[856]: Error adding Membership: Error: addMembership EINVAL Apr 19 10:40:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 10:40:29 volumio sudo[1061]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:29 volumio sudo[1064]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:29 volumio volumio[856]: info: Shairport-Sync Started Apr 19 10:40:29 volumio volumio[856]: info: Shairport-Sync Started Apr 19 10:40:30 volumio mpd[1044]: Apr 19 10:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 10:40:30 volumio systemd[1]: Started Music Player Daemon. Apr 19 10:40:30 volumio sudo[1036]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:30 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Apr 19 10:40:30 volumio volumio[856]: info: Completed starting Core Plugins Apr 19 10:40:30 volumio volumio[856]: info: ------------------------------------------- Apr 19 10:40:30 volumio volumio[856]: info: ----- MyVolumio plugins startup ---- Apr 19 10:40:30 volumio volumio[856]: info: ------------------------------------------- Apr 19 10:40:30 volumio volumio[856]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 10:40:30 volumio volumio[856]: error: MPD error: The expression evaluated to a falsy value: Apr 19 10:40:30 volumio volumio[856]: assert.ok(self.idling) Apr 19 10:40:30 volumio volumio[856]: error: The expression evaluated to a falsy value: Apr 19 10:40:30 volumio volumio[856]: assert.ok(self.idling) Apr 19 10:40:30 volumio volumio[856]: error: updateQueue error: null Apr 19 10:40:30 volumio volumio[856]: info: MPD running with PID1044 Apr 19 10:40:30 volumio volumio[856]: ,establishing connection Apr 19 10:40:30 volumio volumio[856]: error: updateQueue error: null Apr 19 10:40:33 volumio ntpd[692]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 19 10:40:35 volumio ntpd[692]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 19 10:40:36 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:38 volumio ntpd[692]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 19 10:40:39 volumio ntpd[692]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 19 10:40:39 volumio volumio[856]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=1e345b1b8b3fc316ced1f4235cb3e694" http://updates.volumio.org/downloader-v1/track-device Apr 19 10:40:39 volumio volumio[856]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 19 10:40:39 volumio volumio[856]: Dload Upload Total Spent Left Speed Apr 19 10:40:39 volumio volumio[856]: [132B blob data] Apr 19 10:40:39 volumio volumio[856]: retrying in 5 seconds, trial 0 Apr 19 10:40:39 volumio volumio[856]: info: Volumio Calling Home Apr 19 10:40:42 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:48 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:49 volumio volumio[856]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=1e345b1b8b3fc316ced1f4235cb3e694" http://updates.volumio.org/downloader-v1/track-device Apr 19 10:40:49 volumio volumio[856]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 19 10:40:49 volumio volumio[856]: Dload Upload Total Spent Left Speed Apr 19 10:40:49 volumio volumio[856]: [132B blob data] Apr 19 10:40:49 volumio volumio[856]: retrying in 5 seconds, trial 1 Apr 19 10:40:49 volumio volumio[856]: info: Volumio Calling Home Apr 19 10:40:50 volumio volumio[856]: error: MyVolumio Plugin failed to start in a timely fashion Apr 19 10:40:50 volumio volumio[856]: info: BOOT COMPLETED Apr 19 10:40:50 volumio volumio[856]: [Metrics] CommandRouter: 55s 976.53ms Apr 19 10:40:50 volumio volumio[856]: info: CoreCommandRouter::volumiosetStartupVolume Apr 19 10:40:50 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:40:50 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:50 volumio volumio[856]: info: CoreCommandRouter::Close All Modals sent Apr 19 10:40:50 volumio volumio[856]: info: CoreCommandRouter::Close All Modals sent Apr 19 10:40:51 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 19 10:40:51 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 19 10:40:51 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 19 10:40:54 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:40:55 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 5 ep 1 Apr 19 10:40:55 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a1133b0 00000000 00000000 0e000000 05028001 Apr 19 10:40:58 volumio systemd[1]: nmbd.service: Start operation timed out. Terminating. Apr 19 10:40:58 volumio systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Apr 19 10:40:58 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Apr 19 10:40:58 volumio systemd[1]: Failed to start Samba NMB Daemon. Apr 19 10:40:58 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 19 10:40:58 volumio winbindd[1098]: [2024/04/19 10:40:58.305555, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 19 10:40:58 volumio winbindd[1098]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 19 10:40:58 volumio winbindd[1098]: [2024/04/19 10:40:58.323848, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 10:40:58 volumio winbindd[1098]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 19 10:40:58 volumio systemd[1]: Started Samba Winbind Daemon. Apr 19 10:40:58 volumio systemd[1]: Starting Samba SMB Daemon... Apr 19 10:40:58 volumio winbindd[1098]: [2024/04/19 10:40:58.685949, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 19 10:40:58 volumio winbindd[1098]: res_names->count = 3, expected 4 Apr 19 10:40:58 volumio smbd[1102]: [2024/04/19 10:40:58.702317, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 10:40:58 volumio smbd[1102]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 19 10:40:58 volumio systemd[1]: Started Samba SMB Daemon. Apr 19 10:40:58 volumio systemd[1]: Reached target Multi-User System. Apr 19 10:40:58 volumio systemd[1]: Reached target Graphical Interface. Apr 19 10:40:58 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 10:40:58 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 10:40:58 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 10:40:58 volumio systemd[1]: Startup finished in 9.594s (kernel) + 1min 39.593s (userspace) = 1min 49.188s. Apr 19 10:40:59 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 10:40:59 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:40:59 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 10:40:59 volumio volumio[856]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=1e345b1b8b3fc316ced1f4235cb3e694" http://updates.volumio.org/downloader-v1/track-device Apr 19 10:40:59 volumio volumio[856]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 19 10:40:59 volumio volumio[856]: Dload Upload Total Spent Left Speed Apr 19 10:40:59 volumio volumio[856]: [132B blob data] Apr 19 10:40:59 volumio volumio[856]: retrying in 5 seconds, trial 2 Apr 19 10:40:59 volumio volumio[856]: info: Volumio Calling Home Apr 19 10:41:00 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:41:06 volumio wpa_supplicant[773]: wlan0: Failed to initiate sched scan Apr 19 10:41:12 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="xuyd2" Apr 19 10:41:12 volumio wpa_supplicant[773]: wlan0: Trying to associate with SSID 'xuyd2' Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: carrier acquired Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: connected to Access Point `xuyd2' Apr 19 10:41:13 volumio wpa_supplicant[773]: wlan0: Associated with 78:60:5b:a2:09:5f Apr 19 10:41:13 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:60:5b:a2:09:5f completed [id=0 id_str=] Apr 19 10:41:13 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 19 10:41:13 volumio wpa_supplicant[773]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Apr 19 10:41:13 volumio dhcpcd[785]: DUID 00:01:00:01:2b:b8:6c:3c:b8:27:eb:ec:cb:43 Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: IAID 32:15:4b:86 Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: adding address fe80::dea6:32ff:fe15:4b86 Apr 19 10:41:13 volumio dhcpcd[785]: ipv6_addaddr1: Permission denied Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: carrier lost Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: carrier acquired Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: IAID 32:15:4b:86 Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: rebinding lease of 192.168.11.196 Apr 19 10:41:13 volumio dhcpcd[785]: wlan0: probing address 192.168.11.196/24 Apr 19 10:41:14 volumio dhcpcd[785]: wlan0: soliciting an IPv6 router Apr 19 10:41:18 volumio dhcpcd[785]: wlan0: leased 192.168.11.196 for 43200 seconds Apr 19 10:41:18 volumio avahi-daemon[547]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.196. Apr 19 10:41:18 volumio avahi-daemon[547]: New relevant interface wlan0.IPv4 for mDNS. Apr 19 10:41:18 volumio dhcpcd[785]: wlan0: adding route to 192.168.11.0/24 Apr 19 10:41:18 volumio avahi-daemon[547]: Registering new address record for 192.168.11.196 on wlan0.IPv4. Apr 19 10:41:18 volumio dhcpcd[785]: wlan0: adding default route via 192.168.11.1 Apr 19 10:41:18 volumio volumio[856]: verbose: New Socket.io Connection to 192.168.11.196 from 192.168.11.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 3 Apr 19 10:41:18 volumio volumio[856]: verbose: New Socket.io Connection to 192.168.11.196 from 192.168.11.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 Apr 19 10:41:18 volumio volumio[856]: verbose: New Socket.io Connection to 192.168.11.196 from 192.168.11.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 5 Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin bluetooth to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin multiroom to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin metavolumio to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin cd_controller to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 19 10:41:19 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 19 10:41:20 volumio volumio[856]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 19 10:41:20 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 19 10:41:20 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:20 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:20 volumio volumio[856]: info: Starting MyVolumio Remote Streaming Endpoints Apr 19 10:41:20 volumio volumio[856]: info: MyVolumio login type: Token Apr 19 10:41:20 volumio volumio[856]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 19 10:41:20 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 19 10:41:20 volumio ntpd[692]: Listen normally on 3 wlan0 192.168.11.196:123 Apr 19 10:41:20 volumio ntpd[692]: new interface(s) found: waking up resolver Apr 19 10:41:21 volumio volumio[856]: info: Starting Streaming Service Transparent Proxy Apr 19 10:41:21 volumio volumio[856]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 19 10:41:21 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 19 10:41:21 volumio volumio[856]: info: Streaming services startup Apr 19 10:41:21 volumio volumio[856]: info: Starting Streaming Daemon Apr 19 10:41:21 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 10:41:21 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:21 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:21 volumio volumio[856]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 19 10:41:21 volumio volumio[856]: error: Cannot start Volumio Streaming Daemon Apr 19 10:41:21 volumio volumio[856]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 10:41:21 volumio volumio[856]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 10:41:21 volumio volumio[856]: info: Discovery: adding 1c0910de-b758-4edc-9cf2-4bde49142e62 Apr 19 10:41:21 volumio volumio[856]: info: Discovery: Found device Volumio Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 19 10:41:21 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 19 10:41:21 volumio volumio[856]: verbose: New Socket.io Connection to 192.168.11.196 from 192.168.11.139 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 6 Apr 19 10:41:21 volumio volumio[856]: STREAMING PROXY: Starting server on port 3245 Apr 19 10:41:21 volumio volumio[856]: Node JS runtime: 14 Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 19 10:41:21 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 10:41:21 volumio volumio[856]: info: Listing playlists Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 10:41:21 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:41:22 volumio volumio[856]: info: CoreCommandRouter::volumioGetQueue Apr 19 10:41:22 volumio volumio[856]: info: CoreStateMachine::getQueue Apr 19 10:41:22 volumio volumio[856]: info: CorePlayQueue::getQueue Apr 19 10:41:22 volumio volumio[1002]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 19 10:41:22 volumio volumio[856]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 19 10:41:23 volumio volumio[856]: info: MyVolumio token set successfully Apr 19 10:41:23 volumio volumio[856]: info: MYVOLUMIO: Adding device Apr 19 10:41:23 volumio volumio[856]: info: MYVOLUMIO: Evaluating Server Apr 19 10:41:23 volumio volumio[1002]: ..................++++ Apr 19 10:41:24 volumio volumio[856]: info: MyVolumio Plan changed: premium Apr 19 10:41:24 volumio volumio[856]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 19 10:41:24 volumio volumio[856]: info: Removing browser output: myVolumio user plan is not superstar Apr 19 10:41:24 volumio volumio[856]: info: Removing audio output: Apr 19 10:41:24 volumio volumio[856]: info: MYVOLUMIO: Adding device Apr 19 10:41:24 volumio volumio[856]: info: MYVOLUMIO: Evaluating Server Apr 19 10:41:24 volumio volumio[856]: info: Remote config written successfully Apr 19 10:41:24 volumio volumio[856]: info: Starting Tunnel 1 Apr 19 10:41:24 volumio volumio[856]: info: Starting Tunnel Connection Checker Apr 19 10:41:24 volumio volumio[856]: info: MYVolumio Device enabled Apr 19 10:41:24 volumio volumio[856]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 19 10:41:24 volumio volumio[856]: info: MyVolumio status changed Apr 19 10:41:24 volumio volumio[856]: info: Streaming services startup Apr 19 10:41:24 volumio volumio[856]: info: Starting Streaming Daemon Apr 19 10:41:24 volumio sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 19 10:41:24 volumio sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:24 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 19 10:41:24 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 19 10:41:24 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:24 volumio volumio[856]: info: [1713523284684] Starting BluetoothController Apr 19 10:41:24 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 19 10:41:25 volumio volumio[856]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 19 10:41:25 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 19 10:41:25 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:25 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:25 volumio volumio[856]: info: MRS: MultiRoom plugin initialized Apr 19 10:41:25 volumio volumio[856]: info: MRS: STOPPING SNAPCLIENT Apr 19 10:41:25 volumio volumio[856]: info: MRS: Snap server stop Apr 19 10:41:25 volumio sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 19 10:41:25 volumio sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:25 volumio volumio[856]: info: MRS: STOPPING volumioStreaming Apr 19 10:41:25 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 19 10:41:25 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:25 volumio sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 19 10:41:25 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:25 volumio sudo[1219]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 19 10:41:26 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 19 10:41:26 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 19 10:41:26 volumio volumio[856]: info: Preparing to generate the ALSA configuration file Apr 19 10:41:26 volumio volumio[856]: info: Setting Geolocation for MyVolumio to us2 Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: MRS: Removed streaming files Apr 19 10:41:26 volumio volumio[856]: info: MRS: volumioStreaming STOPPED Apr 19 10:41:26 volumio volumio[856]: info: MRS: SNAPSERVER STOPPED Apr 19 10:41:26 volumio volumio[856]: info: MRS: SNAPCLIENT STOPPED Apr 19 10:41:26 volumio volumio[856]: error: Cannot start Volumio Streaming Daemon Apr 19 10:41:26 volumio volumio[856]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 19 10:41:26 volumio volumio[856]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 19 10:41:26 volumio volumio[856]: info: Setting Geolocation for MyVolumio to us2 Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: Asound.conf file unchanged, so no further update is needed Apr 19 10:41:26 volumio volumio[856]: info: Output device has changed, restarting MPD Apr 19 10:41:26 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 10:41:26 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio volumio[856]: info: Output device has changed, restarting Shairport Sync Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 10:41:26 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 10:41:26 volumio volumio[856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 10:41:26 volumio volumio[856]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 19 10:41:26 volumio volumio[856]: info: Adding METAVOLUMIO REST API Endpoints Apr 19 10:41:26 volumio volumio[856]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 19 10:41:26 volumio volumio[856]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 19 10:41:26 volumio volumio[856]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 19 10:41:26 volumio volumio[856]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 19 10:41:26 volumio volumio[856]: info: Adding Manifest REST API Endpoints Apr 19 10:41:26 volumio volumio[856]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 19 10:41:26 volumio volumio[856]: info: Preparing CD Folders Apr 19 10:41:26 volumio volumio[856]: info: Adding CD REST API Endpoints Apr 19 10:41:26 volumio volumio[856]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 19 10:41:26 volumio volumio[856]: info: Starting UDEV Watcher for CD Apr 19 10:41:26 volumio volumio[856]: info: Detecting CD presence with UDEV Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 19 10:41:26 volumio systemd[1]: mpd.service: Succeeded. Apr 19 10:41:26 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 10:41:26 volumio sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr0 Apr 19 10:41:26 volumio sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio sudo[1243]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio systemd[1]: Starting Music Player Daemon... Apr 19 10:41:26 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr1 Apr 19 10:41:26 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:26 volumio volumio[856]: /bin/chmod: cannot access '/dev/sr1': No such file or directory Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:41:26 volumio volumio[856]: info: [1713523286651] CoreMusicLibrary::Adding element Audio CD Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:41:26 volumio volumio[856]: Cannot find translation for source Audio CD Apr 19 10:41:26 volumio volumio[856]: info: Set CD Speed to 4X Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 19 10:41:26 volumio volumio[856]: error: Hi Res Audio Failed Login: Missing Login Data Apr 19 10:41:26 volumio volumio[856]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 19 10:41:26 volumio volumio[856]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 19 10:41:26 volumio volumio[856]: info: Refreshing TIDAL token Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 19 10:41:26 volumio volumio[856]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Apr 19 10:41:26 volumio volumio[856]: info: Stopping AccessToken refresher cron for QOBUZ Apr 19 10:41:26 volumio volumio[856]: info: AccessToken refresher cron started for QOBUZ Apr 19 10:41:26 volumio volumio[856]: info: Adding QOBUZ REST API Endpoints Apr 19 10:41:26 volumio volumio[856]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:26 volumio volumio[856]: error: MPD error: Error: write EPIPE Apr 19 10:41:26 volumio volumio[856]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 19 10:41:26 volumio volumio[1002]: .............................++++ Apr 19 10:41:26 volumio volumio[1002]: e is 65537 (0x010001) Apr 19 10:41:26 volumio volumio[1002]: writing RSA key Apr 19 10:41:26 volumio volumio[856]: info: MPD Permissions set Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 10:41:26 volumio volumio[856]: info: Starting Shairport Sync Apr 19 10:41:26 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 10:41:26 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 10:41:26 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 19 10:41:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 10:41:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 10:41:27 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:27 volumio volumio[856]: info: Shairport-Sync Started Apr 19 10:41:27 volumio mpd[1241]: Apr 19 10:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 10:41:27 volumio volumio[856]: info: Updating MyVolumio device info Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio systemd[1]: Started Music Player Daemon. Apr 19 10:41:27 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:27 volumio volumio[856]: info: Successfully Added MyVolumio device Apr 19 10:41:27 volumio volumio[856]: info: Updating MyVolumio device info Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 10:41:27 volumio volumio[856]: error: updateQueue error: null Apr 19 10:41:27 volumio volumio[856]: info: Successfully Added MyVolumio device Apr 19 10:41:27 volumio volumio[856]: info: Successfully Updated MyVolumio device Apr 19 10:41:28 volumio volumio[856]: info: Successfully Updated MyVolumio device Apr 19 10:41:28 volumio volumio[856]: info: Access Token successfully retrieved Apr 19 10:41:28 volumio kernel: usb usb2-port1: over-current change #1 Apr 19 10:41:28 volumio kernel: usb 2-1: USB disconnect, device number 2 Apr 19 10:41:28 volumio kernel: device offline error, dev sda, sector 0 op 0x1:(WRITE) flags 0x800 phys_seg 0 prio class 2 Apr 19 10:41:28 volumio kernel: device offline error, dev sda, sector 0 op 0x1:(WRITE) flags 0x800 phys_seg 0 prio class 2 Apr 19 10:41:28 volumio volumio[856]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-WDC_WD20_PURX-78P6ZY0_00000000000000000000-0:0-part1 /dev/disk/by-label/NAS-DISK1 /dev/disk/by-partuuid/125036c9-ad37-4263-b015-aeafcd91c2a8 /dev/disk/by-partlabel/primary /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/04490adb-fbe6-4546-ab2e-3490bb3ec860","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"NAS-DISK1","ID_FS_LABEL_ENC":"NAS-DISK1","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"04490adb-fbe6-4546-ab2e-3490bb3ec860","ID_FS_UUID_ENC":"04490adb-fbe6-4546-ab2e-3490bb3ec860","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"PURX-78P6ZY0","ID_MODEL_ENC":"PURX-78P6ZY0\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9561","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"primary","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"3886055425","ID_PART_ENTRY_TYPE":"0fc63daf-8483-4772-8e79-3d69d8477de4","ID_PART_ENTRY_UUID":"125036c9-ad37-4263-b015-aeafcd91c2a8","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"41657b78-1edb-47dc-99e7-a2def84d404b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0105","ID_SERIAL":"WDC_WD20_PURX-78P6ZY0_00000000000000000000-0:0","ID_SERIAL_SHORT":"00000000000000000000","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WDC_WD20","ID_VENDOR_ENC":"WDC\\x20WD20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"primary","SEQNUM":"1833","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"13728769"} Apr 19 10:41:28 volumio sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Apr 19 10:41:28 volumio sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:28 volumio systemd[1]: media-NAS\x2dDISK1.mount: Succeeded. Apr 19 10:41:28 volumio kernel: EXT4-fs (sda1): unmounting filesystem. Apr 19 10:41:28 volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:28 volumio volumio[856]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/d4cbb0ab-0f80-4600-9c79-e63d258addd9 /dev/disk/by-label/NAS-eDISK1 /dev/disk/by-uuid/c04124e0-f987-44e7-b06e-f85513ec7a56 /dev/disk/by-partlabel/primary /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-WDC_WD20_PURX-78P6ZY0_00000000000000000000-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"NAS-eDISK1","ID_FS_LABEL_ENC":"NAS-eDISK1","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"c04124e0-f987-44e7-b06e-f85513ec7a56","ID_FS_UUID_ENC":"c04124e0-f987-44e7-b06e-f85513ec7a56","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"PURX-78P6ZY0","ID_MODEL_ENC":"PURX-78P6ZY0\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9561","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"primary","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"3886059520","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"20969472","ID_PART_ENTRY_TYPE":"0fc63daf-8483-4772-8e79-3d69d8477de4","ID_PART_ENTRY_UUID":"d4cbb0ab-0f80-4600-9c79-e63d258addd9","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"41657b78-1edb-47dc-99e7-a2def84d404b","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0105","ID_SERIAL":"WDC_WD20_PURX-78P6ZY0_00000000000000000000-0:0","ID_SERIAL_SHORT":"00000000000000000000","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WDC_WD20","ID_VENDOR_ENC":"WDC\\x20WD20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"primary","SEQNUM":"1834","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"13711873"} Apr 19 10:41:28 volumio kernel: usb 1-1-port1: over-current change #1 Apr 19 10:41:28 volumio sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Apr 19 10:41:28 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:28 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Apr 19 10:41:28 volumio systemd[1]: media-NAS\x2deDISK1.mount: Succeeded. Apr 19 10:41:28 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:28 volumio kernel: EXT4-fs (sda2): unmounting filesystem. Apr 19 10:41:29 volumio kernel: usb 1-1-port2: over-current change #1 Apr 19 10:41:29 volumio sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 19 10:41:29 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:29 volumio systemd[1]: Started MyVolumio SSH Tunnel. Apr 19 10:41:29 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:29 volumio volumio[856]: info: Remote SSH Started Apr 19 10:41:29 volumio autossh[1397]: port set to 0, monitoring disabled Apr 19 10:41:29 volumio autossh[1397]: starting ssh (count 1) Apr 19 10:41:29 volumio autossh[1397]: ssh child pid is 1400 Apr 19 10:41:29 volumio volumio[856]: info: Successfully retrieved User Session From TIDAL Apr 19 10:41:29 volumio volumio[856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 19 10:41:29 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 19 10:41:29 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Apr 19 10:41:29 volumio volumio[856]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:1/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/d4069d1a-01 /dev/disk/by-id/usb-WDC_WD10_EACS-00D6B1_00000000000000000000-0:1-part1 /dev/disk/by-label/新加卷 /dev/disk/by-uuid/C01CA3381CA327FC /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:1-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:1/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_LABEL":"新加卷","ID_FS_LABEL_ENC":"新加卷","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"C01CA3381CA327FC","ID_FS_UUID_ENC":"C01CA3381CA327FC","ID_INSTANCE":"0:1","ID_MODEL":"EACS-00D6B1","ID_MODEL_ENC":"EACS-00D6B1\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"9561","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1953519616","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"d4069d1a-01","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"d4069d1a","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:1","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_1","ID_REVISION":"0105","ID_SERIAL":"WDC_WD10_EACS-00D6B1_00000000000000000000-0:1","ID_SERIAL_SHORT":"00000000000000000000","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WDC_WD10","ID_VENDOR_ENC":"WDC\\x20WD10","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"1845","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"13717638"} Apr 19 10:41:29 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Apr 19 10:41:29 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:29 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:29 volumio volumio[856]: umount: /dev/sdb1: no mount point specified. Apr 19 10:41:29 volumio volumio[856]: error: Failed to umount 新加卷: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Apr 19 10:41:29 volumio volumio[856]: umount: /dev/sdb1: no mount point specified. Apr 19 10:41:29 volumio kernel: sd 0:0:0:1: [sdb] Synchronizing SCSI cache Apr 19 10:41:29 volumio kernel: usb 1-1.3: reset high-speed USB device number 4 using xhci_hcd Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 10:41:29 volumio kernel: usb 1-1-port3: over-current change #1 Apr 19 10:41:29 volumio kernel: sr 1:0:0:0: Power-on or device reset occurred Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::volumioGetState Apr 19 10:41:29 volumio volumio[856]: info: CorePlayQueue::getTrack 0 Apr 19 10:41:29 volumio volumio[856]: info: Clearing queue from CD entries Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:41:29 volumio volumio[856]: info: Successfully retrieved User Subscription From TIDAL Apr 19 10:41:29 volumio volumio[856]: info: Adding TIDAL to Browse Sources Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 10:41:29 volumio volumio[856]: info: [1713523289991] CoreMusicLibrary::Adding element TIDAL Apr 19 10:41:29 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 10:41:29 volumio volumio[856]: Cannot find translation for source TIDAL Apr 19 10:41:29 volumio volumio[856]: info: Stopping AccessToken refresher cron Apr 19 10:41:30 volumio volumio[856]: info: AccessToken refresher cron started Apr 19 10:41:30 volumio volumio[856]: info: Adding TIDAL REST API Endpoints Apr 19 10:41:30 volumio volumio[856]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 19 10:41:30 volumio kernel: usb 1-1-port4: over-current change #1 Apr 19 10:41:30 volumio volumiossh-tunnel[1396]: Warning: Permanently added '[us2.myvolumio.org]:2222,[137.184.235.84]:2222' (RSA) to the list of known hosts. Apr 19 10:41:30 volumio kernel: sd 0:0:0:1: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Apr 19 10:41:30 volumio kernel: usb usb2-port2: over-current change #1 Apr 19 10:41:30 volumio kernel: usb 1-1.1: new high-speed USB device number 6 using xhci_hcd Apr 19 10:41:30 volumio kernel: usb usb2-port3: over-current change #1 Apr 19 10:41:31 volumio kernel: usb usb2-port4: over-current change #1 Apr 19 10:41:31 volumio kernel: usb usb2-port1: over-current change #2 Apr 19 10:41:31 volumio volumio[856]: info: Error on Writing CDDB entry: Error: Command failed: /usr/bin/abcde -N -a cddb -c /etc/abcdecddb.conf -d /dev/sr0 Apr 19 10:41:31 volumio volumio[856]: [WARNING] something went wrong while querying the CD... Maybe a DATA CD or the CD is not loaded? Apr 19 10:41:31 volumio volumio[856]: [WARNING] Error trying to calculate disc ids without lead-out information. Apr 19 10:41:31 volumio kernel: usb 2-1: new SuperSpeed USB device number 3 using xhci_hcd Apr 19 10:41:31 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=9561, bcdDevice= 1.05 Apr 19 10:41:31 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=5 Apr 19 10:41:31 volumio kernel: usb 2-1: Product: JMS56x Series Apr 19 10:41:31 volumio kernel: usb 2-1: Manufacturer: JMicron Apr 19 10:41:31 volumio kernel: usb 2-1: SerialNumber: 00000000000000000000 Apr 19 10:41:31 volumio kernel: scsi host0: uas Apr 19 10:41:31 volumio kernel: scsi 0:0:0:0: Direct-Access WDC WD20 PURX-78P6ZY0 0105 PQ: 0 ANSI: 6 Apr 19 10:41:31 volumio kernel: scsi 0:0:0:0: Attached scsi generic sg0 type 0 Apr 19 10:41:31 volumio kernel: scsi 0:0:0:1: Direct-Access WDC WD10 EACS-00D6B1 0105 PQ: 0 ANSI: 6 Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 67 00 10 08 Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: Attached scsi generic sg1 type 0 Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] Write Protect is off Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] Mode Sense: 67 00 10 08 Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, supports DPO and FUA Apr 19 10:41:31 volumio kernel: sda: sda1 sda2 Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] Preferred minimum I/O size 4096 bytes Apr 19 10:41:31 volumio kernel: sd 0:0:0:1: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Apr 19 10:41:31 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 19 10:41:32 volumio kernel: sdb: sdb1 Apr 19 10:41:32 volumio kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Apr 19 10:41:32 volumio volumio[856]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/NAS-DISK1 Apr 19 10:41:32 volumio volumio[856]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/NAS-eDISK1 Apr 19 10:41:33 volumio volumio[856]: info: Mounting Device 新加卷 Apr 19 10:41:33 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/新加卷 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 19 10:41:33 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:33 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:33 volumio volumio[856]: $MFTMirr does not match $MFT (record 3). Apr 19 10:41:33 volumio volumio[856]: Failed to mount '/dev/sdb1': Input/output error Apr 19 10:41:33 volumio volumio[856]: NTFS is either inconsistent, or there is a hardware fault, or it's a Apr 19 10:41:33 volumio volumio[856]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Apr 19 10:41:33 volumio volumio[856]: then reboot into Windows twice. The usage of the /f parameter is very Apr 19 10:41:33 volumio volumio[856]: important! If the device is a SoftRAID/FakeRAID then first activate Apr 19 10:41:33 volumio volumio[856]: it and mount a different device under the /dev/mapper/ directory, (e.g. Apr 19 10:41:33 volumio volumio[856]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Apr 19 10:41:33 volumio volumio[856]: for more details. Apr 19 10:41:33 volumio volumio[856]: error: Failed to mount 新加卷: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/新加卷" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 19 10:41:33 volumio volumio[856]: $MFTMirr does not match $MFT (record 3). Apr 19 10:41:33 volumio volumio[856]: Failed to mount '/dev/sdb1': Input/output error Apr 19 10:41:33 volumio volumio[856]: NTFS is either inconsistent, or there is a hardware fault, or it's a Apr 19 10:41:33 volumio volumio[856]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Apr 19 10:41:33 volumio volumio[856]: then reboot into Windows twice. The usage of the /f parameter is very Apr 19 10:41:33 volumio volumio[856]: important! If the device is a SoftRAID/FakeRAID then first activate Apr 19 10:41:33 volumio volumio[856]: it and mount a different device under the /dev/mapper/ directory, (e.g. Apr 19 10:41:33 volumio volumio[856]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Apr 19 10:41:33 volumio volumio[856]: for more details. Apr 19 10:41:33 volumio volumio[856]: info: Mounting Device NAS-eDISK1 Apr 19 10:41:33 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/NAS-eDISK1 -o noatime Apr 19 10:41:33 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:33 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:33 volumio kernel: EXT4-fs (sda2): recovery complete Apr 19 10:41:33 volumio kernel: EXT4-fs (sda2): mounted filesystem with ordered data mode. Quota mode: none. Apr 19 10:41:33 volumio volumio[856]: info: Mounting Device NAS-DISK1 Apr 19 10:41:33 volumio sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/NAS-DISK1 -o noatime Apr 19 10:41:33 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 10:41:33 volumio kernel: EXT4-fs (sda1): recovery complete Apr 19 10:41:33 volumio kernel: EXT4-fs (sda1): mounted filesystem with ordered data mode. Quota mode: none. Apr 19 10:41:33 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Apr 19 10:41:33 volumio volumio[856]: info: Preload queue cleared Apr 19 10:41:33 volumio volumio[856]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 19 10:41:33 volumio volumio[856]: info: CoreStateMachine::ClearQueue Apr 19 10:41:33 volumio volumio[856]: info: CoreStateMachine::stop Apr 19 10:41:33 volumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 10:41:33 volumio volumio[856]: info: CorePlayQueue::clearPlayQueue Apr 19 10:41:33 volumio volumio[856]: info: CorePlayQueue::saveQueue Apr 19 10:41:33 volumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Apr 19 10:41:33 volumio volumio[856]: info: CoreStateMachine::addQueueItems Apr 19 10:41:33 volumio volumio[856]: info: CorePlayQueue::addQueueItems Apr 19 10:41:33 volumio volumio[856]: info: Preload queue cleared Apr 19 10:41:33 volumio volumio[856]: info: Adding Item to queue: music-library/USB/NAS-DISK1/音乐/中国民乐系列/WAV格式/马久越-聆听中国2CD精灵2019民乐WAV Apr 19 10:41:33 volumio volumio[856]: info: Exploding uri music-library/USB/NAS-DISK1/音乐/中国民乐系列/WAV格式/马久越-聆听中国2CD精灵2019民乐WAV in service mpd Apr 19 10:41:34 volumio kernel: usb 1-1.2: USB disconnect, device number 3 Apr 19 10:41:34 volumio kernel: usb 1-1.2.2: USB disconnect, device number 5 Apr 19 10:41:34 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using xhci_hcd Apr 19 10:41:34 volumio kernel: usb 1-1.2: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11 Apr 19 10:41:34 volumio kernel: usb 1-1.2: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 19 10:41:34 volumio kernel: usb 1-1.2: Product: USB 2.0 Hub Apr 19 10:41:34 volumio kernel: hub 1-1.2:1.0: USB hub found Apr 19 10:41:34 volumio kernel: hub 1-1.2:1.0: 4 ports detected Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: new high-speed USB device number 8 using xhci_hcd Apr 19 10:41:35 volumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.06 Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: Product: E50 Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: Manufacturer: Topping Apr 19 10:41:35 volumio kernel: usb 1-1.2.2: 1:3 : unsupported format bits 0x100000000 Apr 19 10:41:35 volumio volumio[856]: info: Scanning new location : "USB/NAS-DISK1" Apr 19 10:41:35 volumio kernel: hid-generic 0003:152A:8750.0002: hiddev96,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:01:00.0-1.2.2/input3 Apr 19 10:41:35 volumio volumio[856]: info: ALBUMART /albumart?cacheid=58&web=/%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD%C2%B7%E7%B2%BE%E7%81%B5%20CD1/extralarge&path=%2Fmnt%2FUSB%2FNAS-DISK1%2F%E9%9F%B3%E4%B9%90%2F%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E7%B3%BB%E5%88%97%2FWAV%E6%A0%BC%E5%BC%8F%2F%E9%A9%AC%E4%B9%85%E8%B6%8A-%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD2CD%E7%B2%BE%E7%81%B52019%E6%B0%91%E4%B9%90WAV%2F%E9%A9%AC%E4%B9%85%E8%B6%8A-%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD2019%E7%B2%BE%E7%81%B5CD1WAV%2F%E9%A9%AC%E4%B9%85%E8%B6%8A%20-%20%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD%C2%B7%E7%B2%BE%E7%81%B5%20CD1.cue&metadata=false Apr 19 10:41:35 volumio volumio[856]: info: URI /mnt/USB/NAS-DISK1/音乐/中国民乐系列/WAV格式/马久越-聆听中国2CD精灵2019民乐WAV/马久越-聆听中国2019精灵CD1WAV/马久越 - 聆听中国·精灵 CD1.cue Apr 19 10:41:35 volumio volumio[856]: info: ALBUMART /albumart?cacheid=58&web=/%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD%C2%B7%E7%B2%BE%E7%81%B5%20CD1/extralarge&path=%2Fmnt%2FUSB%2FNAS-DISK1%2F%E9%9F%B3%E4%B9%90%2F%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E7%B3%BB%E5%88%97%2FWAV%E6%A0%BC%E5%BC%8F%2F%E9%A9%AC%E4%B9%85%E8%B6%8A-%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD2CD%E7%B2%BE%E7%81%B52019%E6%B0%91%E4%B9%90WAV%2F%E9%A9%AC%E4%B9%85%E8%B6%8A-%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD2019%E7%B2%BE%E7%81%B5CD1WAV%2F%E9%A9%AC%E4%B9%85%E8%B6%8A%20-%20%E8%81%86%E5%90%AC%E4%B8%AD%E5%9B%BD%C2%B7%E7%B2%BE%E7%81%B5%20CD1.cue&metadata=false Apr 19 10:41:35 volumio volumio[856]: info: URI /mnt/USB/NAS-DISK1/音乐/中国民乐系列/WAV格式/马久越-聆听中国2CD精灵2019民乐WAV/马久越-聆听中国2019精灵CD1WAV/马久越 - 聆听中国·精灵 CD1.cue Apr 19 10:41:35 volumio volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 10:41:35 volumio volumio[856]: Error: Unable to resolve or reject the same promise twice Apr 19 10:41:35 volumio volumio[856]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 19 10:41:35 volumio volumio[856]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 19 10:41:35 volumio volumio[856]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 19 10:41:35 volumio volumio[856]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 19 10:41:35 volumio volumio[856]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 19 10:41:35 volumio volumio[856]: at Socket.emit (events.js:315:20) Apr 19 10:41:35 volumio volumio[856]: at addChunk (internal/streams/readable.js:309:12) Apr 19 10:41:35 volumio volumio[856]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 19 10:41:35 volumio volumio[856]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 19 10:41:35 volumio volumio[856]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 19 10:41:35 volumio volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 10:41:35 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-19 10:40 Apr 19 10:41:35 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"