-- Logs begin at Thu 2025-05-29 00:39:27 CST, end at Sun 2025-06-01 11:52:48 CST. -- Jun 01 11:52:16 volumio systemd-timedated[1261]: Changed local time to Sun Jun 1 11:52:16 2025 Jun 01 11:52:16 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:16 volumio systemd[1]: Starting Daily man-db regeneration... Jun 01 11:52:16 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:16 volumio systemd[1]: Starting Daily apt download activities... Jun 01 11:52:16 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 11:52:16 volumio volumio[1099]: info: ___________ START PLUGINS ___________ Jun 01 11:52:16 volumio volumio[1099]: info: ControllerMpd::onStart: Initializing MPD Jun 01 11:52:16 volumio volumio[1099]: info: Creating MPD Configuration file Jun 01 11:52:16 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:16 volumio volumio-time-update[573]: volumio-time-update-util: System time updated successfully. Jun 01 11:52:16 volumio systemd[1]: Started Volumio Time Update Utility. Jun 01 11:52:16 volumio systemd[1]: Stopping Music Player Daemon... Jun 01 11:52:16 volumio ntpd[1122]: Soliciting pool server 162.159.200.1 Jun 01 11:52:16 volumio sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 11:52:16 volumio volumio[1099]: info: [1748749936121] CoreMusicLibrary::Adding element 媒体服务器 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 11:52:16 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:16 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:16 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:16 volumio volumio[1099]: Starting albumart workers Jun 01 11:52:16 volumio volumio[1099]: Starting albumart workers Jun 01 11:52:16 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:16 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:16 volumio systemd[1]: mpd.service: Succeeded. Jun 01 11:52:16 volumio systemd[1]: Stopped Music Player Daemon. Jun 01 11:52:16 volumio systemd[1]: Starting Music Player Daemon... Jun 01 11:52:16 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 11:52:16 volumio volumio[1099]: info: [1748749936348] CoreMusicLibrary::Adding element Last_100 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 01 11:52:16 volumio volumio[1099]: info: [1748749936351] CoreMusicLibrary::Adding element Webradio Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 11:52:16 volumio volumio[1099]: info: Initializing BBC Radios Jun 01 11:52:16 volumio volumio[1099]: Starting albumart workers Jun 01 11:52:16 volumio sudo[1322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: Volumio Calling Home Jun 01 11:52:16 volumio sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:16 volumio sudo[1322]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:16 volumio volumio[1099]: info: Setting Device type: Tinkerboard Jun 01 11:52:16 volumio volumio[1099]: info: VolumeController:: Volume=34 Mute =false Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:16 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::updateTrackBlock Jun 01 11:52:16 volumio volumio[1099]: info: CorePlayQueue::getTrackBlock Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioRetrievevolume Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::setRepeat null single undefined Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:16 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::setRandom null Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:16 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:16 volumio volumio[1099]: info: MPD Permissions set Jun 01 11:52:16 volumio volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 11:52:16 volumio systemd[1]: man-db.service: Succeeded. Jun 01 11:52:16 volumio systemd[1]: Started Daily man-db regeneration. Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:16 volumio volumio[1099]: info: Starting Shairport Sync Jun 01 11:52:16 volumio volumio[1099]: info: Starting Shairport Sync Jun 01 11:52:16 volumio volumio[1099]: info: Starting Shairport Sync Jun 01 11:52:16 volumio volumio[1099]: info: Discovery: A device disappeared from network Jun 01 11:52:16 volumio sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 11:52:16 volumio volumio[1099]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jun 01 11:52:16 volumio volumio[1099]: info: VolumeController:: Volume=34 Mute =false Jun 01 11:52:16 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:16 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 11:52:16 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:16 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:17 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:17 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:17 volumio sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 11:52:17 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 11:52:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 01 11:52:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:17 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:17 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:17 volumio volumio[1099]: info: Discovery: adding c173d053-3305-4e6e-8780-6a532f6389ec Jun 01 11:52:17 volumio volumio[1099]: info: Discovery: Found device Volumio Jun 01 11:52:17 volumio systemd[1]: apt-daily.service: Succeeded. Jun 01 11:52:17 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:17 volumio systemd[1]: Started Daily apt download activities. Jun 01 11:52:17 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:17 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 01 11:52:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 11:52:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 01 11:52:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 01 11:52:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:17 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:17 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:17 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:17 volumio volumio[1099]: info: Shairport-Sync Started Jun 01 11:52:17 volumio volumio[1099]: Error adding Membership: Error: addMembership EINVAL Jun 01 11:52:17 volumio volumio[1099]: info: Shairport-Sync Started Jun 01 11:52:17 volumio volumio[1099]: info: Shairport-Sync Started Jun 01 11:52:17 volumio volumio[1099]: info: Discovery: this is already registered, c173d053-3305-4e6e-8780-6a532f6389ec Jun 01 11:52:17 volumio volumio[1099]: info: Discovery: Found device Volumio Jun 01 11:52:17 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:17 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:17 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 01 11:52:17 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 01 11:52:17 volumio volumio[1099]: info: Volumio called home Jun 01 11:52:17 volumio mpd[1366]: Jun 01 11:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 11:52:17 volumio systemd[1]: Started Music Player Daemon. Jun 01 11:52:17 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:17 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:17 volumio volumio[1099]: info: Completed starting Core Plugins Jun 01 11:52:17 volumio volumio[1099]: info: ------------------------------------------- Jun 01 11:52:17 volumio volumio[1099]: info: ----- MyVolumio plugins startup ---- Jun 01 11:52:17 volumio volumio[1099]: info: ------------------------------------------- Jun 01 11:52:17 volumio volumio[1099]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 01 11:52:17 volumio volumio[1099]: error: MPD error: The expression evaluated to a falsy value: Jun 01 11:52:17 volumio volumio[1099]: assert.ok(self.idling) Jun 01 11:52:17 volumio volumio[1099]: error: The expression evaluated to a falsy value: Jun 01 11:52:17 volumio volumio[1099]: assert.ok(self.idling) Jun 01 11:52:17 volumio volumio[1099]: info: MPD running with PID1366 Jun 01 11:52:17 volumio volumio[1099]: ,establishing connection Jun 01 11:52:17 volumio volumio[1099]: error: updateQueue error: null Jun 01 11:52:17 volumio volumio[1099]: error: updateQueue error: null Jun 01 11:52:20 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:20 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 11:52:20 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Jun 01 11:52:20 volumio volumio[1099]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 11:52:21 volumio volumio[1099]: info: Discovery: Getting this device information Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:21 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 11:52:21 volumio volumio[1099]: verbose: New Socket.io Connection to 192.168.32.117:3000 from 192.168.32.36 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:21 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 01 11:52:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 01 11:52:24 volumio ntpd[1122]: receive: Unexpected origin timestamp 0xebe64cf7.1e1baca3 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebe64cf7.f688e90c Jun 01 11:52:24 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:24 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:25 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 11:52:25 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 11:52:25 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 01 11:52:25 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 01 11:52:25 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio volumio[1099]: info: Upmpdcli Daemon Started Jun 01 11:52:25 volumio volumio[1567]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 01 11:52:25 volumio sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 11:52:25 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 11:52:25 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio volumio[1099]: verbose: New Socket.io Connection to 192.168.32.117 from 192.168.32.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 11:52:25 volumio sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 11:52:25 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 11:52:25 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:25 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:25 volumio volumio[1099]: verbose: New Socket.io Connection to 192.168.32.117 from 192.168.32.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 11:52:25 volumio volumio[1099]: info: Received Get System Info Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 11:52:25 volumio volumio[1099]: info: Discovery: Getting this device information Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:25 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:25 volumio volumio[1099]: info: Listing playlists Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 11:52:25 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 11:52:26 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=draco,password=zhouyan,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.32.16/music /mnt/NAS/music Jun 01 11:52:26 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:27 volumio volumio[1567]: ......................................++++ Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 11:52:27 volumio volumio[1099]: info: Received Get System Info Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 11:52:27 volumio volumio[1099]: info: Discovery: Getting this device information Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPlay Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::play index undefined Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::startPlaybackTimer Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand stop took 1 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand clear took 3 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac" Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: error: updateQueue error: null Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 6ms Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand add "NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac" took 5 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 4ms Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 4ms Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Jun 01 11:52:27 volumio volumio[1099]: info: Ignoring MPD Status Update Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces state update: player Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 7ms Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand play took 5 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::getState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 5ms Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 4ms Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces state update: player Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::getState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Jun 01 11:52:27 volumio volumio[1099]: info: Jun 01 11:52:27 volumio volumio[1099]: ---------------------------- MPD announces state update: player Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::getState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand status took 10 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand status took 9 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand status took 9 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseState Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand clearerror took 13 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand clearerror took 12 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand clearerror took 11 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 11:52:27 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Jun 01 11:52:27 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你是我的SUPERMAN","artist":"李玟","album":"Sunny Day 好心情","uri":"NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac","trackType":"flac"} Jun 01 11:52:27 volumio volumio[1099]: verbose: CURRENT POSITION 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: No code Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你是我的SUPERMAN","artist":"李玟","album":"Sunny Day 好心情","uri":"NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac","trackType":"flac"} Jun 01 11:52:27 volumio volumio[1099]: verbose: CURRENT POSITION 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: No code Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: ControllerMpd::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你是我的SUPERMAN","artist":"李玟","album":"Sunny Day 好心情","uri":"NAS/music/李玟 - Sunny Day 好心情/01. 你是我的SUPERMAN.flac","trackType":"flac"} Jun 01 11:52:27 volumio volumio[1099]: verbose: CURRENT POSITION 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: No code Jun 01 11:52:27 volumio volumio[1099]: info: CoreStateMachine::pushState Jun 01 11:52:27 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:27 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 74ms Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 72ms Jun 01 11:52:27 volumio volumio[1099]: info: ------------------------------ 72ms Jun 01 11:52:27 volumio volumio[1567]: ............++++ Jun 01 11:52:27 volumio volumio[1567]: e is 65537 (0x010001) Jun 01 11:52:27 volumio volumio[1567]: writing RSA key Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin bluetooth to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin multiroom to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin metavolumio to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin cd_controller to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 01 11:52:27 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 01 11:52:28 volumio volumio[1099]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 01 11:52:28 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 01 11:52:28 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:28 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:28 volumio volumio[1099]: info: Starting MyVolumio Remote Streaming Endpoints Jun 01 11:52:28 volumio volumio[1099]: info: MyVolumio login type: Token Jun 01 11:52:28 volumio volumio[1099]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 01 11:52:28 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 01 11:52:29 volumio volumio[1099]: info: Starting Streaming Service Transparent Proxy Jun 01 11:52:29 volumio volumio[1099]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 01 11:52:29 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 01 11:52:29 volumio volumio[1099]: info: Streaming services startup Jun 01 11:52:29 volumio volumio[1099]: info: Starting Streaming Daemon Jun 01 11:52:29 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 01 11:52:29 volumio volumio[1099]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 01 11:52:29 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:29 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:29 volumio volumio[1099]: error: Cannot start Volumio Streaming Daemon Jun 01 11:52:29 volumio volumio[1099]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 01 11:52:29 volumio volumio[1099]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 01 11:52:29 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 01 11:52:29 volumio sh[498]: timed out Jun 01 11:52:29 volumio dhcpcd[546]: timed out Jun 01 11:52:29 volumio sh[498]: dhcpcd exited Jun 01 11:52:29 volumio dhcpcd[546]: dhcpcd exited Jun 01 11:52:29 volumio sh[498]: ifup: failed to bring up eth0 Jun 01 11:52:29 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:52:29 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 01 11:52:29 volumio dhcpcd[1691]: sending commands to master dhcpcd process Jun 01 11:52:29 volumio ifplugd(eth0)[823]: client: sending commands to master dhcpcd process Jun 01 11:52:29 volumio dhcpcd[1124]: control command: /sbin/dhcpcd eth0 Jun 01 11:52:30 volumio volumio[1099]: STREAMING PROXY: Starting server on port 3245 Jun 01 11:52:30 volumio volumio[1099]: Node JS runtime: 14 Jun 01 11:52:30 volumio ifplugd(eth0)[823]: Program executed successfully. Jun 01 11:52:30 volumio ifplugd(eth0)[823]: Link beat lost. Jun 01 11:52:30 volumio ifplugd[760]: Network Interface Plugging Daemon...start eth0...done. Jun 01 11:52:30 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 01 11:52:30 volumio systemd[1]: Reached target Multi-User System. Jun 01 11:52:30 volumio systemd[1]: Reached target Graphical Interface. Jun 01 11:52:30 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 01 11:52:30 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 01 11:52:30 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 01 11:52:30 volumio systemd[1]: Startup finished in 13.830s (kernel) + 32.065s (userspace) = 45.895s. Jun 01 11:52:30 volumio volumio[1099]: error: MyVolumio Custom Token format not valid, refreshing it Jun 01 11:52:31 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 01 11:52:32 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:32 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 11:52:32 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Jun 01 11:52:32 volumio volumio[1099]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Jun 01 11:52:35 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: info: Retrieving Cloud Streaming UI Jun 01 11:52:36 volumio volumio[1099]: info: Getting Tidal Cloud Configuration Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: info: Getting Qobuz Cloud Configuration Jun 01 11:52:36 volumio volumio[1099]: info: Asking plugin for UI Config Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: info: Getting Spotify Cloud Configuration Jun 01 11:52:36 volumio volumio[1099]: info: Asking plugin for UI Config Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: info: Saving Spotify Acccount Jun 01 11:52:36 volumio volumio[1099]: info: Got Tidal Cloud Configuration Jun 01 11:52:36 volumio volumio[1099]: info: Got it Jun 01 11:52:36 volumio volumio[1099]: info: Got it Jun 01 11:52:36 volumio volumio[1099]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 11:52:36 volumio volumio[1099]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 11:52:36 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 11:52:37 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=draco,password=zhouyan,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.32.16/music /mnt/NAS/music Jun 01 11:52:37 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:38 volumio volumio[1099]: info: MyVolumio login type: Token Jun 01 11:52:40 volumio volumio[1099]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 01 11:52:40 volumio ifplugd(eth0)[823]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jun 01 11:52:40 volumio dhcpcd[1773]: sending commands to master dhcpcd process Jun 01 11:52:40 volumio ifplugd(eth0)[823]: client: sending commands to master dhcpcd process Jun 01 11:52:40 volumio dhcpcd[1124]: control command: /sbin/dhcpcd -k eth0 Jun 01 11:52:40 volumio dhcpcd[1124]: eth0: removing interface Jun 01 11:52:40 volumio ifplugd(eth0)[823]: Program executed successfully. Jun 01 11:52:40 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 11:52:40 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 11:52:40 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 11:52:40 volumio volumio[1099]: info: Discovery: Getting this device information Jun 01 11:52:40 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:40 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:40 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 11:52:41 volumio volumio[1099]: info: MyVolumio token set successfully Jun 01 11:52:41 volumio volumio[1099]: info: MYVOLUMIO: Adding device Jun 01 11:52:41 volumio volumio[1099]: info: MYVOLUMIO: Evaluating Server Jun 01 11:52:41 volumio volumio[1099]: info: MyVolumio Plan changed: superstar Jun 01 11:52:41 volumio volumio[1099]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jun 01 11:52:41 volumio volumio[1099]: info: Adding audio output: Jun 01 11:52:41 volumio volumio[1099]: info: MYVOLUMIO: Adding device Jun 01 11:52:41 volumio volumio[1099]: info: MYVOLUMIO: Evaluating Server Jun 01 11:52:41 volumio volumio[1099]: info: Remote config written successfully Jun 01 11:52:41 volumio volumio[1099]: info: Starting Tunnel 1 Jun 01 11:52:41 volumio volumio[1099]: info: Starting Tunnel Connection Checker Jun 01 11:52:41 volumio volumio[1099]: info: Completed starting MyVolumio Plugin Jun 01 11:52:42 volumio volumio[1099]: info: MYVolumio Device enabled Jun 01 11:52:42 volumio volumio[1099]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 01 11:52:42 volumio volumio[1099]: info: MyVolumio status changed Jun 01 11:52:42 volumio volumio[1099]: info: Streaming services startup Jun 01 11:52:42 volumio volumio[1099]: info: Starting Streaming Daemon Jun 01 11:52:42 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 01 11:52:42 volumio volumio[1099]: error: [MyVolumio PluginManager] Cache data is invalid! Jun 01 11:52:42 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 01 11:52:42 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:42 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:42 volumio volumio[1099]: error: Cannot start Volumio Streaming Daemon Jun 01 11:52:42 volumio volumio[1099]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 01 11:52:42 volumio volumio[1099]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 01 11:52:42 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jun 01 11:52:43 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:43 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jun 01 11:52:43 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Jun 01 11:52:43 volumio volumio[1099]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds Jun 01 11:52:43 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 11:52:43 volumio volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 11:52:43 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:43 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:43 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 11:52:44 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 11:52:45 volumio volumio[1099]: info: Setting Geolocation for MyVolumio to us2 Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: Setting Geolocation for MyVolumio to us2 Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:45 volumio volumio[1099]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 01 11:52:45 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 01 11:52:45 volumio volumio[1099]: info: [1748749965378] Starting BluetoothController Jun 01 11:52:45 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 01 11:52:46 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 01 11:52:46 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio volumio[1099]: info: MRS: MultiRoom plugin initialized Jun 01 11:52:46 volumio volumio[1099]: info: MRS: STOPPING SNAPCLIENT Jun 01 11:52:46 volumio volumio[1099]: info: MRS: Snap server stop Jun 01 11:52:46 volumio volumio[1099]: info: MRS: STOPPING volumioStreaming Jun 01 11:52:46 volumio sudo[1888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 01 11:52:46 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 01 11:52:46 volumio sudo[1888]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 01 11:52:46 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 01 11:52:46 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 01 11:52:46 volumio sudo[1888]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 01 11:52:46 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 01 11:52:46 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 01 11:52:46 volumio volumio[1099]: info: Preparing to generate the ALSA configuration file Jun 01 11:52:46 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 01 11:52:46 volumio volumio[1099]: info: Updating MyVolumio device info Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: Updating MyVolumio device info Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 01 11:52:46 volumio volumio[1099]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 01 11:52:46 volumio volumio[1099]: info: Reading ALSA contributions from plugins. Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 11:52:46 volumio volumio[1099]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 11:52:46 volumio volumio[1099]: info: Received Get System Version Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 11:52:46 volumio volumio[1099]: info: Received Get System Info Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 11:52:46 volumio volumio[1099]: info: Discovery: Getting this device information Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:46 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 11:52:46 volumio volumio[1099]: info: MRS: Removed streaming files Jun 01 11:52:46 volumio volumio[1099]: info: MRS: volumioStreaming STOPPED Jun 01 11:52:46 volumio volumio[1099]: info: MRS: SNAPSERVER STOPPED Jun 01 11:52:46 volumio volumio[1099]: info: MRS: SNAPCLIENT STOPPED Jun 01 11:52:46 volumio volumio[1099]: info: Asound.conf file written Jun 01 11:52:46 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 01 11:52:46 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio volumio[1099]: info: Output device has changed, restarting MPD Jun 01 11:52:46 volumio volumio[1099]: info: Output device has changed, restarting Shairport Sync Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:46 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 01 11:52:46 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 01 11:52:46 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:46 volumio sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:46 volumio volumio[1099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 01 11:52:46 volumio volumio[1099]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:46 volumio systemd[1]: Stopping Music Player Daemon... Jun 01 11:52:46 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 01 11:52:46 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 01 11:52:47 volumio volumio[1099]: info: MRS: Pushing multiroomSync output for this device Jun 01 11:52:47 volumio volumio[1099]: info: MRS: Pushing multiroomSync output Jun 01 11:52:47 volumio volumio[1099]: info: Adding audio output: Jun 01 11:52:47 volumio volumio[1099]: info: Adding audio output: Jun 01 11:52:47 volumio sudo[1965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Jun 01 11:52:47 volumio volumio[1099]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio sudo[1965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:47 volumio systemd[1]: mpd.service: Succeeded. Jun 01 11:52:47 volumio systemd[1]: Stopped Music Player Daemon. Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 01 11:52:47 volumio systemd[1]: Starting Music Player Daemon... Jun 01 11:52:47 volumio volumio[1099]: info: Adding METAVOLUMIO REST API Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 01 11:52:47 volumio volumio[1099]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 01 11:52:47 volumio volumio[1099]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 01 11:52:47 volumio volumio[1099]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 01 11:52:47 volumio volumio[1099]: info: Preparing CD Folders Jun 01 11:52:47 volumio volumio[1099]: info: Adding CD REST API Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 01 11:52:47 volumio volumio[1099]: info: Starting UDEV Watcher for CD Jun 01 11:52:47 volumio volumio[1099]: info: Detecting CD presence with UDEV Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 01 11:52:47 volumio volumio[1099]: info: Adding inputs REST Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 01 11:52:47 volumio volumio[1099]: info: Scanning Audio Inputs Jun 01 11:52:47 volumio volumio[1099]: info: Checking against Known Cards name Jun 01 11:52:47 volumio volumio[1099]: info: Checking against Known Cards name Jun 01 11:52:47 volumio volumio[1099]: info: Checking against Known Cards name Jun 01 11:52:47 volumio volumio[1099]: info: Adding Server instance for streaming Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 01 11:52:47 volumio volumio[1099]: error: Hi Res Audio Failed Login: Missing Login Data Jun 01 11:52:47 volumio volumio[1099]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 01 11:52:47 volumio volumio[1099]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 01 11:52:47 volumio sudo[1973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 01 11:52:47 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:47 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:47 volumio volumio[1099]: info: Stopping AccessToken refresher cron Jun 01 11:52:47 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 11:52:47 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:47 volumio volumio[1099]: info: AccessToken refresher cron started Jun 01 11:52:47 volumio volumio[1099]: info: Stopping AccessToken refresher cron for QOBUZ Jun 01 11:52:47 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:47 volumio volumio[1099]: info: AccessToken refresher cron started for QOBUZ Jun 01 11:52:47 volumio volumio[1099]: info: Adding TIDAL REST API Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 01 11:52:47 volumio volumio[1099]: info: Adding QOBUZ REST API Endpoints Jun 01 11:52:47 volumio volumio[1099]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 11:52:47 volumio volumio[1099]: info: MPD Permissions set Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 01 11:52:47 volumio volumio[1099]: info: Starting Shairport Sync Jun 01 11:52:47 volumio sudo[1965]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:47 volumio volumio[1099]: info: Bluetooth name changed to Volumio Jun 01 11:52:47 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 11:52:47 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:47 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 01 11:52:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 11:52:47 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 01 11:52:47 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 11:52:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 11:52:47 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:47 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 01 11:52:47 volumio volumio[1099]: info: Shairport-Sync Started Jun 01 11:52:47 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:47 volumio volumio[1099]: info: Remote SSH Started Jun 01 11:52:47 volumio autossh[2032]: port set to 0, monitoring disabled Jun 01 11:52:47 volumio autossh[2032]: starting ssh (count 1) Jun 01 11:52:47 volumio autossh[2032]: ssh child pid is 2039 Jun 01 11:52:47 volumio volumio-remote-updater[589]: No test mode Jun 01 11:52:47 volumio volumio-remote-updater[589]: No alpha test mode Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 01 11:52:47 volumio volumio[1099]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 01 11:52:47 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Jun 01 11:52:47 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Jun 01 11:52:47 volumio volumio[1099]: info: Successfully Added MyVolumio device Jun 01 11:52:47 volumio volumio[1099]: info: Successfully Added MyVolumio device Jun 01 11:52:48 volumio mpd[1998]: Jun 01 11:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 01 11:52:48 volumio systemd[1]: Started Music Player Daemon. Jun 01 11:52:48 volumio sudo[1945]: pam_unix(sudo:session): session closed for user root Jun 01 11:52:48 volumio volumio[1099]: error: updateQueue error: null Jun 01 11:52:48 volumio volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:52:48 volumio volumio[1099]: TypeError: Cannot read property 'replace' of undefined Jun 01 11:52:48 volumio volumio[1099]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jun 01 11:52:48 volumio volumio[1099]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jun 01 11:52:48 volumio volumio[1099]: at listOnTimeout (internal/timers.js:557:17) Jun 01 11:52:48 volumio volumio[1099]: at processTimers (internal/timers.js:500:7) Jun 01 11:52:48 volumio volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:52:48 volumio sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-01 11:51 Jun 01 11:52:48 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"