-- Logs begin at Sat 2024-05-11 08:05:43 JST, end at Sat 2024-05-11 08:09:25 JST. -- May 11 08:08:05 volumio ntpd[617]: receive: Unexpected origin timestamp 0xe9e9273c.0529e905 does not match aorg 0000000000.00000000 from server@18.180.64.47 xmt 0xe9e92755.96778a4a May 11 08:08:05 volumio systemd[1]: Starting Daily apt download activities... May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 11 08:08:10 volumio volumio[725]: info: Adding plugin bluetooth to MyMusic Plugins May 11 08:08:10 volumio volumio[725]: info: Adding plugin multiroom to MyMusic Plugins May 11 08:08:10 volumio volumio[725]: info: Adding plugin metavolumio to MyMusic Plugins May 11 08:08:11 volumio volumio[725]: info: Adding plugin cd_controller to MyMusic Plugins May 11 08:08:11 volumio volumio[725]: info: Adding plugin smart_inputs to MyMusic Plugins May 11 08:08:11 volumio volumio[725]: info: Adding plugin tidalconnect to MyMusic Plugins May 11 08:08:11 volumio volumio[725]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 11 08:08:11 volumio systemd[1]: apt-daily.service: Succeeded. May 11 08:08:11 volumio systemd[1]: Started Daily apt download activities. May 11 08:08:22 volumio volumio[725]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 11 08:08:22 volumio volumio[725]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 11 08:08:22 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:22 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:22 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:22 volumio volumio[725]: info: Starting MyVolumio Remote Streaming Endpoints May 11 08:08:22 volumio volumio[725]: info: MyVolumio not started May 11 08:08:22 volumio volumio[725]: info: Initializing device activation check May 11 08:08:22 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:22 volumio volumio[725]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 11 08:08:23 volumio volumio[725]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 11 08:08:35 volumio volumio[725]: info: Starting Streaming Service Transparent Proxy May 11 08:08:35 volumio volumio[725]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 11 08:08:35 volumio volumio[725]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 11 08:08:35 volumio volumio[725]: info: Streaming services startup May 11 08:08:35 volumio volumio[725]: info: Starting Streaming Daemon May 11 08:08:36 volumio volumio[725]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 11 08:08:36 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 08:08:36 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:08:36 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root May 11 08:08:37 volumio volumio[725]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 11 08:08:37 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:37 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:08:37 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:37 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 08:08:38 volumio volumio[725]: error: Cannot start Volumio Streaming Daemon May 11 08:08:38 volumio volumio[725]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 08:08:38 volumio volumio[725]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 08:08:38 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 08:08:39 volumio volumio[725]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 May 11 08:08:41 volumio volumio-remote-updater[279]: No test mode May 11 08:08:41 volumio volumio-remote-updater[279]: No alpha test mode May 11 08:08:41 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::volumioGetBrowseSources May 11 08:08:42 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 08:08:43 volumio volumio[725]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 11 08:08:43 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 11 08:08:43 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:08:43 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:08:51 volumio volumio[725]: STREAMING PROXY: Starting server on port 3245 May 11 08:08:51 volumio volumio[725]: Node JS runtime: 14 May 11 08:08:56 volumio volumio[725]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 11 08:08:56 volumio volumio[725]: info: Completed starting MyVolumio Plugin May 11 08:08:56 volumio volumio[725]: info: BOOT COMPLETED May 11 08:08:56 volumio volumio[725]: [Metrics] CommandRouter: 102s 397.32ms May 11 08:08:56 volumio volumio[725]: info: CoreCommandRouter::volumiosetStartupVolume May 11 08:08:56 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 08:08:56 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 08:08:56 volumio volumio[725]: info: CoreCommandRouter::Close All Modals sent May 11 08:08:56 volumio volumio[725]: info: CoreCommandRouter::Close All Modals sent May 11 08:08:57 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 11 08:08:57 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 11 08:08:58 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 08:09:03 volumio volumio[725]: info: Discovery: Getting this device information May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:03 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 08:09:03 volumio volumio[725]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.20 UA: okhttp/4.9.2 Total Clients: 4 May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:03 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 11 08:09:03 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 11 08:09:07 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 08:09:07 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:09:07 volumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 08:09:07 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:09:07 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root May 11 08:09:07 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root May 11 08:09:07 volumio volumio[725]: verbose: New Socket.io Connection to 192.168.1.22 from 192.168.1.20 UA: okhttp/4.9.2 Total Clients: 5 May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::volumioGetVisibleSources May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:08 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 08:09:08 volumio volumio[725]: info: Received Get System Info May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 08:09:08 volumio volumio[725]: info: Discovery: Getting this device information May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:08 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 08:09:08 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:08 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 08:09:09 volumio volumio[725]: info: Listing playlists May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 08:09:09 volumio volumio[725]: info: Received Get System Info May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 08:09:09 volumio volumio[725]: info: Discovery: Getting this device information May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::volumioGetState May 11 08:09:09 volumio volumio[725]: info: CorePlayQueue::getTrack 0 May 11 08:09:09 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: info: Retrieving Cloud Streaming UI May 11 08:09:13 volumio volumio[725]: info: Getting Tidal Cloud Configuration May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: info: Getting Qobuz Cloud Configuration May 11 08:09:13 volumio volumio[725]: info: Asking plugin for UI Config May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: info: Getting Spotify Cloud Configuration May 11 08:09:13 volumio volumio[725]: info: Asking plugin for UI Config May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: info: Saving Spotify Acccount May 11 08:09:13 volumio volumio[725]: info: Got Tidal Cloud Configuration May 11 08:09:13 volumio volumio[725]: info: Got it May 11 08:09:13 volumio volumio[725]: info: Got it May 11 08:09:13 volumio volumio[725]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 08:09:13 volumio volumio[725]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::volumioGetBrowseSources May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::volumioGetBrowseSources May 11 08:09:13 volumio volumio[725]: info: CoreCommandRouter::volumioGetBrowseSources May 11 08:09:14 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 08:09:14 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 11 08:09:18 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 11 08:09:19 volumio volumio[725]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 11 08:09:20 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Qnap May 11 08:09:20 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 08:09:20 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root May 11 08:09:20 volumio volumio[725]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:09:20 volumio volumio[725]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Qnap' May 11 08:09:20 volumio volumio[725]: at Object.rmdirSync (fs.js:890:10) May 11 08:09:20 volumio volumio[725]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 May 11 08:09:20 volumio volumio[725]: at ChildProcess.exithandler (child_process.js:299:7) May 11 08:09:20 volumio volumio[725]: at ChildProcess.emit (events.js:315:20) May 11 08:09:20 volumio volumio[725]: at maybeClose (internal/child_process.js:1048:16) May 11 08:09:20 volumio volumio[725]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { May 11 08:09:20 volumio volumio[725]: errno: -16, May 11 08:09:20 volumio volumio[725]: syscall: 'rmdir', May 11 08:09:20 volumio volumio[725]: code: 'EBUSY', May 11 08:09:20 volumio volumio[725]: path: '/mnt/NAS/Qnap' May 11 08:09:20 volumio volumio[725]: } May 11 08:09:20 volumio volumio[725]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 08:09:25 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 08:08 May 11 08:09:25 volumio sudo[1114]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"