-- 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"