-- Logs begin at Tue 2025-06-03 23:18:40 CDT, end at Thu 2025-06-05 17:52:33 CDT. -- Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** For more information see Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 17:51:00 volumiopi volumio[24349]: *** WARNING *** For more information see Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** For more information see Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 05 17:51:00 volumiopi node[24349]: *** WARNING *** For more information see Jun 05 17:51:00 volumiopi volumio[24349]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 05 17:51:00 volumiopi volumio[24349]: info: Discovery: Started advertising with name: VolumioPi Jun 05 17:51:00 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 05 17:51:00 volumiopi volumio[24349]: info: Loading plugin "squeezelite_mc"... Jun 05 17:51:00 volumiopi volumio-remote-updater[612]: [2025-06-05 17:51:00] [connect] Successful connection Jun 05 17:51:00 volumiopi volumio[24349]: info: Loading plugin "outputs"... Jun 05 17:51:00 volumiopi volumio[24349]: info: Loading plugin "albumart"... Jun 05 17:51:00 volumiopi volumio[24349]: info: Plugin example_plugin is not enabled Jun 05 17:51:00 volumiopi volumio[24349]: info: Loading plugin "inputs"... Jun 05 17:51:00 volumiopi volumio[24349]: info: Loading plugin "updater_comm"... Jun 05 17:51:01 volumiopi volumio[24349]: info: Plugin mpdemulation is not enabled Jun 05 17:51:01 volumiopi volumio[24349]: info: Loading plugin "rest_api"... Jun 05 17:51:01 volumiopi volumio[24349]: info: Loading plugin "websocket"... Jun 05 17:51:01 volumiopi volumio[24349]: info: Starting Socket.io Server version 2.3.0 Jun 05 17:51:01 volumiopi volumio[24349]: info: Loading i18n strings for locale en Jun 05 17:51:01 volumiopi volumio[24349]: Updating browse sources language Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 17:51:01 volumiopi volumio[24349]: Forking 3 albumart workers Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::initPlayerControls Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 17:51:01 volumiopi volumio[24349]: Express server listening on port 3000 Jun 05 17:51:01 volumiopi volumio[24349]: [Metrics] WebUI: 5s 10.12ms Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::resetVolumioState Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::getcurrentVolume Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:01 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 2 Jun 05 17:51:01 volumiopi volumio[24349]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 05 17:51:01 volumiopi volumio-remote-updater[612]: [2025-06-05 17:51:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749163860 101 Jun 05 17:51:01 volumiopi volumio[24349]: 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: 1 Jun 05 17:51:01 volumiopi volumio[24349]: info: VolumeController:: Volume=undefined Mute =false Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::updateTrackBlock Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrackBlock Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:01 volumiopi volumio[24349]: info: Reloading queue from file Jun 05 17:51:01 volumiopi volumio[24349]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 05 17:51:01 volumiopi volumio[24349]: info: Setting Device type: Raspberry PI Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::setRepeat null single undefined Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::setRandom null Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:01 volumiopi volumio[24349]: info: VolumeController:: Volume=undefined Mute =false Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:01 volumiopi volumio[24349]: info: Completed loading Core Plugins Jun 05 17:51:01 volumiopi volumio[24349]: info: Preparing to generate the ALSA configuration file Jun 05 17:51:02 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:02 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:02 volumiopi volumio[24349]: info: Asound.conf file unchanged, so no further update is needed Jun 05 17:51:02 volumiopi volumio[24349]: info: Output device has changed, restarting MPD Jun 05 17:51:02 volumiopi volumio[24349]: info: Output device has changed, restarting Shairport Sync Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:02 volumiopi sudo[24514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 17:51:02 volumiopi sudo[24514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:02 volumiopi sudo[24516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 17:51:02 volumiopi sudo[24516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:02 volumiopi sudo[24514]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:02 volumiopi volumio[24349]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 17:51:02 volumiopi volumio[24349]: info: ___________ START PLUGINS ___________ Jun 05 17:51:02 volumiopi systemd[1]: Stopping Music Player Daemon... Jun 05 17:51:02 volumiopi volumio[24349]: info: ControllerMpd::onStart: Initializing MPD Jun 05 17:51:02 volumiopi volumio[24349]: info: Creating MPD Configuration file Jun 05 17:51:02 volumiopi systemd[1]: mpd.service: Succeeded. Jun 05 17:51:02 volumiopi systemd[1]: Stopped Music Player Daemon. Jun 05 17:51:02 volumiopi sudo[24523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 17:51:02 volumiopi sudo[24523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:02 volumiopi systemd[1]: Starting Music Player Daemon... Jun 05 17:51:02 volumiopi sudo[24523]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:02 volumiopi sudo[24526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 17:51:02 volumiopi sudo[24526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:02 volumiopi volumio[24349]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 17:51:02 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 05 17:51:02 volumiopi systemd[1]: mpd.service: Succeeded. Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:02 volumiopi systemd[1]: Stopped Music Player Daemon. Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:02 volumiopi systemd[1]: Starting Music Player Daemon... Jun 05 17:51:02 volumiopi volumio[24349]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 17:51:02 volumiopi volumio[24349]: info: [1749163862424] CoreMusicLibrary::Adding element Last_100 Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 05 17:51:02 volumiopi volumio[24349]: info: [1749163862433] CoreMusicLibrary::Adding element Webradio Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 17:51:02 volumiopi volumio[24349]: info: Initializing BBC Radios Jun 05 17:51:02 volumiopi sudo[24532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 17:51:02 volumiopi sudo[24532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:02 volumiopi sudo[24532]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:02 volumiopi volumio[24349]: Starting albumart workers Jun 05 17:51:02 volumiopi volumio[24349]: Starting albumart workers Jun 05 17:51:02 volumiopi volumio[24349]: info: [squeezelite_mc] Starting proxy server... Jun 05 17:51:02 volumiopi volumio[24349]: info: Volumio Calling Home Jun 05 17:51:02 volumiopi volumio[24349]: Starting albumart workers Jun 05 17:51:02 volumiopi volumio[24349]: info: [squeezelite_mc] Proxy server started on port 33163 Jun 05 17:51:02 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 17:51:03 volumiopi volumio[24349]: info: Discovery: adding 4e0bb5a3-b151-4982-8745-04d89a9b395e Jun 05 17:51:03 volumiopi volumio[24349]: info: Discovery: Found device VolumioPi Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:03 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:03 volumiopi volumio[24349]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 05 17:51:03 volumiopi volumio[24349]: info: MPD Permissions set Jun 05 17:51:03 volumiopi volumio[24349]: info: MPD Permissions set Jun 05 17:51:03 volumiopi volumio[24349]: info: VolumeController:: Volume=undefined Mute =false Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:03 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Server discovery started Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Player finder started Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreStateMachine::getcurrentVolume Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 17:51:03 volumiopi volumio[24349]: 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 05 17:51:03 volumiopi volumio[24349]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:03 volumiopi volumio[24349]: warn: [squeezelite_mc] No supported ALSA formats found for card 5 Jun 05 17:51:03 volumiopi volumio[24349]: warn: [squeezelite_mc] No ALSA formats returned for card 5 Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumiopi","dsdFormat":null,"card":"5","mixerType":"undefined","mixer":""} Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 05 17:51:03 volumiopi volumio[24349]: info: VolumeController:: Volume=undefined Mute =false Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:03 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreStateMachine::updateTrackBlock Jun 05 17:51:03 volumiopi volumio[24349]: info: CorePlayQueue::getTrackBlock Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 17:51:03 volumiopi volumio[24349]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 05 17:51:03 volumiopi volumio[24349]: info: Starting Shairport Sync Jun 05 17:51:03 volumiopi volumio[24349]: info: Starting Shairport Sync Jun 05 17:51:03 volumiopi volumio[24349]: info: Starting Shairport Sync Jun 05 17:51:03 volumiopi sudo[24575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 05 17:51:03 volumiopi sudo[24575]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:03 volumiopi sudo[24575]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:03 volumiopi sudo[24593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 17:51:03 volumiopi sudo[24598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 17:51:03 volumiopi sudo[24598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:03 volumiopi sudo[24593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:03 volumiopi sudo[24587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 17:51:03 volumiopi sudo[24587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:03 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:03 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:03 volumiopi volumio[24349]: info: Volumio called home Jun 05 17:51:03 volumiopi volumio[24349]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jun 05 17:51:03 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 05 17:51:03 volumiopi systemd[1]: shairport-sync.service: Succeeded. Jun 05 17:51:03 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 05 17:51:04 volumiopi volumio[24349]: info: VolumeController:: Volume=undefined Mute =false Jun 05 17:51:04 volumiopi volumio[24349]: info: CoreStateMachine::pushState Jun 05 17:51:04 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:04 volumiopi volumio[24349]: info: CoreCommandRouter::volumioPushState Jun 05 17:51:04 volumiopi sudo[24607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jun 05 17:51:04 volumiopi sudo[24607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:04 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 17:51:04 volumiopi sudo[24587]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:04 volumiopi sudo[24593]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:04 volumiopi sudo[24607]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:04 volumiopi sudo[24598]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:04 volumiopi volumio[24349]: info: Shairport-Sync Started Jun 05 17:51:04 volumiopi volumio[24349]: Error adding Membership: Error: addMembership EINVAL Jun 05 17:51:04 volumiopi volumio[24349]: info: Shairport-Sync Started Jun 05 17:51:04 volumiopi volumio[24349]: info: Shairport-Sync Started Jun 05 17:51:04 volumiopi volumio[24349]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 05 17:51:04 volumiopi sudo[24614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 05 17:51:04 volumiopi sudo[24614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:04 volumiopi sudo[24614]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:04 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jun 05 17:51:04 volumiopi volumio[24349]: info: Cannot mount NAS DSF at system boot, trial number 2 ,retrying in 5 seconds Jun 05 17:51:04 volumiopi volumio[24349]: info: Cannot mount NAS 24bit_88khz_+ at system boot, trial number 2 ,retrying in 5 seconds Jun 05 17:51:04 volumiopi volumio[24349]: info: Cannot mount NAS SACD at system boot, trial number 2 ,retrying in 5 seconds Jun 05 17:51:04 volumiopi sudo[24617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 05 17:51:04 volumiopi sudo[24617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:04 volumiopi systemd[1]: Reloading. Jun 05 17:51:04 volumiopi systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 05 17:51:04 volumiopi mpd[24544]: Jun 05 17:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 05 17:51:04 volumiopi systemd[1]: squeezelite.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 05 17:51:05 volumiopi systemd[1]: Started Music Player Daemon. Jun 05 17:51:05 volumiopi sudo[24617]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:05 volumiopi sudo[24516]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:05 volumiopi sudo[24526]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:05 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 05 17:51:05 volumiopi sudo[24637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 05 17:51:05 volumiopi sudo[24637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:05 volumiopi sudo[24637]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:05 volumiopi volumio[24349]: error: MPD error: The expression evaluated to a falsy value: Jun 05 17:51:05 volumiopi volumio[24349]: assert.ok(self.idling) Jun 05 17:51:05 volumiopi volumio[24349]: error: The expression evaluated to a falsy value: Jun 05 17:51:05 volumiopi volumio[24349]: assert.ok(self.idling) Jun 05 17:51:05 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Jun 05 17:51:05 volumiopi volumio[24349]: info: MPD running with PID24544 Jun 05 17:51:05 volumiopi volumio[24349]: ,establishing connection Jun 05 17:51:05 volumiopi volumio[24349]: error: updateQueue error: null Jun 05 17:51:05 volumiopi volumio[24349]: error: updateQueue error: null Jun 05 17:51:05 volumiopi sudo[24642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Jun 05 17:51:05 volumiopi sudo[24642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:05 volumiopi systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:05 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:05 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:05 volumiopi sudo[24642]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:06 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 05 17:51:06 volumiopi sudo[24645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 05 17:51:06 volumiopi sudo[24645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:06 volumiopi sudo[24645]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:06 volumiopi volumio[24349]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Jun 05 17:51:06 volumiopi sudo[24649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Jun 05 17:51:06 volumiopi sudo[24649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:06 volumiopi sudo[24649]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:06 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jun 05 17:51:06 volumiopi sudo[24652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jun 05 17:51:06 volumiopi sudo[24652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:06 volumiopi systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:06 volumiopi systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:06 volumiopi sudo[24652]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. Jun 05 17:51:06 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:06 volumiopi systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:06 volumiopi systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:06 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 05 17:51:06 volumiopi sudo[24659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 05 17:51:06 volumiopi sudo[24659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 17:51:06 volumiopi systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 2. Jun 05 17:51:06 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:06 volumiopi systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:07 volumiopi systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi sudo[24659]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 3. Jun 05 17:51:07 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:07 volumiopi systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 4. Jun 05 17:51:07 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 05 17:51:07 volumiopi systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 05 17:51:07 volumiopi sudo[24668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Succeeded. Jun 05 17:51:07 volumiopi sudo[24668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:07 volumiopi sudo[24668]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 5. Jun 05 17:51:07 volumiopi systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Start request repeated too quickly. Jun 05 17:51:07 volumiopi systemd[1]: squeezelite.service: Failed with result 'start-limit-hit'. Jun 05 17:51:07 volumiopi systemd[1]: Failed to start Squeezelite lightweight headless Squeezebox emulator. Jun 05 17:51:08 volumiopi volumio[24349]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 05 17:51:08 volumiopi sudo[24671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 05 17:51:08 volumiopi sudo[24671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:08 volumiopi sudo[24671]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:08 volumiopi volumio[24349]: info: Completed starting Core Plugins Jun 05 17:51:08 volumiopi volumio[24349]: info: ------------------------------------------- Jun 05 17:51:08 volumiopi volumio[24349]: info: ----- MyVolumio plugins startup ---- Jun 05 17:51:08 volumiopi volumio[24349]: info: ------------------------------------------- Jun 05 17:51:08 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 05 17:51:09 volumiopi volumio[24349]: info: Cannot mount NAS DSF at system boot, trial number 3 ,retrying in 5 seconds Jun 05 17:51:09 volumiopi volumio[24349]: info: Cannot mount NAS 24bit_88khz_+ at system boot, trial number 3 ,retrying in 5 seconds Jun 05 17:51:09 volumiopi volumio[24349]: info: Cannot mount NAS SACD at system boot, trial number 3 ,retrying in 5 seconds Jun 05 17:51:10 volumiopi volumio[24349]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 05 17:51:11 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:11 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:14 volumiopi volumio[24349]: info: Cannot mount NAS DSF at system boot, trial number 4 ,retrying in 5 seconds Jun 05 17:51:14 volumiopi volumio[24349]: info: Cannot mount NAS 24bit_88khz_+ at system boot, trial number 4 ,retrying in 5 seconds Jun 05 17:51:14 volumiopi volumio[24349]: info: Cannot mount NAS SACD at system boot, trial number 4 ,retrying in 5 seconds Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:9f:c2:34:23:e9 reason=6 Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: Trying to associate with SSID 'SYNACK' Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA Jun 05 17:51:14 volumiopi avahi-daemon[604]: Withdrawing address record for 172.16.1.172 on wlan0. Jun 05 17:51:14 volumiopi avahi-daemon[604]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:51:14 volumiopi avahi-daemon[604]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: deleting route to 172.16.1.0/24 Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: deleting default route via 172.16.1.254 Jun 05 17:51:14 volumiopi volumio[24349]: info: Discovery: A device disappeared from network Jun 05 17:51:14 volumiopi volumio[24349]: info: Discovery: Device volumiopi disappeared from network Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: Associated with fa:9f:c2:34:23:e9 Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:9f:c2:34:23:e9 completed [id=0 id_str=] Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 05 17:51:14 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:51:14 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:51:15 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:51:15 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:51:15 volumiopi dhcpcd[894]: wlan0: soliciting an IPv6 router Jun 05 17:51:15 volumiopi dhcpcd[894]: wlan0: rebinding lease of 172.16.1.172 Jun 05 17:51:15 volumiopi dhcpcd[894]: wlan0: probing address 172.16.1.172/24 Jun 05 17:51:16 volumiopi ntpd[784]: Deleting interface #26 wlan0, 172.16.1.172#123, interface stats: received=72, sent=72, dropped=0, active_time=17 secs Jun 05 17:51:16 volumiopi ntpd[784]: 162.159.200.123 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 162.159.200.1 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 206.108.0.131 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 174.142.148.226 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 192.95.27.155 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 23.133.168.245 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 54.39.196.172 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 54.39.23.64 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 206.108.0.132 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 144.217.81.160 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 149.56.19.163 local addr 172.16.1.172 -> Jun 05 17:51:16 volumiopi ntpd[784]: 206.108.0.133 local addr 172.16.1.172 -> Jun 05 17:51:19 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 0 Jun 05 17:51:19 volumiopi volumio[24349]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 05 17:51:19 volumiopi volumio[24349]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 05 17:51:19 volumiopi volumio[24349]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 05 17:51:20 volumiopi dhcpcd[894]: wlan0: leased 172.16.1.172 for 86400 seconds Jun 05 17:51:20 volumiopi avahi-daemon[604]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:51:20 volumiopi dhcpcd[894]: wlan0: adding route to 172.16.1.0/24 Jun 05 17:51:20 volumiopi dhcpcd[894]: wlan0: adding default route via 172.16.1.254 Jun 05 17:51:20 volumiopi avahi-daemon[604]: New relevant interface wlan0.IPv4 for mDNS. Jun 05 17:51:20 volumiopi avahi-daemon[604]: Registering new address record for 172.16.1.172 on wlan0.IPv4. Jun 05 17:51:21 volumiopi volumio[24349]: info: Discovery: adding 4e0bb5a3-b151-4982-8745-04d89a9b395e Jun 05 17:51:21 volumiopi volumio[24349]: info: Discovery: Found device VolumioPi Jun 05 17:51:21 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:21 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:21 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:21 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:21 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:21 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:21 volumiopi ntpd[784]: Listen normally on 27 wlan0 172.16.1.172:123 Jun 05 17:51:21 volumiopi ntpd[784]: new interface(s) found: waking up resolver Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin bluetooth to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin multiroom to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin metavolumio to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin cd_controller to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 05 17:51:24 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 05 17:51:25 volumiopi volumio[24349]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 05 17:51:25 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 05 17:51:25 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:25 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:25 volumiopi volumio[24349]: info: Starting MyVolumio Remote Streaming Endpoints Jun 05 17:51:25 volumiopi volumio[24349]: info: MyVolumio login type: Token Jun 05 17:51:25 volumiopi volumio[24349]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 05 17:51:25 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 05 17:51:26 volumiopi volumio[24349]: info: Starting Streaming Service Transparent Proxy Jun 05 17:51:26 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 05 17:51:26 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 05 17:51:26 volumiopi volumio[24349]: info: Streaming services startup Jun 05 17:51:26 volumiopi volumio[24349]: info: Starting Streaming Daemon Jun 05 17:51:26 volumiopi sudo[24775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 05 17:51:26 volumiopi sudo[24775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:27 volumiopi sudo[24775]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:27 volumiopi volumio[24349]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 05 17:51:27 volumiopi volumio[24349]: error: Cannot start Volumio Streaming Daemon Jun 05 17:51:27 volumiopi volumio[24349]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 05 17:51:27 volumiopi volumio[24349]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 05 17:51:27 volumiopi volumio[24349]: error: MyVolumio Custom Token format not valid, refreshing it Jun 05 17:51:28 volumiopi volumio[24349]: STREAMING PROXY: Starting server on port 3245 Jun 05 17:51:28 volumiopi volumio[24349]: Node JS runtime: 14 Jun 05 17:51:28 volumiopi volumio[24349]: info: MyVolumio login type: Token Jun 05 17:51:28 volumiopi volumio[24349]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 05 17:51:29 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 2 Jun 05 17:51:29 volumiopi volumio[24349]: info: MyVolumio token set successfully Jun 05 17:51:29 volumiopi volumio[24349]: info: MYVOLUMIO: Adding device Jun 05 17:51:29 volumiopi volumio[24349]: info: MYVOLUMIO: Evaluating Server Jun 05 17:51:29 volumiopi volumio[24349]: info: MyVolumio status changed Jun 05 17:51:29 volumiopi volumio[24349]: info: Streaming services startup Jun 05 17:51:29 volumiopi volumio[24349]: info: Starting Streaming Daemon Jun 05 17:51:29 volumiopi volumio[24349]: info: Removing browser output: myVolumio user plan is not superstar Jun 05 17:51:29 volumiopi volumio[24349]: info: Removing audio output: Jun 05 17:51:29 volumiopi volumio[24349]: info: Stoppping Tunnel 1 Jun 05 17:51:29 volumiopi sudo[24814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 05 17:51:29 volumiopi sudo[24814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:29 volumiopi sudo[24817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 05 17:51:29 volumiopi sudo[24817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 17:51:29 volumiopi sudo[24814]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:29 volumiopi volumio[24349]: error: Cannot start Volumio Streaming Daemon Jun 05 17:51:29 volumiopi volumio[24349]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 05 17:51:29 volumiopi volumio[24349]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 05 17:51:30 volumiopi sudo[24817]: pam_unix(sudo:session): session closed for user root Jun 05 17:51:30 volumiopi volumio[24349]: info: Remote SSH Stopped Jun 05 17:51:30 volumiopi volumio[24349]: info: Setting Geolocation for MyVolumio to us3 Jun 05 17:51:30 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:30 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:30 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:30 volumiopi volumio[24349]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 05 17:51:31 volumiopi volumio[24349]: info: Updating MyVolumio device info Jun 05 17:51:31 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:31 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:31 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:32 volumiopi volumio[24349]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 05 17:51:32 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 17:51:32 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:32 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 17:51:32 volumiopi volumio-remote-updater[612]: No test mode Jun 05 17:51:32 volumiopi volumio-remote-updater[612]: No alpha test mode Jun 05 17:51:32 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 05 17:51:33 volumiopi volumio[24349]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 05 17:51:33 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 17:51:33 volumiopi volumio[24349]: info: [squeezelite_mc] Server discovered: {"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"} Jun 05 17:51:34 volumiopi volumio[24349]: info: [squeezelite_mc] Notification listener started Jun 05 17:51:34 volumiopi volumio[24349]: info: [squeezelite_mc] Getting players connected to LMS Lossless (172.16.1.11) Jun 05 17:51:34 volumiopi volumio[24349]: info: [squeezelite_mc] Players connected to LMS Lossless (172.16.1.11): [{"id":"bb:bb:75:bd:ea:68","uuid":null,"ip":"172.16.1.11","name":"Living Room","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:58:ad:48:c4","uuid":null,"ip":"172.16.1.11","name":"Network Cabinet","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:58:3d:50:48","uuid":null,"ip":"172.16.1.11","name":"Patio","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:ca:11:4b:a0","uuid":null,"ip":"172.16.1.11","name":"172.16.1.155","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:fd:08:25:6e","uuid":null,"ip":"172.16.1.11","name":"172.16.1.152","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:58:a8:87:d6","uuid":null,"ip":"172.16.1.11","name":"172.16.1.112","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:37:a7:d5:88","uuid":null,"ip":"172.16.1.11","name":"Kitchen","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"34:17:eb:cf:42:a9","uuid":null,"ip":"172.16.1.222","name":"PC-Squeezelite-X","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:58:7a:1b:86","uuid":null,"ip":"172.16.1.11","name":"Hallway","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:58:7a:0d:08","uuid":null,"ip":"172.16.1.11","name":"Media Room","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:fd:4e:13:64","uuid":null,"ip":"172.16.1.11","name":"Bathroom","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}},{"id":"bb:bb:78:1d:17:41","uuid":null,"ip":"172.16.1.11","name":"Marantz SR5008","server":{"ip":"172.16.1.11","name":"LMS Lossless","ver":"8.3.2","uuid":"af61a1b6-90ff-4ab0-94ec-2ef1342f6910","jsonPort":"9001","cliPort":"9090"}}] Jun 05 17:51:34 volumiopi volumio[24349]: info: MYVOLUMIO: Adding device Jun 05 17:51:34 volumiopi volumio[24349]: info: MYVOLUMIO: Evaluating Server Jun 05 17:51:40 volumiopi volumio[24349]: info: Setting Geolocation for MyVolumio to us3 Jun 05 17:51:40 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:40 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:40 volumiopi volumio[24349]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 05 17:51:40 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 17:51:40 volumiopi volumio[24349]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 05 17:51:40 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:40 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:41 volumiopi volumio[24349]: info: Updating MyVolumio device info Jun 05 17:51:41 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:41 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:41 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:41 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:41 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:51:42 volumiopi volumio[24349]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 17:51:45 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:9f:c2:34:23:e9 reason=6 Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: Trying to associate with SSID 'SYNACK' Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA Jun 05 17:51:46 volumiopi avahi-daemon[604]: Withdrawing address record for 172.16.1.172 on wlan0. Jun 05 17:51:46 volumiopi avahi-daemon[604]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:51:46 volumiopi avahi-daemon[604]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: deleting route to 172.16.1.0/24 Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: deleting default route via 172.16.1.254 Jun 05 17:51:46 volumiopi volumio[24349]: info: Discovery: A device disappeared from network Jun 05 17:51:46 volumiopi volumio[24349]: info: Discovery: Device volumiopi disappeared from network Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: Associated with fa:9f:c2:34:23:e9 Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:9f:c2:34:23:e9 completed [id=0 id_str=] Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 05 17:51:46 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:51:46 volumiopi dhcpcd[894]: wlan0: soliciting an IPv6 router Jun 05 17:51:47 volumiopi dhcpcd[894]: wlan0: rebinding lease of 172.16.1.172 Jun 05 17:51:47 volumiopi volumio[24349]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 05 17:51:47 volumiopi volumio[24349]: info: Completed starting MyVolumio Plugin Jun 05 17:51:47 volumiopi volumio[24349]: [Metrics] CommandRouter: 50s 30.73ms Jun 05 17:51:47 volumiopi volumio[24349]: info: CoreCommandRouter::volumiosetStartupVolume Jun 05 17:51:47 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 17:51:47 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 17:51:47 volumiopi volumio[24349]: info: CoreCommandRouter::Close All Modals sent Jun 05 17:51:47 volumiopi volumio[24349]: info: CoreCommandRouter::Close All Modals sent Jun 05 17:51:47 volumiopi volumio[24349]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 05 17:51:47 volumiopi volumio[24349]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 05 17:51:47 volumiopi volumio[24349]: aplay: main:828: audio open error: No such device Jun 05 17:51:47 volumiopi dhcpcd[894]: wlan0: probing address 172.16.1.172/24 Jun 05 17:51:47 volumiopi ntpd[784]: Deleting interface #27 wlan0, 172.16.1.172#123, interface stats: received=24, sent=24, dropped=0, active_time=26 secs Jun 05 17:51:47 volumiopi ntpd[784]: 162.159.200.123 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 162.159.200.1 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 206.108.0.131 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 174.142.148.226 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 192.95.27.155 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 23.133.168.245 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 54.39.196.172 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 54.39.23.64 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 206.108.0.132 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 144.217.81.160 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 149.56.19.163 local addr 172.16.1.172 -> Jun 05 17:51:47 volumiopi ntpd[784]: 206.108.0.133 local addr 172.16.1.172 -> Jun 05 17:51:48 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 05 17:51:48 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 17:51:48 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 05 17:51:48 volumiopi volumio[24349]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 05 17:51:48 volumiopi ntpd[784]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 05 17:51:49 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 0 Jun 05 17:51:51 volumiopi dhcpcd[894]: wlan0: leased 172.16.1.172 for 86400 seconds Jun 05 17:51:51 volumiopi avahi-daemon[604]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:51:51 volumiopi avahi-daemon[604]: New relevant interface wlan0.IPv4 for mDNS. Jun 05 17:51:51 volumiopi dhcpcd[894]: wlan0: adding route to 172.16.1.0/24 Jun 05 17:51:51 volumiopi avahi-daemon[604]: Registering new address record for 172.16.1.172 on wlan0.IPv4. Jun 05 17:51:51 volumiopi dhcpcd[894]: wlan0: adding default route via 172.16.1.254 Jun 05 17:51:52 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:52 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:52 volumiopi volumio[24349]: info: Discovery: adding 4e0bb5a3-b151-4982-8745-04d89a9b395e Jun 05 17:51:52 volumiopi volumio[24349]: info: Discovery: Found device VolumioPi Jun 05 17:51:52 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:51:52 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:51:53 volumiopi ntpd[784]: Listen normally on 28 wlan0 172.16.1.172:123 Jun 05 17:51:53 volumiopi ntpd[784]: new interface(s) found: waking up resolver Jun 05 17:51:54 volumiopi volumio[24349]: info: BOOT COMPLETED Jun 05 17:51:58 volumiopi ntpd[784]: Soliciting pool server 199.182.221.110 Jun 05 17:51:59 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 2 Jun 05 17:51:59 volumiopi ntpd[784]: Soliciting pool server 70.55.19.46 Jun 05 17:52:00 volumiopi ntpd[784]: Soliciting pool server 51.161.35.150 Jun 05 17:52:01 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:52:01 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:52:01 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:52:01 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:52:01 volumiopi ntpd[784]: Soliciting pool server 142.4.192.253 Jun 05 17:52:03 volumiopi ntpd[784]: Soliciting pool server 2606:4700:f1::1 Jun 05 17:52:11 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:52:11 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:9f:c2:34:23:e9 reason=6 Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: Trying to associate with SSID 'SYNACK' Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA Jun 05 17:52:16 volumiopi avahi-daemon[604]: Withdrawing address record for 172.16.1.172 on wlan0. Jun 05 17:52:16 volumiopi avahi-daemon[604]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:52:16 volumiopi avahi-daemon[604]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: deleting route to 172.16.1.0/24 Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: deleting default route via 172.16.1.254 Jun 05 17:52:16 volumiopi volumio[24349]: info: Discovery: A device disappeared from network Jun 05 17:52:16 volumiopi volumio[24349]: info: Discovery: Device volumiopi disappeared from network Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: Associated with fa:9f:c2:34:23:e9 Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:9f:c2:34:23:e9 completed [id=0 id_str=] Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 05 17:52:16 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: rebinding lease of 172.16.1.172 Jun 05 17:52:16 volumiopi dhcpcd[894]: wlan0: probing address 172.16.1.172/24 Jun 05 17:52:17 volumiopi dhcpcd[894]: wlan0: soliciting an IPv6 router Jun 05 17:52:17 volumiopi ntpd[784]: Deleting interface #28 wlan0, 172.16.1.172#123, interface stats: received=40, sent=40, dropped=0, active_time=24 secs Jun 05 17:52:17 volumiopi ntpd[784]: 142.4.192.253 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 51.161.35.150 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 70.55.19.46 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 199.182.221.110 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 162.159.200.123 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 162.159.200.1 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 206.108.0.131 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 174.142.148.226 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 192.95.27.155 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 23.133.168.245 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 54.39.196.172 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 54.39.23.64 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 206.108.0.132 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 144.217.81.160 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 149.56.19.163 local addr 172.16.1.172 -> Jun 05 17:52:17 volumiopi ntpd[784]: 206.108.0.133 local addr 172.16.1.172 -> Jun 05 17:52:19 volumiopi volumio[24349]: info: Volumio Network Manager: Network status updated: 0 Jun 05 17:52:22 volumiopi dhcpcd[894]: wlan0: leased 172.16.1.172 for 86400 seconds Jun 05 17:52:22 volumiopi avahi-daemon[604]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:52:22 volumiopi dhcpcd[894]: wlan0: adding route to 172.16.1.0/24 Jun 05 17:52:22 volumiopi dhcpcd[894]: wlan0: adding default route via 172.16.1.254 Jun 05 17:52:22 volumiopi avahi-daemon[604]: New relevant interface wlan0.IPv4 for mDNS. Jun 05 17:52:22 volumiopi avahi-daemon[604]: Registering new address record for 172.16.1.172 on wlan0.IPv4. Jun 05 17:52:22 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:52:22 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:52:22 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:52:22 volumiopi volumio[24349]: info: Listing playlists Jun 05 17:52:23 volumiopi volumio[24349]: info: Discovery: adding 4e0bb5a3-b151-4982-8745-04d89a9b395e Jun 05 17:52:23 volumiopi volumio[24349]: info: Discovery: Found device VolumioPi Jun 05 17:52:23 volumiopi volumio[24349]: info: CoreCommandRouter::volumioGetState Jun 05 17:52:23 volumiopi volumio[24349]: info: CorePlayQueue::getTrack 0 Jun 05 17:52:23 volumiopi ntpd[784]: Listen normally on 29 wlan0 172.16.1.172:123 Jun 05 17:52:23 volumiopi ntpd[784]: new interface(s) found: waking up resolver Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-DISCONNECTED bssid=fa:9f:c2:34:23:e9 reason=6 Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: Trying to associate with SSID 'SYNACK' Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA Jun 05 17:52:29 volumiopi avahi-daemon[604]: Withdrawing address record for 172.16.1.172 on wlan0. Jun 05 17:52:29 volumiopi avahi-daemon[604]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 172.16.1.172. Jun 05 17:52:29 volumiopi avahi-daemon[604]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: deleting route to 172.16.1.0/24 Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: deleting default route via 172.16.1.254 Jun 05 17:52:29 volumiopi volumio[24349]: info: Discovery: A device disappeared from network Jun 05 17:52:29 volumiopi volumio[24349]: info: Discovery: Device volumiopi disappeared from network Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: Associated with fa:9f:c2:34:23:e9 Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-CONNECTED - Connection to fa:9f:c2:34:23:e9 completed [id=0 id_str=] Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 05 17:52:29 volumiopi wpa_supplicant[876]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: carrier lost Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: carrier acquired Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: IAID eb:18:3e:2c Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: rebinding lease of 172.16.1.172 Jun 05 17:52:29 volumiopi dhcpcd[894]: wlan0: probing address 172.16.1.172/24 Jun 05 17:52:30 volumiopi dhcpcd[894]: wlan0: soliciting an IPv6 router Jun 05 17:52:30 volumiopi ntpd[784]: Deleting interface #29 wlan0, 172.16.1.172#123, interface stats: received=16, sent=18, dropped=1, active_time=7 secs Jun 05 17:52:30 volumiopi ntpd[784]: 162.159.200.123 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 162.159.200.1 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 206.108.0.131 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 174.142.148.226 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 192.95.27.155 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 23.133.168.245 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 54.39.196.172 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 54.39.23.64 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 206.108.0.132 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 144.217.81.160 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 149.56.19.163 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 206.108.0.133 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 199.182.221.110 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 70.55.19.46 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 51.161.35.150 local addr 172.16.1.172 -> Jun 05 17:52:30 volumiopi ntpd[784]: 142.4.192.253 local addr 172.16.1.172 -> Jun 05 17:52:33 volumiopi volumio[24349]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 17:52:33 volumiopi volumio[24349]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 05 17:52:33 volumiopi volumio[24349]: at doSend (dgram.js:692:16) Jun 05 17:52:33 volumiopi volumio[24349]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 05 17:52:33 volumiopi volumio[24349]: at afterDns (dgram.js:638:5) Jun 05 17:52:33 volumiopi volumio[24349]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 05 17:52:33 volumiopi volumio[24349]: errno: -101, Jun 05 17:52:33 volumiopi volumio[24349]: code: 'ENETUNREACH', Jun 05 17:52:33 volumiopi volumio[24349]: syscall: 'send', Jun 05 17:52:33 volumiopi volumio[24349]: address: '255.255.255.255', Jun 05 17:52:33 volumiopi volumio[24349]: port: 3483 Jun 05 17:52:33 volumiopi volumio[24349]: } Jun 05 17:52:33 volumiopi volumio[24349]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 17:52:33 volumiopi sudo[25156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 17:51 Jun 05 17:52:33 volumiopi sudo[25156]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"