-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Wed 2026-02-11 18:40:09 EST. -- Feb 11 18:39:00 pifystrm volumio[3875]: info: Plugin mpdemulation is not enabled Feb 11 18:39:00 pifystrm volumio[3875]: info: Loading plugin "rest_api"... Feb 11 18:39:00 pifystrm volumio[3875]: info: Loading plugin "websocket"... Feb 11 18:39:00 pifystrm volumio[3875]: info: Starting Socket.io Server version 2.3.0 Feb 11 18:39:00 pifystrm volumio[3875]: info: Loading plugin "touch_display"... Feb 11 18:39:00 pifystrm volumio[3875]: Forking 3 albumart workers Feb 11 18:39:00 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:00] [connect] Successful connection Feb 11 18:39:00 pifystrm volumio[3875]: info: Applying required configuration parameters for plugin touch_display Feb 11 18:39:00 pifystrm volumio[3875]: info: Loading i18n strings for locale en Feb 11 18:39:00 pifystrm volumio[3875]: Updating browse sources language Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::initPlayerControls Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: Express server listening on port 3000 Feb 11 18:39:00 pifystrm volumio[3875]: [Metrics] WebUI: 4s 367.56ms Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::resetVolumioState Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::getcurrentVolume Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::pushState Feb 11 18:39:00 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:00 pifystrm volumio[3875]: info: Volumio Network Manager: Network status updated: 0 Feb 11 18:39:00 pifystrm volumio[3875]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 11 18:39:00 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1770853140 101 Feb 11 18:39:00 pifystrm volumio[3875]: 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: 3 Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 11 18:39:00 pifystrm volumio[3875]: Starting albumart workers Feb 11 18:39:00 pifystrm volumio[3875]: Starting albumart workers Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:00 pifystrm volumio[3875]: info: Reloading queue from file Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 11 18:39:00 pifystrm volumio[3875]: Starting albumart workers Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::setRepeat false single undefined Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::pushState Feb 11 18:39:00 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::setRandom false Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreStateMachine::pushState Feb 11 18:39:00 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:00 pifystrm volumio[3875]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:00 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:39:00 pifystrm volumio[3875]: info: Setting Device type: Raspberry PI Feb 11 18:39:00 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:00 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:39:00 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:39:00 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:39:00 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:39:00 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 11 18:39:01 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:01 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:01 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:01 pifystrm volumio[3875]: info: Completed loading Core Plugins Feb 11 18:39:01 pifystrm volumio[3875]: info: Preparing to generate the ALSA configuration file Feb 11 18:39:01 pifystrm volumio[3875]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 11 18:39:01 pifystrm volumio[3875]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 11 18:39:01 pifystrm volumio[3875]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:01 pifystrm volumio[3875]: info: Asound.conf file unchanged, so no further update is needed Feb 11 18:39:01 pifystrm volumio[3875]: info: Output device has changed, restarting MPD Feb 11 18:39:01 pifystrm volumio[3875]: info: Output device has changed, restarting Shairport Sync Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:01 pifystrm sudo[4013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:39:01 pifystrm sudo[4013]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:01.228-05:00 level=INFO msg="disabling BLE discovery" Feb 11 18:39:01 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:01.235-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 11 18:39:01 pifystrm sudo[4013]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:39:01 pifystrm sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm systemd[1]: Stopping Music Player Daemon... Feb 11 18:39:01 pifystrm systemd[1]: mpd.service: Succeeded. Feb 11 18:39:01 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 11 18:39:01 pifystrm volumio[3875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:01 pifystrm volumio[3875]: info: ___________ START PLUGINS ___________ Feb 11 18:39:01 pifystrm systemd[1]: Starting Music Player Daemon... Feb 11 18:39:01 pifystrm volumio[3875]: info: ControllerMpd::onStart: Initializing MPD Feb 11 18:39:01 pifystrm volumio[3875]: info: Creating MPD Configuration file Feb 11 18:39:01 pifystrm sudo[4023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 11 18:39:01 pifystrm sudo[4023]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm sudo[4021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 18:39:01 pifystrm sudo[4021]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:01 pifystrm sudo[4021]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm volumio[3875]: info: [1770853141450] CoreMusicLibrary::Adding element Media Servers Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:01 pifystrm sudo[4023]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm sudo[4035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:39:01 pifystrm sudo[4035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm volumio[3875]: info: UPNP Browser: Client initialized successfully Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:01 pifystrm sudo[4035]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm sudo[4043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:39:01 pifystrm sudo[4043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 11 18:39:01 pifystrm systemd[1]: mpd.service: Succeeded. Feb 11 18:39:01 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 11 18:39:01 pifystrm systemd[1]: Starting Music Player Daemon... Feb 11 18:39:01 pifystrm volumio[3875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:01 pifystrm volumio[3875]: info: [1770853141606] CoreMusicLibrary::Adding element Last_100 Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:01 pifystrm volumio[3875]: info: [1770853141608] CoreMusicLibrary::Adding element Webradio Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:01 pifystrm volumio[3875]: info: Initializing BBC Radios Feb 11 18:39:01 pifystrm sudo[4060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 18:39:01 pifystrm sudo[4060]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm sudo[4060]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: Checking for old spotify connect plugin installed Feb 11 18:39:01 pifystrm volumio[3875]: info: [squeezelite_mc] Starting proxy server... Feb 11 18:39:01 pifystrm volumio[3875]: info: [now-playing-config] Config is up to date. Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:01 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:01 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:01 pifystrm volumio[3875]: info: Loading i18n strings for locale en Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:01 pifystrm volumio[3875]: info: Volumio Calling Home Feb 11 18:39:01 pifystrm sudo[3937]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:39:01 pifystrm volumio[3875]: [SpotifyConnect] Creating VLS config file Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:01 pifystrm volumio[3875]: [SpotifyConnect] Starting metadata listener Feb 11 18:39:01 pifystrm volumio[3875]: info: [squeezelite_mc] Proxy server started on port 45081 Feb 11 18:39:01 pifystrm volumio[3875]: info: [now-playing-app] App is listening on port 4004. Feb 11 18:39:01 pifystrm sudo[4091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Feb 11 18:39:01 pifystrm sudo[4091]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm sudo[4091]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:01 pifystrm sudo[4094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 18:39:01 pifystrm sudo[4094]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:01 pifystrm systemd[1]: Reloading. Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreStateMachine::pushState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Server discovery started Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Player finder started Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 11 18:39:02 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:02 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:02 pifystrm volumio[3875]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 11 18:39:02 pifystrm volumio[3875]: [Metrics] SpotifyConnect: 0s 265.44ms Feb 11 18:39:02 pifystrm volumio[3875]: info: MPD Permissions set Feb 11 18:39:02 pifystrm volumio[3875]: info: MPD Permissions set Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:02 pifystrm volumio[3875]: verbose: New Socket.io Connection to 10.1.12.92:3000 from 10.1.11.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 18:39:02 pifystrm volumio[3875]: verbose: New Socket.io Connection to 10.1.12.92:3000 from 10.1.11.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Server discovered: {"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"} Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S24_LE"] Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pifystrm","dsdFormat":"dop","card":"1","mixerType":"None","mixer":null} Feb 11 18:39:02 pifystrm volumio[3875]: 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 Feb 11 18:39:02 pifystrm volumio[3875]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 11 18:39:02 pifystrm volumio[3875]: info: Discovery: Found device PiFyStrm Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:02 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:02 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 11 18:39:02 pifystrm sudo[4114]: 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 Feb 11 18:39:02 pifystrm sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm volumio[3875]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 11 18:39:02 pifystrm sudo[4114]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm volumio[3875]: info: Volumio called home Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm 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. Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 11 18:39:02 pifystrm sudo[4126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 11 18:39:02 pifystrm sudo[4126]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm volumio[3875]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 11 18:39:02 pifystrm volumio[3875]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Feb 11 18:39:02 pifystrm volumio[3875]: info: Starting Shairport Sync Feb 11 18:39:02 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 11 18:39:02 pifystrm sudo[4126]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm volumio[3875]: info: Starting Shairport Sync Feb 11 18:39:02 pifystrm sudo[4129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:02 pifystrm volumio[3875]: info: Starting Shairport Sync Feb 11 18:39:02 pifystrm sudo[4129]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm sudo[4131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:02 pifystrm sudo[4131]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:02 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:02 pifystrm sudo[4134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:02 pifystrm sudo[4134]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm mpd[4067]: Feb 11 18:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 11 18:39:02 pifystrm systemd[1]: Started Music Player Daemon. Feb 11 18:39:02 pifystrm sudo[4094]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4043]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4015]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Feb 11 18:39:02 pifystrm sudo[4141]: 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 Feb 11 18:39:02 pifystrm sudo[4141]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm sudo[4144]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm sudo[4141]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4144]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 11 18:39:02 pifystrm systemd[1]: shairport-sync.service: Succeeded. Feb 11 18:39:02 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:02 pifystrm volumio[3875]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 18:39:02 pifystrm sudo[4151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 18:39:02 pifystrm sudo[4151]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm volumio[3875]: info: MPD running with PID4067 Feb 11 18:39:02 pifystrm volumio[3875]: ,establishing connection Feb 11 18:39:02 pifystrm volumio[3875]: info: touch_display: File permissions for backlight brightness control set. Feb 11 18:39:02 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 11 18:39:02 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:02 pifystrm sudo[4129]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4134]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4131]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm sudo[4154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 18:39:02 pifystrm sudo[4154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:02 pifystrm systemd[1]: Reloading. Feb 11 18:39:02 pifystrm sudo[4151]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:02 pifystrm volumio[3875]: error: MPD error: The expression evaluated to a falsy value: Feb 11 18:39:02 pifystrm volumio[3875]: assert.ok(self.idling) Feb 11 18:39:02 pifystrm volumio[3875]: error: The expression evaluated to a falsy value: Feb 11 18:39:02 pifystrm volumio[3875]: assert.ok(self.idling) Feb 11 18:39:02 pifystrm volumio[3875]: error: MPD error: The expression evaluated to a falsy value: Feb 11 18:39:02 pifystrm volumio[3875]: assert.ok(self.idling) Feb 11 18:39:02 pifystrm volumio[3875]: error: The expression evaluated to a falsy value: Feb 11 18:39:02 pifystrm volumio[3875]: assert.ok(self.idling) Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Volumio Kiosk started Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm volumio[3875]: info: Shairport-Sync Started Feb 11 18:39:03 pifystrm volumio[3875]: Error adding Membership: Error: addMembership EINVAL Feb 11 18:39:03 pifystrm volumio[3875]: info: Shairport-Sync Started Feb 11 18:39:03 pifystrm volumio[3875]: info: Shairport-Sync Started Feb 11 18:39:03 pifystrm volumio[3875]: error: updateQueue error: null Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm 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. Feb 11 18:39:03 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 11 18:39:03 pifystrm sudo[4154]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:03 pifystrm ntpd[769]: Listen normally on 5 wlan0 10.1.12.92:123 Feb 11 18:39:03 pifystrm ntpd[769]: new interface(s) found: waking up resolver Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] Notification listener started Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm sudo[4190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:03 pifystrm sudo[4190]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"id":"bb:bb:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:2d:2b:10:5e","uuid":null,"ip":"10.1.14.16","name":"SHIELD","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"c0:f5:35:6b:a7:0c","uuid":null,"ip":"10.1.12.55","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:a1:6b:dc:c6","uuid":null,"ip":"10.1.14.16","name":"EVStrmer-UPnP/AV","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"3c:e1:a1:6b:dc:c6","uuid":null,"ip":"10.1.11.28","name":"evstrmer","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:de:b5:4a:65","uuid":null,"ip":"10.1.14.16","name":"YamahaAVR","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:33:fc:1a:20","uuid":null,"ip":"10.1.14.16","name":"Training Room 9","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}] Feb 11 18:39:03 pifystrm sudo[4190]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 11 18:39:03 pifystrm sudo[4193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 11 18:39:03 pifystrm sudo[4193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:03 pifystrm systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm volumio[3875]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:03 pifystrm volumio[3875]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 18:39:03 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:03 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:03 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:03 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:03 pifystrm volumio[3875]: info: Listing playlists Feb 11 18:39:03 pifystrm volumio[3875]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'disconnect' Feb 11 18:39:03 pifystrm systemd[1]: squeezelite.service: Succeeded. Feb 11 18:39:03 pifystrm systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Feb 11 18:39:04 pifystrm sudo[4193]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:04 pifystrm sudo[4201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:04 pifystrm sudo[4201]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:04 pifystrm sudo[4201]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 11 18:39:04 pifystrm sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 11 18:39:04 pifystrm sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:04 pifystrm sudo[4205]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 11 18:39:04 pifystrm sudo[4208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 11 18:39:04 pifystrm sudo[4208]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:04 pifystrm systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 11 18:39:04 pifystrm systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 11 18:39:04 pifystrm sudo[4208]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'connect' Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"id":"bb:bb:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:2d:2b:10:5e","uuid":null,"ip":"10.1.14.16","name":"SHIELD","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"c0:f5:35:6b:a7:0c","uuid":null,"ip":"10.1.12.55","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:a1:6b:dc:c6","uuid":null,"ip":"10.1.14.16","name":"EVStrmer-UPnP/AV","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"3c:e1:a1:6b:dc:c6","uuid":null,"ip":"10.1.11.28","name":"evstrmer","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:de:b5:4a:65","uuid":null,"ip":"10.1.14.16","name":"YamahaAVR","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:33:fc:1a:20","uuid":null,"ip":"10.1.14.16","name":"Training Room 9","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}}] Feb 11 18:39:04 pifystrm volumio[3875]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}} Feb 11 18:39:05 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:05 pifystrm sudo[4216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:05 pifystrm sudo[4216]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:05 pifystrm sudo[4216]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:05 pifystrm sudo[4219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:05 pifystrm sudo[4219]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:05 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:05 pifystrm sudo[4223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:05 pifystrm sudo[4223]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:05 pifystrm sudo[4223]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:05 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:05 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:06 pifystrm volumio[3875]: info: Checking Spotify Web API Feb 11 18:39:06 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:06 pifystrm sudo[4226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:06 pifystrm sudo[4226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:06 pifystrm sudo[4226]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:06 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:06 pifystrm sudo[4229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:06 pifystrm sudo[4229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:06 pifystrm sudo[4229]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:07 pifystrm volumio[3875]: verbose: New Socket.io Connection to 10.1.12.92:3000 from 10.1.11.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 11 18:39:07 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:07 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 11 18:39:07 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:07 pifystrm avahi-daemon[675]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 11 18:39:07 pifystrm avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: deleting route to 10.1.8.0/21 Feb 11 18:39:07 pifystrm avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: deleting default route via 10.1.10.1 Feb 11 18:39:07 pifystrm volumio[3875]: info: Discovery: A device disappeared from network Feb 11 18:39:07 pifystrm volumio[3875]: info: Discovery: Device pifystrm disappeared from network Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:07 pifystrm volumio[3875]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:07 pifystrm sudo[4264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:07 pifystrm sudo[4264]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:07 pifystrm sudo[4264]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:07 pifystrm volumio[3875]: info: Completed starting Core Plugins Feb 11 18:39:07 pifystrm volumio[3875]: info: ------------------------------------------- Feb 11 18:39:07 pifystrm volumio[3875]: info: ----- MyVolumio plugins startup ---- Feb 11 18:39:07 pifystrm volumio[3875]: info: ------------------------------------------- Feb 11 18:39:07 pifystrm volumio[3875]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 18:39:07 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:07 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:07 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:07 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:07 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:07.717-05:00 level=INFO msg="enabling BLE discovery" Feb 11 18:39:07 pifystrm sudo[4281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:39:07 pifystrm sudo[4281]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:07 pifystrm sudo[4281]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:07 pifystrm sudo[4284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:39:07 pifystrm sudo[4284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:07 pifystrm sudo[4284]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:07 pifystrm sudo[4287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 18:39:07 pifystrm sudo[4287]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:07 pifystrm sudo[4287]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:07 pifystrm volumio[3875]: info: Upmpdcli Daemon Started Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:07 pifystrm volumio[3875]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 18:39:07 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:08 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:08 pifystrm ntpd[769]: Deleting interface #5 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs Feb 11 18:39:12 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:12 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:99:a1 Feb 11 18:39:12 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:99:a1 completed [id=1 id_str=] Feb 11 18:39:12 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:12 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:12 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:12 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:12 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:12 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:12 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:12 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:12 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:13 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:15 pifystrm sudo[4219]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:15 pifystrm volumio[3875]: info: Cannot mount NAS Curaudio at system boot, trial number 2 ,retrying in 5 seconds Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:18 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 11 18:39:18 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 11 18:39:18 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:18 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:18 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:18 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:18 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:18 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:20 pifystrm sudo[4346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:20 pifystrm sudo[4346]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:20 pifystrm sudo[4346]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:20 pifystrm volumio[3875]: info: Cannot mount NAS Curaudio at system boot, trial number 3 ,retrying in 5 seconds Feb 11 18:39:23 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:39:23 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:23 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:39:23 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:39:23 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:39:23 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:39:24 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:24 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:24 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:24 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:24.201-05:00 level=INFO msg="disabling BLE discovery" Feb 11 18:39:24 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:24.205-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 11 18:39:24 pifystrm volumio[3875]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 11 18:39:24 pifystrm volumio[3875]: info: Discovery: Found device PiFyStrm Feb 11 18:39:24 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:24 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:25 pifystrm ntpd[769]: Listen normally on 6 wlan0 10.1.12.92:123 Feb 11 18:39:25 pifystrm ntpd[769]: new interface(s) found: waking up resolver Feb 11 18:39:25 pifystrm sudo[4385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:25 pifystrm sudo[4385]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:26 pifystrm volumio[3875]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'disconnect' Feb 11 18:39:26 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:26 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:27 pifystrm volumio[3875]: error: MyVolumio Plugin failed to start in a timely fashion Feb 11 18:39:27 pifystrm volumio[3875]: [Metrics] CommandRouter: 30s 978.76ms Feb 11 18:39:27 pifystrm volumio[3875]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 18:39:27 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:27 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:27 pifystrm volumio[3875]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:39:27 pifystrm volumio[3875]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:39:27 pifystrm volumio[3875]: info: Volumio Network Manager: Network status updated: 2 Feb 11 18:39:28 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 18:39:28 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 18:39:28 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:29 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:99:a1 Feb 11 18:39:29 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:99:a1 completed [id=1 id_str=] Feb 11 18:39:29 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:29 pifystrm avahi-daemon[675]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 11 18:39:29 pifystrm avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: deleting route to 10.1.8.0/21 Feb 11 18:39:29 pifystrm avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: deleting default route via 10.1.10.1 Feb 11 18:39:29 pifystrm volumio[3875]: info: Discovery: A device disappeared from network Feb 11 18:39:29 pifystrm volumio[3875]: info: Discovery: Device pifystrm disappeared from network Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:29 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:29 pifystrm volumio[3875]: info: Received Get System Info Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:29 pifystrm volumio[3875]: info: Discovery: Getting this device information Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:29 pifystrm volumio[3875]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:29 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:29 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:29.709-05:00 level=INFO msg="enabling BLE discovery" Feb 11 18:39:30 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:30 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:30 pifystrm ntpd[769]: Deleting interface #6 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs Feb 11 18:39:31 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:31 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:31 pifystrm volumio[3875]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:39:32 pifystrm volumio[3875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:39:32 pifystrm volumio[3875]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 11 18:39:32 pifystrm volumio[3875]: at doSend (dgram.js:692:16) Feb 11 18:39:32 pifystrm volumio[3875]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 11 18:39:32 pifystrm volumio[3875]: at afterDns (dgram.js:638:5) Feb 11 18:39:32 pifystrm volumio[3875]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 11 18:39:32 pifystrm volumio[3875]: errno: -101, Feb 11 18:39:32 pifystrm volumio[3875]: code: 'ENETUNREACH', Feb 11 18:39:32 pifystrm volumio[3875]: syscall: 'send', Feb 11 18:39:32 pifystrm volumio[3875]: address: '255.255.255.255', Feb 11 18:39:32 pifystrm volumio[3875]: port: 3483 Feb 11 18:39:32 pifystrm volumio[3875]: } Feb 11 18:39:32 pifystrm volumio[3875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:39:32 pifystrm sudo[4491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 18:38 Feb 11 18:39:32 pifystrm sudo[4491]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:32 pifystrm sudo[4491]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:32 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 11 18:39:32 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 11 18:39:32 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:32.493-05:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 11 18:39:32 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 11 18:39:32 pifystrm sudo[4385]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:32 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:32.498-05:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 11 18:39:32 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 11 18:39:32 pifystrm systemd[1]: Started dynamicswap service. Feb 11 18:39:32 pifystrm systemd[1]: dynamicswap.service: Succeeded. Feb 11 18:39:32 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 11 18:39:32 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Feb 11 18:39:32 pifystrm systemd[1]: Started dynamicswap service. Feb 11 18:39:32 pifystrm systemd[1]: Stopped Volumio Backend Module. Feb 11 18:39:32 pifystrm systemd[1]: Started Volumio Backend Module. Feb 11 18:39:32 pifystrm systemd[1]: dynamicswap.service: Succeeded. Feb 11 18:39:33 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:33 pifystrm volumio[4505]: info: ----- Volumio3 ---- Feb 11 18:39:33 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:33 pifystrm volumio[4505]: info: ----- System startup ---- Feb 11 18:39:33 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:34 pifystrm volumio[4505]: info: MYVOLUMIO Environment detected Feb 11 18:39:34 pifystrm volumio[4505]: info: Plugin folders cleanup Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning into folder /volumio/app/plugins/ Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category audio_interface Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category miscellanea Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category music_service Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category plugins.json Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category system_controller Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category user_interface Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning into folder /data/plugins/ Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category music_service Feb 11 18:39:34 pifystrm volumio[4505]: info: Scanning category user_interface Feb 11 18:39:34 pifystrm volumio[4505]: info: Plugin folders cleanup completed Feb 11 18:39:34 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:34 pifystrm volumio[4505]: info: ----- Core plugins startup ---- Feb 11 18:39:34 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 18:39:34 pifystrm volumio[4505]: info: Adding plugin upnp to MyMusic Plugins Feb 11 18:39:34 pifystrm volumio[4505]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 18:39:34 pifystrm volumio[4505]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugins from folder /data/plugins/ Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "system"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "appearance"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "network"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Refreshing Cached IP Addresses Feb 11 18:39:34 pifystrm sudo[4531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:39:34 pifystrm sudo[4531]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:34 pifystrm sudo[4531]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:34 pifystrm sudo[4533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:39:34 pifystrm sudo[4533]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:34 pifystrm sudo[4533]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "services"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "volumio5onboarding"... Feb 11 18:39:34 pifystrm sudo[4542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 18:39:34 pifystrm sudo[4542]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "alsa_controller"... Feb 11 18:39:34 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "wizard"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "networkfs"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Starting Udev Watcher for removable devices Feb 11 18:39:34 pifystrm sudo[4557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:34 pifystrm sudo[4557]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:34 pifystrm volumio[4505]: info: Ignoring mount for partition: boot Feb 11 18:39:34 pifystrm volumio[4505]: info: Ignoring mount for partition: volumio Feb 11 18:39:34 pifystrm volumio[4505]: info: Ignoring mount for partition: volumio_data Feb 11 18:39:34 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "volumio_command_line_client"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "upnp"... Feb 11 18:39:34 pifystrm volumio[4505]: info: [1770853174987] Starting Upmpd Daemon Feb 11 18:39:34 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "my_music"... Feb 11 18:39:34 pifystrm volumio[4505]: info: Loading plugin "mpd"... Feb 11 18:39:35 pifystrm sudo[4557]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "upnp_browser"... Feb 11 18:39:35 pifystrm volumio[4505]: info: Starting UPNP Browser Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "alarm-clock"... Feb 11 18:39:35 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:39:35 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:35 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:39:35 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:39:35 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:39:35 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "airplay_emulation"... Feb 11 18:39:35 pifystrm volumio[4505]: info: Starting Shairport Sync Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "last_100"... Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "webradio"... Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "i2s_dacs"... Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "volumiodiscovery"... Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** For more information see Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:39:35 pifystrm volumio[4505]: *** WARNING *** For more information see Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** For more information see Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:39:35 pifystrm node[4505]: *** WARNING *** For more information see Feb 11 18:39:35 pifystrm volumio[4505]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 18:39:35 pifystrm volumio[4505]: info: Discovery: Started advertising with name: PiFyStrm Feb 11 18:39:35 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:39:35 pifystrm volumio[4505]: info: Loading plugin "spop"... Feb 11 18:39:36 pifystrm volumio[4505]: info: Loading plugin "squeezelite_mc"... Feb 11 18:39:36 pifystrm ntpd[769]: Listen normally on 7 wlan0 10.1.12.92:123 Feb 11 18:39:36 pifystrm ntpd[769]: new interface(s) found: waking up resolver Feb 11 18:39:36 pifystrm volumio[4505]: info: Loading plugin "now_playing"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "outputs"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "albumart"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Plugin example_plugin is not enabled Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "inputs"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "updater_comm"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Plugin mpdemulation is not enabled Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "rest_api"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "websocket"... Feb 11 18:39:37 pifystrm volumio[4505]: info: Starting Socket.io Server version 2.3.0 Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading plugin "touch_display"... Feb 11 18:39:37 pifystrm volumio[4505]: Forking 3 albumart workers Feb 11 18:39:37 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:37] [connect] Successful connection Feb 11 18:39:37 pifystrm volumio[4505]: info: Applying required configuration parameters for plugin touch_display Feb 11 18:39:37 pifystrm volumio[4505]: info: Loading i18n strings for locale en Feb 11 18:39:37 pifystrm volumio[4505]: Updating browse sources language Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::initPlayerControls Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:37 pifystrm volumio[4505]: Express server listening on port 3000 Feb 11 18:39:37 pifystrm volumio[4505]: [Metrics] WebUI: 4s 431.04ms Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreStateMachine::resetVolumioState Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreStateMachine::getcurrentVolume Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreStateMachine::pushState Feb 11 18:39:37 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:37 pifystrm volumio[4505]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:37 pifystrm volumio[4505]: info: Volumio Network Manager: Network status updated: 0 Feb 11 18:39:37 pifystrm volumio[4505]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Feb 11 18:39:37 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 11 18:39:38 pifystrm volumio-remote-updater[673]: [2026-02-11 18:39:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1770853177 101 Feb 11 18:39:38 pifystrm volumio[4505]: 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: 2 Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to 10.1.12.92:3000 from 10.1.11.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 11 18:39:38 pifystrm volumio[4505]: Starting albumart workers Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: Reloading queue from file Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 11 18:39:38 pifystrm volumio[4505]: Starting albumart workers Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreStateMachine::setRepeat false single undefined Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreStateMachine::pushState Feb 11 18:39:38 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreStateMachine::setRandom false Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreStateMachine::pushState Feb 11 18:39:38 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:38 pifystrm volumio[4505]: info: Setting Device type: Raspberry PI Feb 11 18:39:38 pifystrm volumio[4505]: info: Completed loading Core Plugins Feb 11 18:39:38 pifystrm volumio[4505]: info: Preparing to generate the ALSA configuration file Feb 11 18:39:38 pifystrm volumio[4505]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 11 18:39:38 pifystrm volumio[4505]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 11 18:39:38 pifystrm volumio[4505]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 11 18:39:38 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 11 18:39:38 pifystrm volumio[4505]: Starting albumart workers Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 11 18:39:38 pifystrm volumio[4505]: info: Asound.conf file unchanged, so no further update is needed Feb 11 18:39:38 pifystrm volumio[4505]: info: Output device has changed, restarting MPD Feb 11 18:39:38 pifystrm sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:39:38 pifystrm sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm sudo[4637]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:38 pifystrm volumio[4505]: info: Output device has changed, restarting Shairport Sync Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:38 pifystrm sudo[4640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:39:38 pifystrm sudo[4640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm volumio[4505]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:38 pifystrm volumio[4505]: info: ___________ START PLUGINS ___________ Feb 11 18:39:38 pifystrm systemd[1]: Stopping Music Player Daemon... Feb 11 18:39:38 pifystrm systemd[1]: mpd.service: Succeeded. Feb 11 18:39:38 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 11 18:39:38 pifystrm volumio[4505]: info: ControllerMpd::onStart: Initializing MPD Feb 11 18:39:38 pifystrm volumio[4505]: info: Creating MPD Configuration file Feb 11 18:39:38 pifystrm sudo[4646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 11 18:39:38 pifystrm sudo[4646]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:38 pifystrm volumio[4505]: info: [1770853178522] CoreMusicLibrary::Adding element Media Servers Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:38 pifystrm systemd[1]: Starting Music Player Daemon... Feb 11 18:39:38 pifystrm sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:39:38 pifystrm sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm sudo[4649]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:38 pifystrm sudo[4646]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:38 pifystrm volumio[4505]: info: UPNP Browser: Client initialized successfully Feb 11 18:39:38 pifystrm sudo[4653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:39:38 pifystrm sudo[4653]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:38 pifystrm sudo[4647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 18:39:38 pifystrm sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm sudo[4647]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:38 pifystrm volumio[4505]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:38 pifystrm systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 11 18:39:38 pifystrm systemd[1]: mpd.service: Succeeded. Feb 11 18:39:38 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 11 18:39:38 pifystrm systemd[1]: Starting Music Player Daemon... Feb 11 18:39:38 pifystrm volumio[4505]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:38 pifystrm volumio[4505]: info: [1770853178753] CoreMusicLibrary::Adding element Last_100 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:39:38 pifystrm volumio[4505]: info: [1770853178756] CoreMusicLibrary::Adding element Webradio Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:38 pifystrm volumio[4505]: info: Initializing BBC Radios Feb 11 18:39:38 pifystrm sudo[4669]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 18:39:38 pifystrm sudo[4669]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:38 pifystrm sudo[4669]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:38 pifystrm volumio[4505]: info: Checking for old spotify connect plugin installed Feb 11 18:39:38 pifystrm volumio[4505]: info: [squeezelite_mc] Starting proxy server... Feb 11 18:39:38 pifystrm volumio[4505]: info: [now-playing-config] Config is up to date. Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:38 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:38 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:38 pifystrm volumio[4505]: info: Loading i18n strings for locale en Feb 11 18:39:38 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:39:38 pifystrm volumio[4505]: info: Volumio Calling Home Feb 11 18:39:38 pifystrm sudo[4542]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:39:39 pifystrm volumio[4505]: [SpotifyConnect] Creating VLS config file Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: [SpotifyConnect] Starting metadata listener Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Proxy server started on port 35221 Feb 11 18:39:39 pifystrm volumio[4505]: info: [now-playing-app] App is listening on port 4004. Feb 11 18:39:39 pifystrm sudo[4704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Feb 11 18:39:39 pifystrm sudo[4704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm sudo[4707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 18:39:39 pifystrm sudo[4704]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm sudo[4707]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm systemd[1]: Reloading. Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreStateMachine::pushState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioPushState Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Server discovery started Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Player finder started Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 11 18:39:39 pifystrm volumio[4505]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 11 18:39:39 pifystrm volumio[4505]: info: Discovery: Found device PiFyStrm Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S24_LE"] Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pifystrm","dsdFormat":"dop","card":"1","mixerType":"None","mixer":null} Feb 11 18:39:39 pifystrm volumio[4505]: 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 Feb 11 18:39:39 pifystrm volumio[4505]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 11 18:39:39 pifystrm volumio[4505]: [Metrics] SpotifyConnect: 0s 412.84ms Feb 11 18:39:39 pifystrm volumio[4505]: info: MPD Permissions set Feb 11 18:39:39 pifystrm volumio[4505]: info: MPD Permissions set Feb 11 18:39:39 pifystrm volumio[4505]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 18:39:39 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:39 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:39 pifystrm 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. Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Server discovered: {"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"} Feb 11 18:39:39 pifystrm sudo[4726]: 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 Feb 11 18:39:39 pifystrm sudo[4726]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm sudo[4726]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:39 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 18:39:39 pifystrm volumio[4505]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 11 18:39:39 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:39.610-05:00 level=INFO msg="disabling BLE discovery" Feb 11 18:39:39 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:39.610-05:00 level=INFO msg="disabling BLE discovery" Feb 11 18:39:39 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:39.619-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 18:39:39 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:39 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:39 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:39 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:39 pifystrm volumio[4505]: info: Listing playlists Feb 11 18:39:39 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 11 18:39:39 pifystrm volumio[4505]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 11 18:39:39 pifystrm sudo[4739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 11 18:39:39 pifystrm sudo[4739]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm volumio[4505]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 11 18:39:39 pifystrm volumio[4505]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Feb 11 18:39:39 pifystrm sudo[4739]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm mpd[4680]: Feb 11 18:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 18:39:39 pifystrm sudo[4707]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm systemd[1]: Started Music Player Daemon. Feb 11 18:39:39 pifystrm volumio[4505]: info: Starting Shairport Sync Feb 11 18:39:39 pifystrm sudo[4640]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm sudo[4653]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:39 pifystrm volumio[4505]: info: Starting Shairport Sync Feb 11 18:39:39 pifystrm sudo[4744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:39 pifystrm sudo[4744]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm volumio[4505]: info: Starting Shairport Sync Feb 11 18:39:39 pifystrm sudo[4747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:39 pifystrm sudo[4747]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:39 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 11 18:39:39 pifystrm systemd[1]: shairport-sync.service: Succeeded. Feb 11 18:39:39 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:39 pifystrm sudo[4750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:39:39 pifystrm sudo[4750]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:40 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:40 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:40 pifystrm volumio[4505]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 11 18:39:40 pifystrm sudo[4744]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 11 18:39:40 pifystrm systemd[1]: shairport-sync.service: Succeeded. Feb 11 18:39:40 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:40 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 18:39:40 pifystrm sudo[4755]: 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 Feb 11 18:39:40 pifystrm sudo[4755]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm sudo[4750]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm sudo[4747]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm sudo[4755]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 18:39:40 pifystrm sudo[4763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 18:39:40 pifystrm sudo[4763]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm volumio[4505]: info: MPD running with PID4680 Feb 11 18:39:40 pifystrm volumio[4505]: ,establishing connection Feb 11 18:39:40 pifystrm volumio[4505]: info: Shairport-Sync Started Feb 11 18:39:40 pifystrm volumio[4505]: Error adding Membership: Error: addMembership EINVAL Feb 11 18:39:40 pifystrm volumio[4505]: info: Shairport-Sync Started Feb 11 18:39:40 pifystrm volumio[4505]: info: Shairport-Sync Started Feb 11 18:39:40 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 11 18:39:40 pifystrm sudo[4763]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm sudo[4767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 18:39:40 pifystrm sudo[4767]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm systemd[1]: Reloading. Feb 11 18:39:40 pifystrm volumio[4505]: error: MPD error: The expression evaluated to a falsy value: Feb 11 18:39:40 pifystrm volumio[4505]: assert.ok(self.idling) Feb 11 18:39:40 pifystrm volumio[4505]: error: The expression evaluated to a falsy value: Feb 11 18:39:40 pifystrm volumio[4505]: assert.ok(self.idling) Feb 11 18:39:40 pifystrm volumio[4505]: error: MPD error: The expression evaluated to a falsy value: Feb 11 18:39:40 pifystrm volumio[4505]: assert.ok(self.idling) Feb 11 18:39:40 pifystrm volumio[4505]: error: The expression evaluated to a falsy value: Feb 11 18:39:40 pifystrm volumio[4505]: assert.ok(self.idling) Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Volumio Kiosk started Feb 11 18:39:40 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:40 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:40 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:40 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:40 pifystrm volumio[4505]: error: updateQueue error: null Feb 11 18:39:40 pifystrm sudo[4772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Feb 11 18:39:40 pifystrm sudo[4772]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm sudo[4772]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: File permissions for backlight brightness control set. Feb 11 18:39:40 pifystrm 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. Feb 11 18:39:40 pifystrm volumio[4505]: info: Volumio called home Feb 11 18:39:40 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 11 18:39:40 pifystrm sudo[4767]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm sudo[4798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm sudo[4798]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:40 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 11 18:39:40 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 11 18:39:40 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:40 pifystrm sudo[4798]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:40 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:40 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:40 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 11 18:39:40 pifystrm sudo[4821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 11 18:39:40 pifystrm sudo[4821]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 18:39:40 pifystrm volumio[4505]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 11 18:39:40 pifystrm systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Feb 11 18:39:40 pifystrm avahi-daemon[675]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 11 18:39:40 pifystrm avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:40 pifystrm dhcpcd[925]: wlan0: deleting route to 10.1.8.0/21 Feb 11 18:39:40 pifystrm avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 11 18:39:40 pifystrm dhcpcd[925]: wlan0: deleting default route via 10.1.10.1 Feb 11 18:39:40 pifystrm volumio[4505]: info: Discovery: A device disappeared from network Feb 11 18:39:40 pifystrm volumio[4505]: info: Discovery: Device pifystrm disappeared from network Feb 11 18:39:41 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:41 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:41 pifystrm systemd[1]: squeezelite.service: Succeeded. Feb 11 18:39:41 pifystrm systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Feb 11 18:39:41 pifystrm sudo[4821]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:41 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:41 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:41 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:41 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:41 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:41.526-05:00 level=INFO msg="enabling BLE discovery" Feb 11 18:39:41 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:41 pifystrm volumio[4505]: info: [squeezelite_mc] Notification listener started Feb 11 18:39:41 pifystrm volumio[4505]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Feb 11 18:39:41 pifystrm volumio[4505]: error: [squeezelite_mc] Failed to get players on server Gamez-PD (10.1.14.16): request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) FetchError: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) Feb 11 18:39:41 pifystrm volumio[4505]: at ClientRequest. (/data/plugins/music_service/squeezelite_mc/node_modules/node-fetch/lib/index.js:1491:11) Feb 11 18:39:41 pifystrm volumio[4505]: at ClientRequest.emit (events.js:315:20) Feb 11 18:39:41 pifystrm volumio[4505]: at Socket.socketErrorListener (_http_client.js:469:9) Feb 11 18:39:41 pifystrm volumio[4505]: at Socket.emit (events.js:315:20) Feb 11 18:39:41 pifystrm volumio[4505]: at emitErrorNT (internal/streams/destroy.js:106:8) Feb 11 18:39:41 pifystrm volumio[4505]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Feb 11 18:39:41 pifystrm volumio[4505]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 11 18:39:41 pifystrm volumio[4505]: error: [squeezelite_mc] An error occurred while processing discovered server: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) FetchError: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) Feb 11 18:39:41 pifystrm volumio[4505]: at ClientRequest. (/data/plugins/music_service/squeezelite_mc/node_modules/node-fetch/lib/index.js:1491:11) Feb 11 18:39:41 pifystrm volumio[4505]: at ClientRequest.emit (events.js:315:20) Feb 11 18:39:41 pifystrm volumio[4505]: at Socket.socketErrorListener (_http_client.js:469:9) Feb 11 18:39:41 pifystrm volumio[4505]: at Socket.emit (events.js:315:20) Feb 11 18:39:41 pifystrm volumio[4505]: at emitErrorNT (internal/streams/destroy.js:106:8) Feb 11 18:39:41 pifystrm volumio[4505]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Feb 11 18:39:41 pifystrm volumio[4505]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 11 18:39:41 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:41 pifystrm sudo[4840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:41 pifystrm sudo[4840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:41 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:41 pifystrm sudo[4840]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:41 pifystrm volumio[4505]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 11 18:39:41 pifystrm sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 11 18:39:41 pifystrm sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:41 pifystrm sudo[4844]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:41 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 11 18:39:41 pifystrm sudo[4847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 11 18:39:41 pifystrm sudo[4847]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:42 pifystrm systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 11 18:39:42 pifystrm systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 11 18:39:42 pifystrm sudo[4847]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:42 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:42 pifystrm ntpd[769]: Deleting interface #7 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=6 secs Feb 11 18:39:42 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:42 pifystrm sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:42 pifystrm sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:42 pifystrm sudo[4854]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:42 pifystrm sudo[4857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:42 pifystrm sudo[4857]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:43 pifystrm sudo[4857]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:43 pifystrm volumio[4505]: info: Cannot mount NAS Curaudio at system boot, trial number 2 ,retrying in 5 seconds Feb 11 18:39:43 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:43 pifystrm sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:43 pifystrm sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:43 pifystrm sudo[4861]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:43 pifystrm volumio[4505]: info: Checking Spotify Web API Feb 11 18:39:43 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:43 pifystrm sudo[4864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:43 pifystrm sudo[4864]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:43 pifystrm sudo[4864]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:44 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:44 pifystrm sudo[4867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:44 pifystrm sudo[4867]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:44 pifystrm sudo[4867]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:44 pifystrm ntpd[769]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 11 18:39:44 pifystrm volumio[4505]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 11 18:39:44 pifystrm sudo[4870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 11 18:39:44 pifystrm sudo[4870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:44 pifystrm sudo[4870]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:44 pifystrm volumio[4505]: info: Completed starting Core Plugins Feb 11 18:39:44 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:44 pifystrm volumio[4505]: info: ----- MyVolumio plugins startup ---- Feb 11 18:39:44 pifystrm volumio[4505]: info: ------------------------------------------- Feb 11 18:39:44 pifystrm volumio[4505]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 18:39:45 pifystrm sudo[4887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:39:45 pifystrm sudo[4887]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:45 pifystrm sudo[4887]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:45 pifystrm sudo[4890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:39:45 pifystrm sudo[4890]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:45 pifystrm sudo[4890]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:45 pifystrm sudo[4893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 18:39:45 pifystrm sudo[4893]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:45 pifystrm sudo[4893]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:45 pifystrm volumio[4505]: info: Upmpdcli Daemon Started Feb 11 18:39:45 pifystrm volumio[4505]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 18:39:45 pifystrm ntpd[769]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 11 18:39:46 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:99:a1 Feb 11 18:39:46 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:46 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:99:a1 completed [id=1 id_str=] Feb 11 18:39:46 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:46 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:46 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:46 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:46 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:46 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:46 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:46 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:46 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:47 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:48 pifystrm sudo[4917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:48 pifystrm sudo[4917]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:48 pifystrm sudo[4917]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:48 pifystrm volumio[4505]: info: Cannot mount NAS Curaudio at system boot, trial number 3 ,retrying in 5 seconds Feb 11 18:39:48 pifystrm ntpd[769]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 11 18:39:48 pifystrm ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 11 18:39:51 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:39:51 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:51 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:39:51 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:39:51 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:39:51 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:39:51 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:51 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:51 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:51 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:51 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:51.824-05:00 level=INFO msg="disabling BLE discovery" Feb 11 18:39:51 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:51.827-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 11 18:39:52 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:52 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 11 18:39:52 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:52 pifystrm avahi-daemon[675]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 11 18:39:52 pifystrm avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: deleting route to 10.1.8.0/21 Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: deleting default route via 10.1.10.1 Feb 11 18:39:52 pifystrm avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 11 18:39:52 pifystrm volumio[4505]: info: Discovery: A device disappeared from network Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:52 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:52 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:52 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:52 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:52 pifystrm volumio5-onboarding[1436]: time=2026-02-11T18:39:52.810-05:00 level=INFO msg="enabling BLE discovery" Feb 11 18:39:52 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:53 pifystrm sudo[4993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:53 pifystrm sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:53 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:39:53 pifystrm sudo[4993]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:53 pifystrm volumio[4505]: info: Cannot mount NAS Curaudio at system boot, trial number 4 ,retrying in 5 seconds Feb 11 18:39:53 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:57 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:39:57 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:99:a1 Feb 11 18:39:57 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:99:a1 completed [id=1 id_str=] Feb 11 18:39:57 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:39:57 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:39:57 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:39:58 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:39:58 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:39:58 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:39:58 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:39:58 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:39:58 pifystrm sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 11 18:39:58 pifystrm sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:39:58 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:39:58 pifystrm sudo[5032]: pam_unix(sudo:session): session closed for user root Feb 11 18:39:58 pifystrm volumio[4505]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 11 18:39:58 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:40:02 pifystrm volumio[4505]: info: Discovery: Browse raised the following error Error: getaddrinfo -3008 Feb 11 18:40:02 pifystrm volumio[4505]: info: Discovery: Restarting Browsing Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:40:03 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:40:03 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:40:03 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:40:03 pifystrm wpa_supplicant[913]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 11 18:40:03 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 11 18:40:03 pifystrm wpa_supplicant[913]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: carrier lost Feb 11 18:40:03 pifystrm wpa_supplicant[913]: RRM: Ignoring radio measurement request: Not RRM network Feb 11 18:40:03 pifystrm avahi-daemon[675]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 11 18:40:03 pifystrm avahi-daemon[675]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:40:03 pifystrm avahi-daemon[675]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: deleting route to 10.1.8.0/21 Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: deleting default route via 10.1.10.1 Feb 11 18:40:03 pifystrm volumio[4505]: info: Discovery: A device disappeared from network Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: carrier acquired Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: IAID dd:0d:07:df Feb 11 18:40:03 pifystrm dhcpcd[925]: wlan0: rebinding lease of 10.1.12.92 Feb 11 18:40:04 pifystrm volumio[4505]: info: Received Get System Info Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:40:04 pifystrm volumio[4505]: info: Discovery: Getting this device information Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::volumioGetState Feb 11 18:40:04 pifystrm volumio[4505]: info: CorePlayQueue::getTrack 0 Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:40:04 pifystrm dhcpcd[925]: wlan0: soliciting an IPv6 router Feb 11 18:40:04 pifystrm dhcpcd[925]: wlan0: probing address 10.1.12.92/21 Feb 11 18:40:04 pifystrm volumio[4505]: error: MyVolumio Plugin failed to start in a timely fashion Feb 11 18:40:04 pifystrm volumio[4505]: [Metrics] CommandRouter: 31s 84.05ms Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:40:04 pifystrm volumio[4505]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:40:05 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 18:40:05 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 18:40:05 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 18:40:08 pifystrm volumio[4505]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 11 18:40:08 pifystrm volumio[4505]: info: Discovery: Restarting Browsing Feb 11 18:40:08 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:40:08 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:40:08 pifystrm volumio[4505]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:40:09 pifystrm volumio[4505]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:40:09 pifystrm volumio[4505]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 11 18:40:09 pifystrm volumio[4505]: at doSend (dgram.js:692:16) Feb 11 18:40:09 pifystrm volumio[4505]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 11 18:40:09 pifystrm volumio[4505]: at afterDns (dgram.js:638:5) Feb 11 18:40:09 pifystrm volumio[4505]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 11 18:40:09 pifystrm volumio[4505]: errno: -101, Feb 11 18:40:09 pifystrm volumio[4505]: code: 'ENETUNREACH', Feb 11 18:40:09 pifystrm volumio[4505]: syscall: 'send', Feb 11 18:40:09 pifystrm volumio[4505]: address: '255.255.255.255', Feb 11 18:40:09 pifystrm volumio[4505]: port: 3483 Feb 11 18:40:09 pifystrm volumio[4505]: } Feb 11 18:40:09 pifystrm volumio[4505]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:40:09 pifystrm dhcpcd[925]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 11 18:40:09 pifystrm avahi-daemon[675]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 11 18:40:09 pifystrm avahi-daemon[675]: New relevant interface wlan0.IPv4 for mDNS. Feb 11 18:40:09 pifystrm dhcpcd[925]: wlan0: adding route to 10.1.8.0/21 Feb 11 18:40:09 pifystrm dhcpcd[925]: wlan0: adding default route via 10.1.10.1 Feb 11 18:40:09 pifystrm avahi-daemon[675]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 11 18:40:09 pifystrm sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 18:39 Feb 11 18:40:09 pifystrm sudo[5139]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"