-- Logs begin at Tue 2025-12-16 14:56:10 EST, end at Tue 2025-12-16 15:01:21 EST. -- Dec 16 15:00:00 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:00 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:00 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:02 pifystrm ntpd[782]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 16 15:00:04 pifystrm volumio[3833]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 16 15:00:04 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:04 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:00:04 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:00:04 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:04 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:04 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:05 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:05 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:05 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:09 pifystrm sudo[4302]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:09 pifystrm volumio[3833]: info: Cannot mount NAS Curaudio at system boot, trial number 4 ,retrying in 5 seconds Dec 16 15:00:10 pifystrm volumio[3833]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 15:00:10 pifystrm volumio[3833]: [Metrics] CommandRouter: 30s 976.80ms Dec 16 15:00:10 pifystrm volumio[3833]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 15:00:10 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:10 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:10 pifystrm volumio[3833]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:00:10 pifystrm volumio[3833]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:00:10 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:10 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:00:10 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:00:10 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:11 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:11 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:11 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:11 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 15:00:11 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 15:00:11 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 15:00:11 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:12 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:14 pifystrm sudo[4446]: 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 Dec 16 15:00:14 pifystrm sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:14 pifystrm sudo[4446]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:14 pifystrm volumio[3833]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 16 15:00:14 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:14 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:14 pifystrm volumio[3833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:00:15 pifystrm volumio[3833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:15 pifystrm volumio[3833]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 15:00:15 pifystrm volumio[3833]: at doSend (dgram.js:692:16) Dec 16 15:00:15 pifystrm volumio[3833]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 15:00:15 pifystrm volumio[3833]: at afterDns (dgram.js:638:5) Dec 16 15:00:15 pifystrm volumio[3833]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 15:00:15 pifystrm volumio[3833]: errno: -101, Dec 16 15:00:15 pifystrm volumio[3833]: code: 'ENETUNREACH', Dec 16 15:00:15 pifystrm volumio[3833]: syscall: 'send', Dec 16 15:00:15 pifystrm volumio[3833]: address: '255.255.255.255', Dec 16 15:00:15 pifystrm volumio[3833]: port: 3483 Dec 16 15:00:15 pifystrm volumio[3833]: } Dec 16 15:00:15 pifystrm volumio[3833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:15 pifystrm sudo[4460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:59 Dec 16 15:00:15 pifystrm sudo[4460]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:15 pifystrm sudo[4460]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:15 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 15:00:15 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 15:00:15 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:00:15 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 15:00:15 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:00:15 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:00:15 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 15:00:15 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 16 15:00:15 pifystrm systemd[1]: Stopped Volumio Backend Module. Dec 16 15:00:15 pifystrm systemd[1]: Started Volumio Backend Module. Dec 16 15:00:15 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:00:15 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:00:16 pifystrm volumio[4473]: info: ------------------------------------------- Dec 16 15:00:16 pifystrm volumio[4473]: info: ----- Volumio3 ---- Dec 16 15:00:16 pifystrm volumio[4473]: info: ------------------------------------------- Dec 16 15:00:16 pifystrm volumio[4473]: info: ----- System startup ---- Dec 16 15:00:16 pifystrm volumio[4473]: info: ------------------------------------------- Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:00:17 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:17 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:00:17 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:17 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:00:17 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:00:17 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:17 pifystrm volumio[4473]: info: MYVOLUMIO Environment detected Dec 16 15:00:17 pifystrm volumio[4473]: info: Plugin folders cleanup Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning into folder /volumio/app/plugins/ Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category audio_interface Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category miscellanea Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category music_service Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category plugins.json Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category system_controller Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category user_interface Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning into folder /data/plugins/ Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category music_service Dec 16 15:00:17 pifystrm volumio[4473]: info: Scanning category user_interface Dec 16 15:00:17 pifystrm volumio[4473]: info: Plugin folders cleanup completed Dec 16 15:00:17 pifystrm volumio[4473]: info: ------------------------------------------- Dec 16 15:00:17 pifystrm volumio[4473]: info: ----- Core plugins startup ---- Dec 16 15:00:17 pifystrm volumio[4473]: info: ------------------------------------------- Dec 16 15:00:17 pifystrm volumio[4473]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 15:00:17 pifystrm volumio[4473]: info: Adding plugin upnp to MyMusic Plugins Dec 16 15:00:17 pifystrm volumio[4473]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 15:00:17 pifystrm volumio[4473]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 15:00:17 pifystrm volumio[4473]: info: Loading plugins from folder /data/plugins/ Dec 16 15:00:17 pifystrm avahi-daemon[621]: Withdrawing address record for 10.1.12.92 on wlan0. Dec 16 15:00:17 pifystrm avahi-daemon[621]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:17 pifystrm volumio[4473]: info: Loading plugin "system"... Dec 16 15:00:17 pifystrm avahi-daemon[621]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: deleting route to 10.1.8.0/21 Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: deleting default route via 10.1.10.1 Dec 16 15:00:17 pifystrm volumio[4473]: info: Loading plugin "appearance"... Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:17 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "network"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Refreshing Cached IP Addresses Dec 16 15:00:18 pifystrm sudo[4555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:00:18 pifystrm sudo[4555]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:18 pifystrm sudo[4555]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:18 pifystrm sudo[4557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:00:18 pifystrm sudo[4557]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:18 pifystrm sudo[4557]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "services"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "alsa_controller"... Dec 16 15:00:18 pifystrm sudo[4567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 15:00:18 pifystrm sudo[4567]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:18 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "wizard"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "networkfs"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Starting Udev Watcher for removable devices Dec 16 15:00:18 pifystrm sudo[4581]: 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 Dec 16 15:00:18 pifystrm sudo[4581]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:18 pifystrm volumio[4473]: info: Ignoring mount for partition: boot Dec 16 15:00:18 pifystrm volumio[4473]: info: Ignoring mount for partition: volumio Dec 16 15:00:18 pifystrm volumio[4473]: info: Ignoring mount for partition: volumio_data Dec 16 15:00:18 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "volumio_command_line_client"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "upnp"... Dec 16 15:00:18 pifystrm volumio[4473]: info: [1765915218242] Starting Upmpd Daemon Dec 16 15:00:18 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "my_music"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "mpd"... Dec 16 15:00:18 pifystrm sudo[4581]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "upnp_browser"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "alarm-clock"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "airplay_emulation"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Starting Shairport Sync Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "last_100"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "webradio"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "i2s_dacs"... Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "volumiodiscovery"... Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** For more information see Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:18 pifystrm volumio[4473]: *** WARNING *** For more information see Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** For more information see Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:18 pifystrm node[4473]: *** WARNING *** For more information see Dec 16 15:00:18 pifystrm volumio[4473]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 15:00:18 pifystrm volumio[4473]: info: Discovery: Started advertising with name: PiFyStrm Dec 16 15:00:18 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:18 pifystrm volumio[4473]: info: Loading plugin "spop"... Dec 16 15:00:19 pifystrm volumio[4473]: info: Loading plugin "squeezelite_mc"... Dec 16 15:00:19 pifystrm volumio[4473]: info: Loading plugin "now_playing"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "outputs"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "albumart"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Plugin example_plugin is not enabled Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "inputs"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "updater_comm"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Plugin mpdemulation is not enabled Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "rest_api"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "websocket"... Dec 16 15:00:20 pifystrm volumio[4473]: info: Starting Socket.io Server version 2.3.0 Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading plugin "touch_display"... Dec 16 15:00:20 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:20] [connect] Successful connection Dec 16 15:00:20 pifystrm volumio[4473]: Forking 3 albumart workers Dec 16 15:00:20 pifystrm volumio[4473]: info: Applying required configuration parameters for plugin touch_display Dec 16 15:00:20 pifystrm volumio[4473]: info: Loading i18n strings for locale en Dec 16 15:00:20 pifystrm volumio[4473]: Updating browse sources language Dec 16 15:00:20 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::initPlayerControls Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: Express server listening on port 3000 Dec 16 15:00:21 pifystrm volumio[4473]: [Metrics] WebUI: 4s 458.33ms Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::resetVolumioState Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::getcurrentVolume Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::pushState Dec 16 15:00:21 pifystrm volumio[4473]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:21 pifystrm volumio[4473]: info: Volumio Network Manager: Network status updated: 0 Dec 16 15:00:21 pifystrm volumio[4473]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Dec 16 15:00:21 pifystrm volumio[4473]: 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 Dec 16 15:00:21 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765915220 101 Dec 16 15:00:21 pifystrm volumio[4473]: 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 Dec 16 15:00:21 pifystrm volumio[4473]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: Reloading queue from file Dec 16 15:00:21 pifystrm volumio[4473]: Starting albumart workers Dec 16 15:00:21 pifystrm volumio[4473]: Starting albumart workers Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::setRepeat false single undefined Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::pushState Dec 16 15:00:21 pifystrm volumio[4473]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::setRandom false Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreStateMachine::pushState Dec 16 15:00:21 pifystrm volumio[4473]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:21 pifystrm volumio[4473]: info: Setting Device type: Raspberry PI Dec 16 15:00:21 pifystrm volumio[4473]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 16 15:00:21 pifystrm volumio[4473]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 16 15:00:21 pifystrm volumio[4473]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 16 15:00:21 pifystrm volumio[4473]: info: Completed loading Core Plugins Dec 16 15:00:21 pifystrm volumio[4473]: info: Preparing to generate the ALSA configuration file Dec 16 15:00:21 pifystrm volumio[4473]: Starting albumart workers Dec 16 15:00:21 pifystrm volumio[4473]: info: Asound.conf file unchanged, so no further update is needed Dec 16 15:00:21 pifystrm volumio[4473]: info: Output device has changed, restarting MPD Dec 16 15:00:21 pifystrm volumio[4473]: info: Output device has changed, restarting Shairport Sync Dec 16 15:00:21 pifystrm sudo[4622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:00:21 pifystrm sudo[4622]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:21 pifystrm sudo[4622]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:21 pifystrm sudo[4624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:00:21 pifystrm sudo[4624]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:21 pifystrm systemd[1]: Stopping Music Player Daemon... Dec 16 15:00:21 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:00:21 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:00:21 pifystrm volumio[4473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:21 pifystrm volumio[4473]: info: ___________ START PLUGINS ___________ Dec 16 15:00:21 pifystrm sudo[4632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:00:21 pifystrm volumio[4473]: info: ControllerMpd::onStart: Initializing MPD Dec 16 15:00:21 pifystrm volumio[4473]: info: Creating MPD Configuration file Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:21 pifystrm volumio[4473]: info: [1765915221557] CoreMusicLibrary::Adding element Media Servers Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:21 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:00:21 pifystrm sudo[4632]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:21 pifystrm sudo[4632]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:21 pifystrm sudo[4635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:00:21 pifystrm sudo[4635]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:21 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 15:00:21 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:00:21 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:00:21 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:00:21 pifystrm volumio[4473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:21 pifystrm volumio[4473]: info: [1765915221693] CoreMusicLibrary::Adding element Last_100 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:21 pifystrm volumio[4473]: info: [1765915221697] CoreMusicLibrary::Adding element Webradio Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:21 pifystrm sudo[4640]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:00:21 pifystrm sudo[4640]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:21 pifystrm volumio[4473]: info: Initializing BBC Radios Dec 16 15:00:21 pifystrm sudo[4640]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:21 pifystrm volumio[4473]: info: Checking for old spotify connect plugin installed Dec 16 15:00:21 pifystrm volumio[4473]: info: [squeezelite_mc] Starting proxy server... Dec 16 15:00:21 pifystrm volumio[4473]: info: [now-playing-config] Config is up to date. Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:00:21 pifystrm volumio[4473]: info: Discovery: Getting this device information Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:21 pifystrm volumio[4473]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:00:21 pifystrm volumio[4473]: info: Loading i18n strings for locale en Dec 16 15:00:21 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:21 pifystrm volumio[4473]: info: Volumio Calling Home Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:22 pifystrm volumio[4473]: [SpotifyConnect] Creating VLS config file Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:22 pifystrm volumio[4473]: [SpotifyConnect] Starting metadata listener Dec 16 15:00:22 pifystrm volumio[4473]: info: [squeezelite_mc] Proxy server started on port 46243 Dec 16 15:00:22 pifystrm volumio[4473]: info: [now-playing-app] App is listening on port 4004. Dec 16 15:00:22 pifystrm sudo[4684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 16 15:00:22 pifystrm sudo[4684]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:22 pifystrm sudo[4684]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm sudo[4687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:00:22 pifystrm sudo[4687]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:22 pifystrm systemd[1]: Reloading. Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreStateMachine::pushState Dec 16 15:00:22 pifystrm volumio[4473]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:22 pifystrm volumio[4473]: info: [squeezelite_mc] Server discovery started Dec 16 15:00:22 pifystrm volumio[4473]: info: [squeezelite_mc] Player finder started Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:22 pifystrm volumio[4473]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:22 pifystrm volumio[4473]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 15:00:22 pifystrm volumio[4473]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:22 pifystrm sudo[4567]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm volumio[4473]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 15:00:22 pifystrm volumio[4473]: at doSend (dgram.js:692:16) Dec 16 15:00:22 pifystrm volumio[4473]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 15:00:22 pifystrm volumio[4473]: at afterDns (dgram.js:638:5) Dec 16 15:00:22 pifystrm volumio[4473]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 15:00:22 pifystrm volumio[4473]: errno: -101, Dec 16 15:00:22 pifystrm volumio[4473]: code: 'ENETUNREACH', Dec 16 15:00:22 pifystrm volumio[4473]: syscall: 'send', Dec 16 15:00:22 pifystrm volumio[4473]: address: '255.255.255.255', Dec 16 15:00:22 pifystrm volumio[4473]: port: 3483 Dec 16 15:00:22 pifystrm volumio[4473]: } Dec 16 15:00:22 pifystrm volumio[4473]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:22 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. Dec 16 15:00:22 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:00:22 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:22 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:00:22 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:22 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. Dec 16 15:00:22 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:22 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:22 pifystrm mpd[4645]: Dec 16 15:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:00:22 pifystrm sudo[4736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:59 Dec 16 15:00:22 pifystrm sudo[4736]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:22 pifystrm sudo[4736]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm systemd[1]: Started Music Player Daemon. Dec 16 15:00:22 pifystrm sudo[4635]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm sudo[4687]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm sudo[4624]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:22 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 15:00:22 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 15:00:22 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:00:22 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 15:00:22 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:00:22 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:00:23 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 15:00:23 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 16 15:00:23 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:23 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:00:23 pifystrm systemd[1]: Stopped Volumio Backend Module. Dec 16 15:00:23 pifystrm systemd[1]: Started Volumio Backend Module. Dec 16 15:00:23 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:00:23 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:23 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:24 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:24 pifystrm volumio[4750]: info: ----- Volumio3 ---- Dec 16 15:00:24 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:24 pifystrm volumio[4750]: info: ----- System startup ---- Dec 16 15:00:24 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:24 pifystrm volumio[4750]: info: MYVOLUMIO Environment detected Dec 16 15:00:24 pifystrm volumio[4750]: info: Plugin folders cleanup Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning into folder /volumio/app/plugins/ Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category audio_interface Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category miscellanea Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category music_service Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category plugins.json Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category system_controller Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category user_interface Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning into folder /data/plugins/ Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category music_service Dec 16 15:00:24 pifystrm volumio[4750]: info: Scanning category user_interface Dec 16 15:00:24 pifystrm volumio[4750]: info: Plugin folders cleanup completed Dec 16 15:00:24 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:24 pifystrm volumio[4750]: info: ----- Core plugins startup ---- Dec 16 15:00:24 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:24 pifystrm volumio[4750]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 15:00:24 pifystrm volumio[4750]: info: Adding plugin upnp to MyMusic Plugins Dec 16 15:00:24 pifystrm volumio[4750]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 15:00:24 pifystrm volumio[4750]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 15:00:24 pifystrm volumio[4750]: info: Loading plugins from folder /data/plugins/ Dec 16 15:00:24 pifystrm volumio[4750]: info: Loading plugin "system"... Dec 16 15:00:24 pifystrm volumio[4750]: info: Loading plugin "appearance"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "network"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Refreshing Cached IP Addresses Dec 16 15:00:25 pifystrm sudo[4777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:00:25 pifystrm sudo[4777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:25 pifystrm sudo[4777]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:25 pifystrm sudo[4779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:00:25 pifystrm sudo[4779]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:25 pifystrm sudo[4779]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "services"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "alsa_controller"... Dec 16 15:00:25 pifystrm sudo[4789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 15:00:25 pifystrm sudo[4789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:25 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "wizard"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "networkfs"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Starting Udev Watcher for removable devices Dec 16 15:00:25 pifystrm sudo[4803]: 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 Dec 16 15:00:25 pifystrm sudo[4803]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:25 pifystrm volumio[4750]: info: Ignoring mount for partition: boot Dec 16 15:00:25 pifystrm volumio[4750]: info: Ignoring mount for partition: volumio Dec 16 15:00:25 pifystrm volumio[4750]: info: Ignoring mount for partition: volumio_data Dec 16 15:00:25 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "volumio_command_line_client"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "upnp"... Dec 16 15:00:25 pifystrm volumio[4750]: info: [1765915225384] Starting Upmpd Daemon Dec 16 15:00:25 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "my_music"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "mpd"... Dec 16 15:00:25 pifystrm sudo[4803]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "upnp_browser"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "alarm-clock"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "airplay_emulation"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Starting Shairport Sync Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "last_100"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "webradio"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "i2s_dacs"... Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "volumiodiscovery"... Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** For more information see Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:25 pifystrm volumio[4750]: *** WARNING *** For more information see Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** For more information see Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:00:25 pifystrm node[4750]: *** WARNING *** For more information see Dec 16 15:00:25 pifystrm volumio[4750]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 15:00:25 pifystrm volumio[4750]: info: Discovery: Started advertising with name: PiFyStrm Dec 16 15:00:25 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:25 pifystrm volumio[4750]: info: Loading plugin "spop"... Dec 16 15:00:26 pifystrm volumio[4750]: info: Loading plugin "squeezelite_mc"... Dec 16 15:00:26 pifystrm volumio[4750]: info: Loading plugin "now_playing"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "outputs"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "albumart"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Plugin example_plugin is not enabled Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "inputs"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "updater_comm"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Plugin mpdemulation is not enabled Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "rest_api"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "websocket"... Dec 16 15:00:27 pifystrm volumio[4750]: info: Starting Socket.io Server version 2.3.0 Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading plugin "touch_display"... Dec 16 15:00:27 pifystrm volumio[4750]: Forking 3 albumart workers Dec 16 15:00:27 pifystrm volumio[4750]: info: Applying required configuration parameters for plugin touch_display Dec 16 15:00:27 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:27] [connect] Successful connection Dec 16 15:00:27 pifystrm volumio[4750]: info: Loading i18n strings for locale en Dec 16 15:00:27 pifystrm volumio[4750]: Updating browse sources language Dec 16 15:00:27 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::initPlayerControls Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: Express server listening on port 3000 Dec 16 15:00:28 pifystrm volumio[4750]: [Metrics] WebUI: 4s 340.45ms Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::resetVolumioState Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::getcurrentVolume Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::pushState Dec 16 15:00:28 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:28 pifystrm volumio[4750]: info: Volumio Network Manager: Network status updated: 0 Dec 16 15:00:28 pifystrm volumio[4750]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Dec 16 15:00:28 pifystrm volumio[4750]: 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 Dec 16 15:00:28 pifystrm volumio-remote-updater[612]: [2025-12-16 15:00:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765915227 101 Dec 16 15:00:28 pifystrm volumio[4750]: 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 Dec 16 15:00:28 pifystrm volumio[4750]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 16 15:00:28 pifystrm volumio[4750]: Starting albumart workers Dec 16 15:00:28 pifystrm volumio[4750]: Starting albumart workers Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: Reloading queue from file Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::setRepeat false single undefined Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::pushState Dec 16 15:00:28 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:28 pifystrm volumio[4750]: Starting albumart workers Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::setRandom false Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreStateMachine::pushState Dec 16 15:00:28 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:28 pifystrm volumio[4750]: info: Setting Device type: Raspberry PI Dec 16 15:00:28 pifystrm volumio[4750]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 16 15:00:28 pifystrm volumio[4750]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 16 15:00:28 pifystrm volumio[4750]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 16 15:00:28 pifystrm volumio[4750]: info: Completed loading Core Plugins Dec 16 15:00:28 pifystrm volumio[4750]: info: Preparing to generate the ALSA configuration file Dec 16 15:00:28 pifystrm volumio[4750]: info: Asound.conf file unchanged, so no further update is needed Dec 16 15:00:28 pifystrm volumio[4750]: info: Output device has changed, restarting MPD Dec 16 15:00:28 pifystrm sudo[4844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:00:28 pifystrm sudo[4844]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:28 pifystrm sudo[4844]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:28 pifystrm volumio[4750]: info: Output device has changed, restarting Shairport Sync Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:28 pifystrm sudo[4847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:00:28 pifystrm sudo[4847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:28 pifystrm systemd[1]: Stopping Music Player Daemon... Dec 16 15:00:28 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:00:28 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:00:28 pifystrm volumio[4750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:28 pifystrm volumio[4750]: info: ___________ START PLUGINS ___________ Dec 16 15:00:28 pifystrm volumio[4750]: info: ControllerMpd::onStart: Initializing MPD Dec 16 15:00:28 pifystrm volumio[4750]: info: Creating MPD Configuration file Dec 16 15:00:28 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:00:28 pifystrm sudo[4854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:00:28 pifystrm sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:28 pifystrm sudo[4854]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:28 pifystrm sudo[4852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:00:28 pifystrm sudo[4852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:28 pifystrm volumio[4750]: info: [1765915228815] CoreMusicLibrary::Adding element Media Servers Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:28 pifystrm sudo[4852]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:28 pifystrm sudo[4862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:00:28 pifystrm sudo[4862]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:28 pifystrm systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 16 15:00:28 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:00:28 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:00:28 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:00:28 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:28 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:00:28 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:00:28 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:00:28 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:00:28 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:00:28 pifystrm volumio[4750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:28 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:29 pifystrm volumio[4750]: info: [1765915229059] CoreMusicLibrary::Adding element Last_100 Dec 16 15:00:29 pifystrm sudo[4878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:29 pifystrm sudo[4878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:00:29 pifystrm volumio[4750]: info: [1765915229062] CoreMusicLibrary::Adding element Webradio Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:29 pifystrm sudo[4878]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:29 pifystrm volumio[4750]: info: Initializing BBC Radios Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: Checking for old spotify connect plugin installed Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Starting proxy server... Dec 16 15:00:29 pifystrm volumio[4750]: info: [now-playing-config] Config is up to date. Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:00:29 pifystrm volumio[4750]: info: Discovery: Getting this device information Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:29 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:00:29 pifystrm volumio[4750]: info: Loading i18n strings for locale en Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:29 pifystrm volumio[4750]: info: Volumio Calling Home Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:00:29 pifystrm volumio[4750]: [SpotifyConnect] Creating VLS config file Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: [SpotifyConnect] Starting metadata listener Dec 16 15:00:29 pifystrm sudo[4789]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Proxy server started on port 43477 Dec 16 15:00:29 pifystrm volumio[4750]: info: [now-playing-app] App is listening on port 4004. Dec 16 15:00:29 pifystrm sudo[4932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 16 15:00:29 pifystrm sudo[4932]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:29 pifystrm sudo[4932]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:29 pifystrm sudo[4935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:00:29 pifystrm sudo[4935]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:29 pifystrm systemd[1]: Reloading. Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreStateMachine::pushState Dec 16 15:00:29 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::volumioPushState Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Server discovery started Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Player finder started Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 15:00:29 pifystrm volumio[4750]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 15:00:29 pifystrm volumio[4750]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 15:00:29 pifystrm volumio[4750]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 15:00:29 pifystrm volumio[4750]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 15:00:29 pifystrm volumio[4750]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 15:00:29 pifystrm volumio[4750]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 15:00:29 pifystrm volumio[4750]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 15:00:29 pifystrm volumio[4750]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 15:00:29 pifystrm volumio[4750]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 15:00:29 pifystrm volumio[4750]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 15:00:29 pifystrm volumio[4750]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 15:00:29 pifystrm volumio[4750]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 15:00:29 pifystrm volumio[4750]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 15:00:29 pifystrm volumio[4750]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 15:00:29 pifystrm volumio[4750]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 15:00:29 pifystrm volumio[4750]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 15:00:29 pifystrm volumio[4750]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S24_LE"] Dec 16 15:00:29 pifystrm volumio[4750]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pifystrm","dsdFormat":"dop","card":"1","mixerType":"None","mixer":null} Dec 16 15:00:29 pifystrm volumio[4750]: 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 Dec 16 15:00:29 pifystrm volumio[4750]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 16 15:00:29 pifystrm volumio[4750]: [Metrics] SpotifyConnect: 0s 446.29ms Dec 16 15:00:29 pifystrm volumio[4750]: info: MPD Permissions set Dec 16 15:00:29 pifystrm volumio[4750]: info: MPD Permissions set Dec 16 15:00:29 pifystrm volumio[4750]: 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"} Dec 16 15:00:29 pifystrm sudo[4965]: 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 Dec 16 15:00:29 pifystrm sudo[4965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:29 pifystrm volumio[4750]: info: Volumio called home Dec 16 15:00:29 pifystrm sudo[4965]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:29 pifystrm volumio[4750]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 16 15:00:29 pifystrm volumio[4750]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 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. Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:29 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:30 pifystrm volumio[4750]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 16 15:00:30 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. Dec 16 15:00:30 pifystrm sudo[4978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 16 15:00:30 pifystrm sudo[4978]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: Raspberry Pi Foundation touch screen detected. Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Dec 16 15:00:30 pifystrm volumio[4750]: info: Starting Shairport Sync Dec 16 15:00:30 pifystrm mpd[4917]: Dec 16 15:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:00:30 pifystrm sudo[4978]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm volumio[4750]: info: Starting Shairport Sync Dec 16 15:00:30 pifystrm sudo[4984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:00:30 pifystrm sudo[4984]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:00:30 pifystrm sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm sudo[4989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 16 15:00:30 pifystrm sudo[4989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:30 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:30 pifystrm volumio[4750]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Dec 16 15:00:30 pifystrm volumio[4750]: info: Discovery: Found device PiFyStrm Dec 16 15:00:30 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:30 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:30 pifystrm volumio[4750]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 16 15:00:30 pifystrm systemd[1]: Started Music Player Daemon. Dec 16 15:00:30 pifystrm sudo[4935]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[4847]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[4862]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: systemctl daemon-reload succeeded. Dec 16 15:00:30 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 16 15:00:30 pifystrm systemd[1]: shairport-sync.service: Succeeded. Dec 16 15:00:30 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 16 15:00:30 pifystrm sudo[4996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 16 15:00:30 pifystrm sudo[4996]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm sudo[4994]: 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 Dec 16 15:00:30 pifystrm sudo[4994]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm sudo[4994]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 16 15:00:30 pifystrm sudo[4989]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[5003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Dec 16 15:00:30 pifystrm sudo[5003]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm sudo[4984]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[5003]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[4987]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm sudo[4996]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:30 pifystrm volumio[4750]: error: MPD error: The expression evaluated to a falsy value: Dec 16 15:00:30 pifystrm volumio[4750]: assert.ok(self.idling) Dec 16 15:00:30 pifystrm volumio[4750]: error: The expression evaluated to a falsy value: Dec 16 15:00:30 pifystrm volumio[4750]: assert.ok(self.idling) Dec 16 15:00:30 pifystrm volumio[4750]: error: updateQueue error: null Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: File permissions for backlight brightness control set. Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: Volumio Kiosk started Dec 16 15:00:30 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:30 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:30 pifystrm volumio[4750]: info: Shairport-Sync Started Dec 16 15:00:30 pifystrm volumio[4750]: Error adding Membership: Error: addMembership EINVAL Dec 16 15:00:30 pifystrm volumio[4750]: info: Shairport-Sync Started Dec 16 15:00:30 pifystrm volumio[4750]: info: MPD running with PID4917 Dec 16 15:00:30 pifystrm volumio[4750]: ,establishing connection Dec 16 15:00:30 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 16 15:00:30 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:30 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:30 pifystrm sudo[5010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 16 15:00:30 pifystrm sudo[5010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:30 pifystrm volumio[4750]: error: updateQueue error: null Dec 16 15:00:30 pifystrm ntpd[782]: Listen normally on 10 wlan0 10.1.12.92:123 Dec 16 15:00:30 pifystrm ntpd[782]: new interface(s) found: waking up resolver Dec 16 15:00:30 pifystrm systemd[1]: Reloading. Dec 16 15:00:30 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:30 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:30 pifystrm volumio[4750]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 16 15:00:30 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. Dec 16 15:00:30 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. Dec 16 15:00:31 pifystrm sudo[5010]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Notification listener started Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Dec 16 15:00:31 pifystrm sudo[5033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:31 pifystrm sudo[5033]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:31 pifystrm volumio[4750]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 16 15:00:31 pifystrm sudo[5033]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"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":"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":"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"}},{"id":"bb:bb:86:4c:c5:16","uuid":null,"ip":"10.1.14.16","name":"Samsung Q6DAA 50 TV","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:17:34:c5:ca","uuid":null,"ip":"10.1.14.16","name":"GoogleTV5315","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:dd:0d:07:df","uuid":null,"ip":"10.1.14.16","name":"PiFyStrm-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":"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"}}] Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 16 15:00:31 pifystrm sudo[5036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 16 15:00:31 pifystrm sudo[5036]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:31 pifystrm systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Dec 16 15:00:31 pifystrm systemd[1]: squeezelite.service: Succeeded. Dec 16 15:00:31 pifystrm systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Dec 16 15:00:31 pifystrm sudo[5036]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:31 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:31 pifystrm sudo[5044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:31 pifystrm sudo[5044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:32 pifystrm sudo[5044]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 16 15:00:32 pifystrm sudo[5048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 16 15:00:32 pifystrm sudo[5048]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:32 pifystrm sudo[5048]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 16 15:00:32 pifystrm sudo[5051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 16 15:00:32 pifystrm sudo[5051]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:32 pifystrm systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 16 15:00:32 pifystrm systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 16 15:00:32 pifystrm sudo[5051]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'connect' Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"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":"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":"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"}},{"id":"bb:bb:86:4c:c5:16","uuid":null,"ip":"10.1.14.16","name":"Samsung Q6DAA 50 TV","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:17:34:c5:ca","uuid":null,"ip":"10.1.14.16","name":"GoogleTV5315","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:dd:0d:07:df","uuid":null,"ip":"10.1.14.16","name":"PiFyStrm-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":"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":"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"}}] Dec 16 15:00:32 pifystrm volumio[4750]: 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"}} Dec 16 15:00:32 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:32 pifystrm sudo[5058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:32 pifystrm sudo[5058]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:32 pifystrm volumio[4750]: 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 Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:00:32 pifystrm sudo[5058]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:32 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:00:32 pifystrm volumio[4750]: info: Received Get System Info Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:00:32 pifystrm volumio[4750]: info: Discovery: Getting this device information Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:32 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:00:32 pifystrm volumio[4750]: info: CoreCommandRouter::volumioGetState Dec 16 15:00:32 pifystrm volumio[4750]: info: CorePlayQueue::getTrack 0 Dec 16 15:00:32 pifystrm volumio[4750]: info: Listing playlists Dec 16 15:00:33 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:33 pifystrm sudo[5061]: 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 Dec 16 15:00:33 pifystrm sudo[5061]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:33 pifystrm sudo[5064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:33 pifystrm sudo[5064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:33 pifystrm sudo[5064]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:33 pifystrm sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 15:00:33 pifystrm sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:33 pifystrm systemd[1]: Stopping MPD Monitor Service... Dec 16 15:00:33 pifystrm systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 16 15:00:33 pifystrm systemd[1]: mpd_monitor.service: Succeeded. Dec 16 15:00:33 pifystrm systemd[1]: Stopped MPD Monitor Service. Dec 16 15:00:33 pifystrm sudo[5071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 15:00:33 pifystrm sudo[5071]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:33 pifystrm volumio[4750]: (node:4750) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Dec 16 15:00:33 pifystrm volumio[4750]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Dec 16 15:00:33 pifystrm volumio[4750]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Dec 16 15:00:33 pifystrm volumio[4750]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 16 15:00:33 pifystrm volumio[4750]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Dec 16 15:00:33 pifystrm volumio[4750]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Dec 16 15:00:33 pifystrm volumio[4750]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 16 15:00:33 pifystrm volumio[4750]: (node:4750) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Dec 16 15:00:33 pifystrm volumio[4750]: (node:4750) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 16 15:00:33 pifystrm systemd[1]: Started MPD Monitor Service. Dec 16 15:00:33 pifystrm mpd_monitor.sh[5073]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 15:00:33 pifystrm sudo[5068]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:33 pifystrm volumio[4750]: info: Successfully started MPD Monitor Dec 16 15:00:33 pifystrm systemd[1]: Stopping MPD Monitor Service... Dec 16 15:00:33 pifystrm systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 16 15:00:33 pifystrm systemd[1]: mpd_monitor.service: Succeeded. Dec 16 15:00:33 pifystrm systemd[1]: Stopped MPD Monitor Service. Dec 16 15:00:33 pifystrm systemd[1]: Started MPD Monitor Service. Dec 16 15:00:33 pifystrm sudo[5071]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:33 pifystrm volumio[4750]: info: Successfully started MPD Monitor Dec 16 15:00:33 pifystrm mpd_monitor.sh[5077]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 15:00:33 pifystrm volumio[4750]: info: Checking Spotify Web API Dec 16 15:00:33 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:33 pifystrm sudo[5082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:33 pifystrm sudo[5082]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:33 pifystrm sudo[5082]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:34 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:34 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:34 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:00:34 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:00:34 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:34 pifystrm sudo[5086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:34 pifystrm sudo[5086]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:34 pifystrm sudo[5086]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:34 pifystrm avahi-daemon[621]: Withdrawing address record for 10.1.12.92 on wlan0. Dec 16 15:00:34 pifystrm avahi-daemon[621]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:34 pifystrm dhcpcd[914]: wlan0: deleting route to 10.1.8.0/21 Dec 16 15:00:34 pifystrm dhcpcd[914]: wlan0: deleting default route via 10.1.10.1 Dec 16 15:00:34 pifystrm avahi-daemon[621]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 15:00:34 pifystrm volumio[4750]: info: Discovery: A device disappeared from network Dec 16 15:00:34 pifystrm volumio[4750]: info: Discovery: Device pifystrm disappeared from network Dec 16 15:00:34 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:34 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:34 pifystrm volumio[4750]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 16 15:00:34 pifystrm sudo[5120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 16 15:00:34 pifystrm sudo[5120]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:34 pifystrm sudo[5120]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:34 pifystrm volumio[4750]: info: Completed starting Core Plugins Dec 16 15:00:34 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:34 pifystrm volumio[4750]: info: ----- MyVolumio plugins startup ---- Dec 16 15:00:34 pifystrm volumio[4750]: info: ------------------------------------------- Dec 16 15:00:34 pifystrm volumio[4750]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 16 15:00:35 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:35 pifystrm sudo[5137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:00:35 pifystrm sudo[5137]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:35 pifystrm sudo[5137]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:35 pifystrm volumio[4750]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 15:00:35 pifystrm sudo[5140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:00:35 pifystrm sudo[5140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:35 pifystrm sudo[5140]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:35 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:35 pifystrm sudo[5143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 15:00:35 pifystrm sudo[5143]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:35 pifystrm sudo[5143]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:35 pifystrm volumio[4750]: info: Upmpdcli Daemon Started Dec 16 15:00:35 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:36 pifystrm ntpd[782]: Deleting interface #10 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=6 secs Dec 16 15:00:39 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:39 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:00:39 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:00:39 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:39 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:39 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:40 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:40 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:40 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:43 pifystrm sudo[5061]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:43 pifystrm volumio[4750]: info: Cannot mount NAS Curaudio at system boot, trial number 2 ,retrying in 5 seconds Dec 16 15:00:45 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:00:45 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:45 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:00:45 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:45 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:45 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:45 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:45 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:46 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:48 pifystrm sudo[5244]: 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 Dec 16 15:00:48 pifystrm sudo[5244]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:48 pifystrm sudo[5244]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:48 pifystrm volumio[4750]: info: Cannot mount NAS Curaudio at system boot, trial number 3 ,retrying in 5 seconds Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:00:51 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:00:51 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:00:51 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:00:51 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:00:51 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:00:51 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:51 pifystrm avahi-daemon[621]: Withdrawing address record for 10.1.12.92 on wlan0. Dec 16 15:00:51 pifystrm avahi-daemon[621]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: deleting route to 10.1.8.0/21 Dec 16 15:00:51 pifystrm avahi-daemon[621]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: deleting default route via 10.1.10.1 Dec 16 15:00:51 pifystrm volumio[4750]: info: Discovery: A device disappeared from network Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:51 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:52 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:53 pifystrm sudo[5304]: 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 Dec 16 15:00:53 pifystrm sudo[5304]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:53 pifystrm sudo[5304]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:53 pifystrm volumio[4750]: info: Cannot mount NAS Curaudio at system boot, trial number 4 ,retrying in 5 seconds Dec 16 15:00:54 pifystrm volumio[4750]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 15:00:54 pifystrm volumio[4750]: [Metrics] CommandRouter: 30s 873.03ms Dec 16 15:00:54 pifystrm volumio[4750]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 15:00:54 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:00:54 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:54 pifystrm volumio[4750]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:00:54 pifystrm volumio[4750]: info: CoreCommandRouter::Close All Modals sent Dec 16 15:00:56 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 15:00:56 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 15:00:56 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 15:00:56 pifystrm volumio[4750]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 16 15:00:56 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:00:56 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:00:56 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:00:56 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:00:56 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:00:56 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:00:57 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:00:57 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:00:57 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:00:57 pifystrm nmbd[734]: [2025/12/16 15:00:57.513642, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 16 15:00:57 pifystrm nmbd[734]: Packet send failed to 10.1.15.255(138) ERRNO=Network is unreachable Dec 16 15:00:58 pifystrm sudo[5346]: 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 Dec 16 15:00:58 pifystrm sudo[5346]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:58 pifystrm sudo[5346]: pam_unix(sudo:session): session closed for user root Dec 16 15:00:58 pifystrm volumio[4750]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 16 15:00:59 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:00:59 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:00:59 pifystrm volumio[4750]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:00:59 pifystrm volumio[4750]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:59 pifystrm volumio[4750]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 15:00:59 pifystrm volumio[4750]: at doSend (dgram.js:692:16) Dec 16 15:00:59 pifystrm volumio[4750]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 15:00:59 pifystrm volumio[4750]: at afterDns (dgram.js:638:5) Dec 16 15:00:59 pifystrm volumio[4750]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 15:00:59 pifystrm volumio[4750]: errno: -101, Dec 16 15:00:59 pifystrm volumio[4750]: code: 'ENETUNREACH', Dec 16 15:00:59 pifystrm volumio[4750]: syscall: 'send', Dec 16 15:00:59 pifystrm volumio[4750]: address: '255.255.255.255', Dec 16 15:00:59 pifystrm volumio[4750]: port: 3483 Dec 16 15:00:59 pifystrm volumio[4750]: } Dec 16 15:00:59 pifystrm volumio[4750]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:00:59 pifystrm sudo[5360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 14:59 Dec 16 15:00:59 pifystrm sudo[5360]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:00:59 pifystrm sudo[5360]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:00 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 15:01:00 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 15:01:00 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:01:00 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 15:01:00 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:01:00 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:01:00 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 15:01:00 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 16 15:01:00 pifystrm systemd[1]: Stopped Volumio Backend Module. Dec 16 15:01:00 pifystrm systemd[1]: Started Volumio Backend Module. Dec 16 15:01:00 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:01:00 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:01:01 pifystrm volumio[5373]: info: ------------------------------------------- Dec 16 15:01:01 pifystrm volumio[5373]: info: ----- Volumio3 ---- Dec 16 15:01:01 pifystrm volumio[5373]: info: ------------------------------------------- Dec 16 15:01:01 pifystrm volumio[5373]: info: ----- System startup ---- Dec 16 15:01:01 pifystrm volumio[5373]: info: ------------------------------------------- Dec 16 15:01:01 pifystrm ntpd[782]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 16 15:01:01 pifystrm volumio[5373]: info: MYVOLUMIO Environment detected Dec 16 15:01:01 pifystrm volumio[5373]: info: Plugin folders cleanup Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning into folder /volumio/app/plugins/ Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category audio_interface Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category miscellanea Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category music_service Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category plugins.json Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category system_controller Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category user_interface Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning into folder /data/plugins/ Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category music_service Dec 16 15:01:01 pifystrm volumio[5373]: info: Scanning category user_interface Dec 16 15:01:01 pifystrm volumio[5373]: info: Plugin folders cleanup completed Dec 16 15:01:01 pifystrm volumio[5373]: info: ------------------------------------------- Dec 16 15:01:01 pifystrm volumio[5373]: info: ----- Core plugins startup ---- Dec 16 15:01:01 pifystrm volumio[5373]: info: ------------------------------------------- Dec 16 15:01:01 pifystrm volumio[5373]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 15:01:01 pifystrm volumio[5373]: info: Adding plugin upnp to MyMusic Plugins Dec 16 15:01:01 pifystrm volumio[5373]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 15:01:01 pifystrm volumio[5373]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 15:01:01 pifystrm volumio[5373]: info: Loading plugins from folder /data/plugins/ Dec 16 15:01:01 pifystrm volumio[5373]: info: Loading plugin "system"... Dec 16 15:01:01 pifystrm volumio[5373]: info: Loading plugin "appearance"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "network"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Refreshing Cached IP Addresses Dec 16 15:01:02 pifystrm sudo[5400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:01:02 pifystrm sudo[5400]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:02 pifystrm sudo[5400]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:02 pifystrm sudo[5402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:01:02 pifystrm sudo[5402]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:02 pifystrm sudo[5402]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "services"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "alsa_controller"... Dec 16 15:01:02 pifystrm sudo[5411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 15:01:02 pifystrm sudo[5411]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:02 pifystrm ntpd[782]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 16 15:01:02 pifystrm ntpd[782]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 16 15:01:02 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "wizard"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "networkfs"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Starting Udev Watcher for removable devices Dec 16 15:01:02 pifystrm sudo[5426]: 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 Dec 16 15:01:02 pifystrm sudo[5426]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:02 pifystrm volumio[5373]: info: Ignoring mount for partition: boot Dec 16 15:01:02 pifystrm volumio[5373]: info: Ignoring mount for partition: volumio Dec 16 15:01:02 pifystrm volumio[5373]: info: Ignoring mount for partition: volumio_data Dec 16 15:01:02 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "volumio_command_line_client"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "upnp"... Dec 16 15:01:02 pifystrm volumio[5373]: info: [1765915262626] Starting Upmpd Daemon Dec 16 15:01:02 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "my_music"... Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "mpd"... Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:01:02 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:01:02 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:01:02 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:01:02 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:01:02 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:01:02 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:01:02 pifystrm volumio[5373]: info: Loading plugin "upnp_browser"... Dec 16 15:01:02 pifystrm avahi-daemon[621]: Withdrawing address record for 10.1.12.92 on wlan0. Dec 16 15:01:02 pifystrm avahi-daemon[621]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: deleting route to 10.1.8.0/21 Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: deleting default route via 10.1.10.1 Dec 16 15:01:02 pifystrm avahi-daemon[621]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:01:02 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "alarm-clock"... Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "airplay_emulation"... Dec 16 15:01:03 pifystrm volumio[5373]: info: Starting Shairport Sync Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "last_100"... Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "webradio"... Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "i2s_dacs"... Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "volumiodiscovery"... Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** For more information see Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:03 pifystrm volumio[5373]: *** WARNING *** For more information see Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:03 pifystrm volumio[5373]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** For more information see Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:03 pifystrm node[5373]: *** WARNING *** For more information see Dec 16 15:01:03 pifystrm volumio[5373]: info: Discovery: Started advertising with name: PiFyStrm Dec 16 15:01:03 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "spop"... Dec 16 15:01:03 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:01:03 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:01:03 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:01:03 pifystrm volumio[5373]: info: Loading plugin "squeezelite_mc"... Dec 16 15:01:04 pifystrm wpa_supplicant[902]: RRM: Ignoring radio measurement request: Not RRM network Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "now_playing"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "outputs"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "albumart"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Plugin example_plugin is not enabled Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "inputs"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "updater_comm"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Plugin mpdemulation is not enabled Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "rest_api"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "websocket"... Dec 16 15:01:04 pifystrm volumio[5373]: info: Starting Socket.io Server version 2.3.0 Dec 16 15:01:04 pifystrm volumio[5373]: info: Loading plugin "touch_display"... Dec 16 15:01:05 pifystrm volumio[5373]: Forking 3 albumart workers Dec 16 15:01:05 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:05] [connect] Successful connection Dec 16 15:01:05 pifystrm volumio[5373]: info: Applying required configuration parameters for plugin touch_display Dec 16 15:01:05 pifystrm volumio[5373]: info: Loading i18n strings for locale en Dec 16 15:01:05 pifystrm volumio[5373]: Updating browse sources language Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::initPlayerControls Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: Express server listening on port 3000 Dec 16 15:01:05 pifystrm volumio[5373]: [Metrics] WebUI: 4s 418.96ms Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::resetVolumioState Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::getcurrentVolume Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::pushState Dec 16 15:01:05 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:05 pifystrm volumio[5373]: info: Volumio Network Manager: Network status updated: 0 Dec 16 15:01:05 pifystrm volumio[5373]: 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 Dec 16 15:01:05 pifystrm volumio[5373]: 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 Dec 16 15:01:05 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765915265 101 Dec 16 15:01:05 pifystrm volumio[5373]: 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 Dec 16 15:01:05 pifystrm volumio[5373]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 16 15:01:05 pifystrm volumio[5373]: Starting albumart workers Dec 16 15:01:05 pifystrm volumio[5373]: Starting albumart workers Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:05 pifystrm volumio[5373]: info: Reloading queue from file Dec 16 15:01:05 pifystrm volumio[5373]: Starting albumart workers Dec 16 15:01:05 pifystrm volumio[5373]: info: Setting Device type: Raspberry PI Dec 16 15:01:05 pifystrm volumio[5373]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 16 15:01:05 pifystrm volumio[5373]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 16 15:01:05 pifystrm volumio[5373]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::setRepeat false single undefined Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::pushState Dec 16 15:01:05 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::setRandom false Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreStateMachine::pushState Dec 16 15:01:05 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:05 pifystrm volumio[5373]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:06 pifystrm sudo[5411]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:07 pifystrm volumio[5373]: 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: 5 Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::volumioGetState Dec 16 15:01:07 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 15:01:07 pifystrm volumio[5373]: info: Received Get System Info Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:01:07 pifystrm volumio[5373]: info: Discovery: Getting this device information Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::volumioGetState Dec 16 15:01:07 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:01:07 pifystrm volumio[5373]: info: CoreCommandRouter::volumioGetState Dec 16 15:01:07 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:07 pifystrm volumio[5373]: info: Listing playlists Dec 16 15:01:08 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:01:08 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Dec 16 15:01:08 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Dec 16 15:01:08 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:01:08 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:01:08 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:01:08 pifystrm ntpd[782]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 16 15:01:08 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:01:08 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:01:09 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:01:12 pifystrm sudo[5581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:01:12 pifystrm sudo[5581]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm sudo[5581]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm sudo[5584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:01:12 pifystrm sudo[5584]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm sudo[5584]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm sudo[5587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 16 15:01:12 pifystrm sudo[5587]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm sudo[5587]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm volumio[5373]: info: Upmpdcli Daemon Started Dec 16 15:01:12 pifystrm sudo[5426]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm volumio[5373]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Dec 16 15:01:12 pifystrm volumio[5373]: info: Completed loading Core Plugins Dec 16 15:01:12 pifystrm volumio[5373]: info: Preparing to generate the ALSA configuration file Dec 16 15:01:12 pifystrm volumio[5373]: info: Asound.conf file unchanged, so no further update is needed Dec 16 15:01:12 pifystrm volumio[5373]: info: Output device has changed, restarting MPD Dec 16 15:01:12 pifystrm sudo[5590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:01:12 pifystrm sudo[5590]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm volumio[5373]: info: Output device has changed, restarting Shairport Sync Dec 16 15:01:12 pifystrm sudo[5590]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:12 pifystrm sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:01:12 pifystrm sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm systemd[1]: Stopping Music Player Daemon... Dec 16 15:01:12 pifystrm volumio[5373]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:12 pifystrm volumio[5373]: info: ___________ START PLUGINS ___________ Dec 16 15:01:12 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:01:12 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:01:12 pifystrm volumio[5373]: info: ControllerMpd::onStart: Initializing MPD Dec 16 15:01:12 pifystrm volumio[5373]: info: Creating MPD Configuration file Dec 16 15:01:12 pifystrm sudo[5600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:01:12 pifystrm sudo[5600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:12 pifystrm volumio[5373]: info: [1765915272846] CoreMusicLibrary::Adding element Media Servers Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:12 pifystrm sudo[5600]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:12 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:01:12 pifystrm sudo[5603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:01:12 pifystrm sudo[5603]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm volumio[5373]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:12 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 15:01:12 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:01:12 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:01:12 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:01:12 pifystrm volumio[5373]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:12 pifystrm volumio[5373]: info: [1765915272936] CoreMusicLibrary::Adding element Last_100 Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:12 pifystrm volumio[5373]: info: [1765915272939] CoreMusicLibrary::Adding element Webradio Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:12 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:12 pifystrm volumio[5373]: info: Initializing BBC Radios Dec 16 15:01:12 pifystrm sudo[5609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:01:12 pifystrm sudo[5609]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:12 pifystrm sudo[5609]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: Checking for old spotify connect plugin installed Dec 16 15:01:13 pifystrm volumio[5373]: info: [squeezelite_mc] Starting proxy server... Dec 16 15:01:13 pifystrm volumio[5373]: info: [now-playing-config] Config is up to date. Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:01:13 pifystrm volumio[5373]: info: Discovery: Getting this device information Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::volumioGetState Dec 16 15:01:13 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:01:13 pifystrm volumio[5373]: info: Loading i18n strings for locale en Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:13 pifystrm volumio[5373]: info: Volumio Calling Home Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:13 pifystrm volumio[5373]: [SpotifyConnect] Creating VLS config file Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: [SpotifyConnect] Starting metadata listener Dec 16 15:01:13 pifystrm volumio[5373]: info: [squeezelite_mc] Proxy server started on port 45109 Dec 16 15:01:13 pifystrm volumio[5373]: info: [now-playing-app] App is listening on port 4004. Dec 16 15:01:13 pifystrm sudo[5628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 16 15:01:13 pifystrm sudo[5628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:13 pifystrm sudo[5628]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm sudo[5631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:01:13 pifystrm sudo[5631]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:13 pifystrm systemd[1]: Reloading. Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreStateMachine::pushState Dec 16 15:01:13 pifystrm volumio[5373]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:13 pifystrm volumio[5373]: info: [squeezelite_mc] Server discovery started Dec 16 15:01:13 pifystrm volumio[5373]: info: [squeezelite_mc] Player finder started Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:13 pifystrm volumio[5373]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 15:01:13 pifystrm volumio[5373]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:01:13 pifystrm volumio[5373]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 15:01:13 pifystrm volumio[5373]: at doSend (dgram.js:692:16) Dec 16 15:01:13 pifystrm volumio[5373]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 15:01:13 pifystrm volumio[5373]: at afterDns (dgram.js:638:5) Dec 16 15:01:13 pifystrm volumio[5373]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 15:01:13 pifystrm volumio[5373]: errno: -101, Dec 16 15:01:13 pifystrm volumio[5373]: code: 'ENETUNREACH', Dec 16 15:01:13 pifystrm volumio[5373]: syscall: 'send', Dec 16 15:01:13 pifystrm volumio[5373]: address: '255.255.255.255', Dec 16 15:01:13 pifystrm volumio[5373]: port: 3483 Dec 16 15:01:13 pifystrm volumio[5373]: } Dec 16 15:01:13 pifystrm volumio[5373]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:01:13 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. Dec 16 15:01:13 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. Dec 16 15:01:13 pifystrm sudo[5659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 15:00 Dec 16 15:01:13 pifystrm sudo[5659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:13 pifystrm sudo[5631]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm mpd[5619]: Dec 16 15:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 15:01:13 pifystrm sudo[5659]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm systemd[1]: Started Music Player Daemon. Dec 16 15:01:13 pifystrm sudo[5593]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm sudo[5603]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:13 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 15:01:13 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 15:01:13 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 15:01:13 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:01:13 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:01:13 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:01:13 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:01:13 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:01:13 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:01:13 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 15:01:14 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:01:14 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:01:14 pifystrm dhcpcd[914]: wlan0: carrier lost Dec 16 15:01:14 pifystrm wpa_supplicant[902]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Dec 16 15:01:14 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Dec 16 15:01:14 pifystrm wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 15:01:14 pifystrm avahi-daemon[621]: Withdrawing address record for 10.1.12.92 on wlan0. Dec 16 15:01:14 pifystrm avahi-daemon[621]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:01:14 pifystrm dhcpcd[914]: wlan0: deleting route to 10.1.8.0/21 Dec 16 15:01:14 pifystrm dhcpcd[914]: wlan0: deleting default route via 10.1.10.1 Dec 16 15:01:14 pifystrm avahi-daemon[621]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 16 15:01:14 pifystrm dhcpcd[914]: wlan0: carrier acquired Dec 16 15:01:14 pifystrm dhcpcd[914]: wlan0: IAID dd:0d:07:df Dec 16 15:01:14 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 15:01:14 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Dec 16 15:01:14 pifystrm systemd[1]: Started dynamicswap service. Dec 16 15:01:14 pifystrm systemd[1]: Stopped Volumio Backend Module. Dec 16 15:01:14 pifystrm systemd[1]: Started Volumio Backend Module. Dec 16 15:01:14 pifystrm systemd[1]: dynamicswap.service: Succeeded. Dec 16 15:01:15 pifystrm dhcpcd[914]: wlan0: rebinding lease of 10.1.12.92 Dec 16 15:01:15 pifystrm dhcpcd[914]: wlan0: soliciting an IPv6 router Dec 16 15:01:15 pifystrm volumio[5732]: info: ------------------------------------------- Dec 16 15:01:15 pifystrm volumio[5732]: info: ----- Volumio3 ---- Dec 16 15:01:15 pifystrm volumio[5732]: info: ------------------------------------------- Dec 16 15:01:15 pifystrm volumio[5732]: info: ----- System startup ---- Dec 16 15:01:15 pifystrm volumio[5732]: info: ------------------------------------------- Dec 16 15:01:15 pifystrm dhcpcd[914]: wlan0: probing address 10.1.12.92/21 Dec 16 15:01:15 pifystrm volumio[5732]: info: MYVOLUMIO Environment detected Dec 16 15:01:15 pifystrm volumio[5732]: info: Plugin folders cleanup Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning into folder /volumio/app/plugins/ Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category audio_interface Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category miscellanea Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category music_service Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category plugins.json Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category system_controller Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category user_interface Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning into folder /data/plugins/ Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category music_service Dec 16 15:01:15 pifystrm volumio[5732]: info: Scanning category user_interface Dec 16 15:01:15 pifystrm volumio[5732]: info: Plugin folders cleanup completed Dec 16 15:01:15 pifystrm volumio[5732]: info: ------------------------------------------- Dec 16 15:01:15 pifystrm volumio[5732]: info: ----- Core plugins startup ---- Dec 16 15:01:15 pifystrm volumio[5732]: info: ------------------------------------------- Dec 16 15:01:15 pifystrm volumio[5732]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 15:01:15 pifystrm volumio[5732]: info: Adding plugin upnp to MyMusic Plugins Dec 16 15:01:15 pifystrm volumio[5732]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 15:01:15 pifystrm volumio[5732]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 15:01:15 pifystrm volumio[5732]: info: Loading plugins from folder /data/plugins/ Dec 16 15:01:15 pifystrm volumio[5732]: info: Loading plugin "system"... Dec 16 15:01:15 pifystrm volumio[5732]: info: Loading plugin "appearance"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "network"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Refreshing Cached IP Addresses Dec 16 15:01:16 pifystrm sudo[5758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 15:01:16 pifystrm sudo[5758]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:16 pifystrm sudo[5758]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:16 pifystrm sudo[5760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 15:01:16 pifystrm sudo[5760]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:16 pifystrm sudo[5760]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "services"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "alsa_controller"... Dec 16 15:01:16 pifystrm sudo[5769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 15:01:16 pifystrm sudo[5769]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:16 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "wizard"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "networkfs"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Starting Udev Watcher for removable devices Dec 16 15:01:16 pifystrm sudo[5784]: 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 Dec 16 15:01:16 pifystrm sudo[5784]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:16 pifystrm volumio[5732]: info: Ignoring mount for partition: boot Dec 16 15:01:16 pifystrm volumio[5732]: info: Ignoring mount for partition: volumio Dec 16 15:01:16 pifystrm volumio[5732]: info: Ignoring mount for partition: volumio_data Dec 16 15:01:16 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "volumio_command_line_client"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "upnp"... Dec 16 15:01:16 pifystrm volumio[5732]: info: [1765915276650] Starting Upmpd Daemon Dec 16 15:01:16 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "my_music"... Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "mpd"... Dec 16 15:01:16 pifystrm sudo[5784]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:16 pifystrm volumio[5732]: info: Loading plugin "upnp_browser"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "alarm-clock"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "airplay_emulation"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Starting Shairport Sync Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "last_100"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "webradio"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "i2s_dacs"... Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "volumiodiscovery"... Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** For more information see Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:17 pifystrm volumio[5732]: *** WARNING *** For more information see Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** For more information see Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 15:01:17 pifystrm node[5732]: *** WARNING *** For more information see Dec 16 15:01:17 pifystrm volumio[5732]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 15:01:17 pifystrm volumio[5732]: info: Discovery: Started advertising with name: PiFyStrm Dec 16 15:01:17 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "spop"... Dec 16 15:01:17 pifystrm nmbd[734]: [2025/12/16 15:01:17.539503, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Dec 16 15:01:17 pifystrm nmbd[734]: reload_interfaces: No subnets to listen to. Waiting.. Dec 16 15:01:17 pifystrm volumio[5732]: info: Loading plugin "squeezelite_mc"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "now_playing"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "outputs"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "albumart"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Plugin example_plugin is not enabled Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "inputs"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "updater_comm"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Plugin mpdemulation is not enabled Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "rest_api"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "websocket"... Dec 16 15:01:18 pifystrm volumio[5732]: info: Starting Socket.io Server version 2.3.0 Dec 16 15:01:18 pifystrm volumio[5732]: info: Loading plugin "touch_display"... Dec 16 15:01:18 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:18] [connect] Successful connection Dec 16 15:01:19 pifystrm volumio[5732]: Forking 3 albumart workers Dec 16 15:01:19 pifystrm volumio[5732]: info: Applying required configuration parameters for plugin touch_display Dec 16 15:01:19 pifystrm volumio[5732]: info: Loading i18n strings for locale en Dec 16 15:01:19 pifystrm volumio[5732]: Updating browse sources language Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::initPlayerControls Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: Express server listening on port 3000 Dec 16 15:01:19 pifystrm volumio[5732]: [Metrics] WebUI: 4s 303.44ms Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::resetVolumioState Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::getcurrentVolume Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::pushState Dec 16 15:01:19 pifystrm volumio[5732]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:19 pifystrm volumio[5732]: info: Volumio Network Manager: Network status updated: 0 Dec 16 15:01:19 pifystrm volumio[5732]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Dec 16 15:01:19 pifystrm volumio[5732]: 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 Dec 16 15:01:19 pifystrm volumio[5732]: 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 Dec 16 15:01:19 pifystrm volumio-remote-updater[612]: [2025-12-16 15:01:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765915278 101 Dec 16 15:01:19 pifystrm volumio[5732]: 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 Dec 16 15:01:19 pifystrm volumio[5732]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: Reloading queue from file Dec 16 15:01:19 pifystrm volumio[5732]: Starting albumart workers Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::setRepeat false single undefined Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::pushState Dec 16 15:01:19 pifystrm volumio[5732]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::setRandom false Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreStateMachine::pushState Dec 16 15:01:19 pifystrm volumio[5732]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:19 pifystrm volumio[5732]: info: Setting Device type: Raspberry PI Dec 16 15:01:19 pifystrm volumio[5732]: info: Completed loading Core Plugins Dec 16 15:01:19 pifystrm volumio[5732]: info: Preparing to generate the ALSA configuration file Dec 16 15:01:19 pifystrm volumio[5732]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 16 15:01:19 pifystrm volumio[5732]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 16 15:01:19 pifystrm volumio[5732]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 16 15:01:19 pifystrm volumio[5732]: Starting albumart workers Dec 16 15:01:19 pifystrm volumio[5732]: info: Asound.conf file unchanged, so no further update is needed Dec 16 15:01:19 pifystrm volumio[5732]: info: Output device has changed, restarting MPD Dec 16 15:01:19 pifystrm volumio[5732]: Starting albumart workers Dec 16 15:01:19 pifystrm sudo[5827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:01:19 pifystrm sudo[5827]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:19 pifystrm volumio[5732]: info: Output device has changed, restarting Shairport Sync Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:19 pifystrm sudo[5827]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:19 pifystrm sudo[5828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:01:19 pifystrm sudo[5828]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:19 pifystrm systemd[1]: Stopping Music Player Daemon... Dec 16 15:01:19 pifystrm volumio[5732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:19 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:01:19 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:01:19 pifystrm volumio[5732]: info: ___________ START PLUGINS ___________ Dec 16 15:01:19 pifystrm volumio[5732]: info: ControllerMpd::onStart: Initializing MPD Dec 16 15:01:19 pifystrm volumio[5732]: info: Creating MPD Configuration file Dec 16 15:01:19 pifystrm sudo[5836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 15:01:19 pifystrm sudo[5836]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:19 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:19 pifystrm volumio[5732]: info: [1765915279908] CoreMusicLibrary::Adding element Media Servers Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:19 pifystrm sudo[5836]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:19 pifystrm sudo[5839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 15:01:19 pifystrm sudo[5839]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:19 pifystrm volumio[5732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:19 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:20 pifystrm sudo[5834]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:01:20 pifystrm sudo[5834]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:20 pifystrm sudo[5834]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:20 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 15:01:20 pifystrm systemd[1]: mpd.service: Succeeded. Dec 16 15:01:20 pifystrm systemd[1]: Stopped Music Player Daemon. Dec 16 15:01:20 pifystrm systemd[1]: Starting Music Player Daemon... Dec 16 15:01:20 pifystrm volumio[5732]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:20 pifystrm volumio[5732]: info: [1765915280073] CoreMusicLibrary::Adding element Last_100 Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 15:01:20 pifystrm volumio[5732]: info: [1765915280076] CoreMusicLibrary::Adding element Webradio Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:20 pifystrm volumio[5732]: info: Initializing BBC Radios Dec 16 15:01:20 pifystrm sudo[5847]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 15:01:20 pifystrm sudo[5847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:20 pifystrm sudo[5847]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: info: Checking for old spotify connect plugin installed Dec 16 15:01:20 pifystrm volumio[5732]: info: [squeezelite_mc] Starting proxy server... Dec 16 15:01:20 pifystrm volumio[5732]: info: [now-playing-config] Config is up to date. Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 15:01:20 pifystrm volumio[5732]: info: Discovery: Getting this device information Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::volumioGetState Dec 16 15:01:20 pifystrm volumio[5732]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 15:01:20 pifystrm volumio[5732]: info: Loading i18n strings for locale en Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 15:01:20 pifystrm volumio[5732]: info: Volumio Calling Home Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 15:01:20 pifystrm volumio[5732]: [SpotifyConnect] Creating VLS config file Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: [SpotifyConnect] Starting metadata listener Dec 16 15:01:20 pifystrm volumio[5732]: info: [squeezelite_mc] Proxy server started on port 40195 Dec 16 15:01:20 pifystrm volumio[5732]: info: [now-playing-app] App is listening on port 4004. Dec 16 15:01:20 pifystrm sudo[5888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Dec 16 15:01:20 pifystrm sudo[5888]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:20 pifystrm sudo[5888]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:20 pifystrm sudo[5891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 16 15:01:20 pifystrm sudo[5891]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:20 pifystrm systemd[1]: Reloading. Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreStateMachine::pushState Dec 16 15:01:20 pifystrm volumio[5732]: info: CorePlayQueue::getTrack 0 Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::volumioPushState Dec 16 15:01:20 pifystrm volumio[5732]: info: [squeezelite_mc] Server discovery started Dec 16 15:01:20 pifystrm volumio[5732]: info: [squeezelite_mc] Player finder started Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 15:01:20 pifystrm volumio[5732]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 15:01:20 pifystrm volumio[5732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:01:20 pifystrm volumio[5732]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 15:01:20 pifystrm volumio[5732]: at doSend (dgram.js:692:16) Dec 16 15:01:20 pifystrm volumio[5732]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 15:01:20 pifystrm volumio[5732]: at afterDns (dgram.js:638:5) Dec 16 15:01:20 pifystrm volumio[5732]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 15:01:20 pifystrm volumio[5732]: errno: -101, Dec 16 15:01:20 pifystrm volumio[5732]: code: 'ENETUNREACH', Dec 16 15:01:20 pifystrm volumio[5732]: syscall: 'send', Dec 16 15:01:20 pifystrm volumio[5732]: address: '255.255.255.255', Dec 16 15:01:20 pifystrm volumio[5732]: port: 3483 Dec 16 15:01:20 pifystrm volumio[5732]: } Dec 16 15:01:20 pifystrm volumio[5732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 15:01:20 pifystrm sudo[5769]: pam_unix(sudo:session): session closed for user root Dec 16 15:01:20 pifystrm dhcpcd[914]: wlan0: leased 10.1.12.92 for 604800 seconds Dec 16 15:01:20 pifystrm avahi-daemon[621]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Dec 16 15:01:20 pifystrm avahi-daemon[621]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 15:01:20 pifystrm dhcpcd[914]: wlan0: adding route to 10.1.8.0/21 Dec 16 15:01:20 pifystrm avahi-daemon[621]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Dec 16 15:01:20 pifystrm dhcpcd[914]: wlan0: adding default route via 10.1.10.1 Dec 16 15:01:20 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. Dec 16 15:01:21 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. Dec 16 15:01:21 pifystrm sudo[5951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 15:00 Dec 16 15:01:21 pifystrm sudo[5951]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 15:01:21 pifystrm mpd[5860]: Dec 16 15:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"