-- Logs begin at Fri 2024-08-23 05:30:55 UTC, end at Fri 2024-08-23 05:33:50 UTC. -- Aug 23 05:32:00 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:00 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:01 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:01 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:02 volumio sudo[1415]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:02 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:02 volumio volumio[1142]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 23 05:32:02 volumio sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 05:32:02 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:02 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:02 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 05:32:02 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:02 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:02 volumio volumio[1142]: info: VolumeController:: Volume=41 Mute =false Aug 23 05:32:02 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:32:02 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:02 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 05:32:02 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:32:02 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Aug 23 05:32:02 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Aug 23 05:32:02 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 05:32:02 volumio volumio[1142]: info: Asound.conf file unchanged, so no further update is needed Aug 23 05:32:02 volumio volumio[1142]: info: Output device has changed, restarting MPD Aug 23 05:32:02 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 05:32:02 volumio volumio[1142]: info: Output device has changed, restarting Shairport Sync Aug 23 05:32:02 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:02 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:02 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 05:32:03 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 05:32:03 volumio volumio[1142]: info: ___________ START PLUGINS ___________ Aug 23 05:32:03 volumio systemd[1]: Listening on mpd.socket. Aug 23 05:32:03 volumio systemd[1]: Starting Music Player Daemon... Aug 23 05:32:03 volumio volumio[1142]: info: ControllerMpd::onStart: Initializing MPD Aug 23 05:32:03 volumio volumio[1142]: info: Creating MPD Configuration file Aug 23 05:32:03 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 05:32:03 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio sudo[1582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 05:32:03 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:32:03 volumio sudo[1582]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 23 05:32:03 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 05:32:03 volumio volumio[1142]: info: [1724391123100] CoreMusicLibrary::Adding element Media Servers Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 05:32:03 volumio sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 05:32:03 volumio sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 23 05:32:03 volumio systemd[1]: mpd.service: Succeeded. Aug 23 05:32:03 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 05:32:03 volumio systemd[1]: Starting Music Player Daemon... Aug 23 05:32:03 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 05:32:03 volumio volumio[1142]: info: [1724391123166] CoreMusicLibrary::Adding element Last_100 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 05:32:03 volumio volumio[1142]: info: [1724391123167] CoreMusicLibrary::Adding element Webradio Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:32:03 volumio volumio[1142]: info: Initializing BBC Radios Aug 23 05:32:03 volumio sudo[1596]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 05:32:03 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio sudo[1596]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 23 05:32:03 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: Volumio Calling Home Aug 23 05:32:03 volumio volumio[1142]: info: CoreStateMachine::setRepeat null single undefined Aug 23 05:32:03 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:32:03 volumio volumio[1142]: info: CoreStateMachine::setRandom null Aug 23 05:32:03 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:32:03 volumio volumio[1142]: info: MPD Permissions set Aug 23 05:32:03 volumio volumio[1142]: info: MPD Permissions set Aug 23 05:32:03 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 23 05:32:03 volumio volumio[1142]: info: VolumeController:: Volume=41 Mute =false Aug 23 05:32:03 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:32:03 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 23 05:32:03 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 23 05:32:03 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 23 05:32:03 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:03 volumio volumio[1142]: info: Discovery: adding bc36fdf8-de94-4e51-9b71-f4413a64f8eb Aug 23 05:32:03 volumio volumio[1142]: info: Discovery: Found device Volumio Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio volumio[1142]: info: Discovery: this is already registered, bc36fdf8-de94-4e51-9b71-f4413a64f8eb Aug 23 05:32:03 volumio volumio[1142]: info: Discovery: Found device Volumio Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio volumio[1142]: info: Volumio called home Aug 23 05:32:03 volumio volumio[1142]: info: Upmpdcli Daemon Started Aug 23 05:32:03 volumio volumio[1142]: info: Starting Shairport Sync Aug 23 05:32:03 volumio volumio[1142]: info: Starting Shairport Sync Aug 23 05:32:03 volumio volumio[1142]: info: Starting Shairport Sync Aug 23 05:32:03 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 05:32:03 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 05:32:03 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 05:32:03 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 05:32:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 05:32:03 volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 05:32:03 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:03 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:03 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 05:32:03 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 05:32:03 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 23 05:32:03 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 05:32:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 05:32:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 05:32:03 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:03 volumio volumio[1142]: info: Shairport-Sync Started Aug 23 05:32:03 volumio volumio[1142]: Error adding Membership: Error: addMembership EINVAL Aug 23 05:32:03 volumio volumio[1142]: info: Shairport-Sync Started Aug 23 05:32:03 volumio volumio[1142]: info: Shairport-Sync Started Aug 23 05:32:04 volumio mpd[1603]: Aug 23 05:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 05:32:04 volumio systemd[1]: Started Music Player Daemon. Aug 23 05:32:04 volumio sudo[1589]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:04 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:04 volumio volumio[1142]: info: Completed starting Core Plugins Aug 23 05:32:04 volumio volumio[1142]: info: ------------------------------------------- Aug 23 05:32:04 volumio volumio[1142]: info: ----- MyVolumio plugins startup ---- Aug 23 05:32:04 volumio volumio[1142]: info: ------------------------------------------- Aug 23 05:32:04 volumio volumio[1142]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 23 05:32:04 volumio volumio[1142]: error: MPD error: The expression evaluated to a falsy value: Aug 23 05:32:04 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:32:04 volumio volumio[1142]: error: The expression evaluated to a falsy value: Aug 23 05:32:04 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:32:04 volumio volumio[1142]: info: MPD running with PID1603 Aug 23 05:32:04 volumio volumio[1142]: ,establishing connection Aug 23 05:32:04 volumio volumio[1142]: error: MPD error: The expression evaluated to a falsy value: Aug 23 05:32:04 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:32:04 volumio volumio[1142]: error: The expression evaluated to a falsy value: Aug 23 05:32:04 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:32:04 volumio volumio[1142]: error: updateQueue error: null Aug 23 05:32:04 volumio volumio[1621]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 23 05:32:04 volumio volumio[1621]: ...........++++ Aug 23 05:32:04 volumio volumio[1142]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 23 05:32:05 volumio volumio[1621]: ....................++++ Aug 23 05:32:05 volumio volumio[1621]: e is 65537 (0x010001) Aug 23 05:32:05 volumio volumio[1621]: writing RSA key Aug 23 05:32:05 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 05:32:05 volumio volumio[1142]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 23 05:32:07 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 05:32:08 volumio volumio[1142]: info: Received Get System Info Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:32:08 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:08 volumio volumio[1142]: info: Listing playlists Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 05:32:08 volumio volumio[1142]: info: Received Get System Info Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:32:08 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:08 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:08 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:32:09 volumio volumio-remote-updater[751]: [2024-08-23 05:32:09] [connect] Successful connection Aug 23 05:32:09 volumio volumio-remote-updater[751]: [2024-08-23 05:32:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724391129 101 Aug 23 05:32:09 volumio volumio[1142]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Aug 23 05:32:09 volumio volumio[1142]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 23 05:32:10 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin bluetooth to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin multiroom to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin metavolumio to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin cd_controller to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 23 05:32:12 volumio volumio[1142]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 23 05:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:13 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:13 volumio volumio[1142]: info: Starting MyVolumio Remote Streaming Endpoints Aug 23 05:32:13 volumio volumio[1142]: info: MyVolumio not started Aug 23 05:32:13 volumio volumio[1142]: info: Initializing device activation check Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 23 05:32:13 volumio volumio[1142]: info: Starting Streaming Service Transparent Proxy Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 23 05:32:13 volumio volumio[1142]: info: Streaming services startup Aug 23 05:32:13 volumio volumio[1142]: info: Starting Streaming Daemon Aug 23 05:32:13 volumio sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 23 05:32:13 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:13 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:13 volumio volumio[1142]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 23 05:32:13 volumio volumio[1142]: error: Cannot start Volumio Streaming Daemon Aug 23 05:32:13 volumio volumio[1142]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 23 05:32:13 volumio volumio[1142]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 23 05:32:14 volumio volumio[1142]: STREAMING PROXY: Starting server on port 3245 Aug 23 05:32:14 volumio volumio[1142]: Node JS runtime: 14 Aug 23 05:32:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 23 05:32:18 volumio kernel: b43-phy0 ERROR: MAC suspend failed Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 05:32:24 volumio volumio[1142]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 23 05:32:24 volumio volumio[1142]: info: Received Get System Version Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:32:24 volumio volumio[1142]: info: Received Get System Info Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:32:24 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:24 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:24 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:32:24 volumio ntpd[1123]: Soliciting pool server 158.69.20.38 Aug 23 05:32:25 volumio ntpd[1123]: Soliciting pool server 216.232.132.95 Aug 23 05:32:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 05:32:28 volumio volumio[1142]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 23 05:32:28 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 23 05:32:33 volumio volumio-remote-updater[751]: No test mode Aug 23 05:32:33 volumio volumio-remote-updater[751]: No alpha test mode Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:32:33 volumio volumio[1142]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 23 05:32:33 volumio volumio[1142]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 23 05:32:33 volumio volumio[1142]: info: Completed starting MyVolumio Plugin Aug 23 05:32:33 volumio volumio[1142]: info: BOOT COMPLETED Aug 23 05:32:33 volumio volumio[1142]: [Metrics] CommandRouter: 76s 793.86ms Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::volumiosetStartupVolume Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent Aug 23 05:32:33 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent Aug 23 05:32:34 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 23 05:32:34 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 05:32:34 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 23 05:32:56 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 05:32:56 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:56 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:56 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 05:32:56 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:56 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:57 volumio sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 05:32:57 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:57 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:57 volumio sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 05:32:57 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:32:57 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root Aug 23 05:32:57 volumio volumio[1142]: verbose: New Socket.io Connection to 10.0.0.52 from 10.0.0.164 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:57 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 05:32:57 volumio volumio[1142]: info: Received Get System Info Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:32:57 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:57 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:57 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:57 volumio volumio[1142]: info: Listing playlists Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 05:32:57 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 05:32:59 volumio volumio[1142]: info: Received Get System Info Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:32:59 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:32:59 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:32:59 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 05:33:04 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 05:33:07 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 23 05:33:12 volumio volumio[1142]: info: CoreCommandRouter::Close All Modals sent Aug 23 05:33:12 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 05:33:15 volumio volumio[1142]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 23 05:33:15 volumio volumio[1142]: info: Received Get System Version Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 05:33:15 volumio volumio[1142]: info: Received Get System Info Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 05:33:15 volumio volumio[1142]: info: Discovery: Getting this device information Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:33:15 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:33:15 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 05:33:28 volumio volumio[1142]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 23 05:33:28 volumio volumio[1142]: info: Preparing to save Alsa Options, stopping services first Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::volumioGetState Aug 23 05:33:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPause Aug 23 05:33:28 volumio volumio[1142]: info: CoreStateMachine::pause Aug 23 05:33:28 volumio volumio[1142]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 05:33:28 volumio volumio[1142]: info: Setting mixer PCM for card Combo384 Amanero Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 23 05:33:28 volumio volumio[1142]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 23 05:33:28 volumio volumio[1142]: info: Disabling external Volume Control Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:28 volumio volumio[1142]: info: Preparing to generate the ALSA configuration file Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 05:33:28 volumio volumio[1142]: info: VolumeController:: Volume=0 Mute =false Aug 23 05:33:28 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:33:28 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:33:28 volumio volumio[1142]: info: Asound.conf file written Aug 23 05:33:28 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 23 05:33:28 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:28 volumio volumio[1142]: info: Output device has changed, restarting MPD Aug 23 05:33:28 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 05:33:28 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:28 volumio volumio[1142]: info: Output device has changed, restarting Shairport Sync Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:28 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 05:33:28 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio systemd[1]: Stopping Music Player Daemon... Aug 23 05:33:28 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 05:33:28 volumio volumio[1142]: info: Output device has changed, restarting MPD Aug 23 05:33:28 volumio volumio[1142]: info: Output device has changed, restarting Shairport Sync Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:28 volumio systemd[1]: mpd.service: Succeeded. Aug 23 05:33:28 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 05:33:28 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 23 05:33:28 volumio sudo[2082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 23 05:33:28 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:28 volumio systemd[1]: Starting Music Player Daemon... Aug 23 05:33:28 volumio volumio[1142]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 05:33:28 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 23 05:33:28 volumio systemd[1]: mpd.service: Succeeded. Aug 23 05:33:28 volumio systemd[1]: Stopped Music Player Daemon. Aug 23 05:33:28 volumio volumio[1142]: info: MPD Permissions set Aug 23 05:33:28 volumio volumio[1142]: info: MPD Permissions set Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio systemd[1]: Starting Music Player Daemon... Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio sudo[2089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 23 05:33:28 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:28 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 23 05:33:28 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 05:33:28 volumio volumio[1142]: info: Starting Shairport Sync Aug 23 05:33:28 volumio volumio[1142]: info: Starting Shairport Sync Aug 23 05:33:29 volumio sudo[2105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 05:33:29 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:29 volumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 23 05:33:29 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 05:33:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 05:33:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 05:33:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 05:33:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 05:33:29 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:29 volumio volumio[1142]: info: Shairport-Sync Started Aug 23 05:33:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 23 05:33:29 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 23 05:33:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 23 05:33:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 23 05:33:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 23 05:33:29 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:29 volumio volumio[1142]: info: Shairport-Sync Started Aug 23 05:33:29 volumio mpd[2101]: Aug 23 05:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 23 05:33:29 volumio systemd[1]: Started Music Player Daemon. Aug 23 05:33:29 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:29 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Aug 23 05:33:29 volumio volumio[1142]: error: MPD error: The expression evaluated to a falsy value: Aug 23 05:33:29 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:33:29 volumio volumio[1142]: error: The expression evaluated to a falsy value: Aug 23 05:33:29 volumio volumio[1142]: assert.ok(self.idling) Aug 23 05:33:29 volumio volumio[1142]: error: updateQueue error: null Aug 23 05:33:32 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 23 05:33:32 volumio volumio[1142]: info: CoreStateMachine::getcurrentVolume Aug 23 05:33:32 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 05:33:32 volumio volumio[1142]: info: VolumeController:: Volume=0 Mute =false Aug 23 05:33:32 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:33:32 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:33:32 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:33:32 volumio volumio[1142]: info: CoreStateMachine::updateTrackBlock Aug 23 05:33:32 volumio volumio[1142]: info: CorePlayQueue::getTrackBlock Aug 23 05:33:32 volumio volumio[1142]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 05:33:32 volumio volumio[1142]: info: VolumeController:: Volume=0 Mute =false Aug 23 05:33:32 volumio volumio[1142]: info: CoreStateMachine::pushState Aug 23 05:33:32 volumio volumio[1142]: info: CorePlayQueue::getTrack 0 Aug 23 05:33:32 volumio volumio[1142]: info: CoreCommandRouter::volumioPushState Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: info: Retrieving Cloud Streaming UI Aug 23 05:33:40 volumio volumio[1142]: info: Getting Tidal Cloud Configuration Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: info: Getting Qobuz Cloud Configuration Aug 23 05:33:40 volumio volumio[1142]: info: Asking plugin for UI Config Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: info: Getting Spotify Cloud Configuration Aug 23 05:33:40 volumio volumio[1142]: info: Asking plugin for UI Config Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: info: Saving Spotify Acccount Aug 23 05:33:40 volumio volumio[1142]: info: Got Tidal Cloud Configuration Aug 23 05:33:40 volumio volumio[1142]: info: Got it Aug 23 05:33:40 volumio volumio[1142]: info: Got it Aug 23 05:33:40 volumio volumio[1142]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 05:33:40 volumio volumio[1142]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::volumioGetBrowseSources Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 23 05:33:40 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 23 05:33:44 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 23 05:33:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 23 05:33:45 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 23 05:33:48 volumio volumio[1142]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 23 05:33:50 volumio volumio[1142]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 05:33:50 volumio volumio[1142]: Error: dns service error: unknown Aug 23 05:33:50 volumio volumio[1142]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 23 05:33:50 volumio volumio[1142]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 23 05:33:50 volumio volumio[1142]: errorCode: -65537 Aug 23 05:33:50 volumio volumio[1142]: } Aug 23 05:33:50 volumio volumio[1142]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 05:33:50 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 05:32 Aug 23 05:33:50 volumio sudo[2134]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"