-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Wed 2026-02-18 15:23:26 EST. -- Feb 18 15:22:00 pifystrm wireless.js[633]: trying... Feb 18 15:22:00 pifystrm sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:00 pifystrm sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:00 pifystrm sudo[940]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:01 pifystrm wireless.js[633]: trying... Feb 18 15:22:01 pifystrm sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:01 pifystrm sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:01 pifystrm sudo[943]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:01 pifystrm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 18 15:22:01 pifystrm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 18 15:22:02 pifystrm volumio-time-update[629]: volumio-time-update-util: Fetching time from Volumio... Feb 18 15:22:02 pifystrm volumio-time-update[629]: volumio-time-update-util: Date not found in response Feb 18 15:22:02 pifystrm volumio-time-update[629]: volumio-time-update-util: Retrying in 5 seconds... Feb 18 15:22:02 pifystrm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 18 15:22:02 pifystrm kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 18 15:22:02 pifystrm wireless.js[633]: trying... Feb 18 15:22:02 pifystrm sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:02 pifystrm sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:02 pifystrm sudo[951]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:03 pifystrm wireless.js[633]: trying... Feb 18 15:22:03 pifystrm sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:03 pifystrm sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:03 pifystrm sudo[954]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:04 pifystrm volumio-remote-updater[619]: [2026-02-18 15:22:04] [info] asio async_connect error: system:111 (Connection refused) Feb 18 15:22:04 pifystrm volumio-remote-updater[619]: [2026-02-18 15:22:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 18 15:22:04 pifystrm volumio-remote-updater[619]: [2026-02-18 15:22:04] [error] handle_connect error: Underlying Transport Error Feb 18 15:22:04 pifystrm wpa_supplicant[920]: wlan0: Trying to associate with SSID 'ElectionCenterWarehouse' Feb 18 15:22:04 pifystrm wireless.js[633]: trying... Feb 18 15:22:04 pifystrm sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:04 pifystrm sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:04 pifystrm sudo[957]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:04 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9c:cf:21 Feb 18 15:22:04 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:cf:21 completed [id=1 id_str=] Feb 18 15:22:04 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:22:04 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:22:04 pifystrm dhcpcd[932]: wlan0: connected to Access Point `ElectionCenterWarehouse' Feb 18 15:22:04 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Feb 18 15:22:04 pifystrm dhcpcd[932]: DUID 00:01:00:01:2c:2b:35:44:d8:3a:dd:0d:07:dc Feb 18 15:22:04 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:22:04 pifystrm dhcpcd[932]: wlan0: adding address fe80::8b41:2932:442d:c11f Feb 18 15:22:04 pifystrm dhcpcd[932]: ipv6_addaddr1: Permission denied Feb 18 15:22:05 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:22:05 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:22:05 pifystrm wireless.js[633]: trying... Feb 18 15:22:05 pifystrm sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:05 pifystrm sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:05 pifystrm sudo[961]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:05 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:22:06 pifystrm wireless.js[633]: trying... Feb 18 15:22:06 pifystrm sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:06 pifystrm sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:06 pifystrm sudo[964]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:07 pifystrm volumio-time-update[629]: volumio-time-update-util: Fetching time from Volumio... Feb 18 15:22:07 pifystrm volumio-time-update[629]: volumio-time-update-util: Date not found in response Feb 18 15:22:07 pifystrm volumio-time-update[629]: volumio-time-update-util: Retrying in 5 seconds... Feb 18 15:22:07 pifystrm wireless.js[633]: trying... Feb 18 15:22:07 pifystrm sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:07 pifystrm sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:07 pifystrm sudo[972]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:08 pifystrm wireless.js[633]: trying... Feb 18 15:22:08 pifystrm sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:08 pifystrm sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:08 pifystrm sudo[975]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:09 pifystrm wireless.js[633]: trying... Feb 18 15:22:09 pifystrm sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:09 pifystrm sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:09 pifystrm sudo[978]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 18 15:22:10 pifystrm avahi-daemon[641]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: adding route to 10.1.8.0/21 Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: adding default route via 10.1.10.1 Feb 18 15:22:10 pifystrm avahi-daemon[641]: New relevant interface wlan0.IPv4 for mDNS. Feb 18 15:22:10 pifystrm avahi-daemon[641]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 18 15:22:10 pifystrm wireless.js[633]: trying... Feb 18 15:22:10 pifystrm sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 18 15:22:10 pifystrm sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:10 pifystrm sudo[1002]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:10 pifystrm wireless.js[633]: Connected to: ----ElectionCenterWarehouse Feb 18 15:22:10 pifystrm wireless.js[633]: ---- Feb 18 15:22:10 pifystrm sudo[1005]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 15:22:10 pifystrm sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:10 pifystrm sudo[1005]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:10 pifystrm wireless.js[633]: ... joined AP, wlan0 IPv4 is 10.1.12.92, ipV6 is undefined Feb 18 15:22:10 pifystrm wireless.js[633]: It's done! AP Feb 18 15:22:10 pifystrm kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 18 15:22:10 pifystrm systemd[1]: Started Wireless Services. Feb 18 15:22:10 pifystrm systemd[1]: Started Volumio Backend Module. Feb 18 15:22:10 pifystrm systemd[1]: Started Volumio Cpu Tweaker. Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: Setting RT Priority for mpd Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: carrier lost Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: pid 35's current scheduling policy: SCHED_OTHER Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: pid 35's current scheduling priority: 0 Feb 18 15:22:10 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9d:13:61 Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: Setting MPD Affinity Feb 18 15:22:10 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9d:13:61 completed [id=1 id_str=] Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: pid 3's current affinity mask: f Feb 18 15:22:10 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 18 15:22:10 pifystrm volumio-cpu-tweak[1011]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 18 15:22:10 pifystrm systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 18 15:22:10 pifystrm avahi-daemon[641]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 18 15:22:10 pifystrm avahi-daemon[641]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: deleting route to 10.1.8.0/21 Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: deleting default route via 10.1.10.1 Feb 18 15:22:10 pifystrm avahi-daemon[641]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:22:10 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:22:11 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:22:11 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:22:12 pifystrm volumio-time-update[629]: volumio-time-update-util: Fetching time from Volumio... Feb 18 15:22:12 pifystrm volumio-time-update[629]: volumio-time-update-util: Date not found in response Feb 18 15:22:12 pifystrm volumio-time-update[629]: volumio-time-update-util: Retrying in 5 seconds... Feb 18 15:22:12 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:22:12 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:12 pifystrm volumio[1010]: info: ----- Volumio3 ---- Feb 18 15:22:12 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:12 pifystrm volumio[1010]: info: ----- System startup ---- Feb 18 15:22:12 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:13 pifystrm volumio[1010]: info: MYVOLUMIO Environment detected Feb 18 15:22:13 pifystrm volumio[1010]: info: Plugin folders cleanup Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning into folder /volumio/app/plugins/ Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category audio_interface Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category miscellanea Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category music_service Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category plugins.json Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category system_controller Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category user_interface Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning into folder /data/plugins/ Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category music_service Feb 18 15:22:13 pifystrm volumio[1010]: info: Scanning category user_interface Feb 18 15:22:13 pifystrm volumio[1010]: info: Plugin folders cleanup completed Feb 18 15:22:13 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:13 pifystrm volumio[1010]: info: ----- Core plugins startup ---- Feb 18 15:22:13 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:13 pifystrm volumio[1010]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 15:22:13 pifystrm volumio[1010]: info: Adding plugin upnp to MyMusic Plugins Feb 18 15:22:13 pifystrm volumio[1010]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 15:22:13 pifystrm volumio[1010]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 15:22:13 pifystrm volumio[1010]: info: Loading plugins from folder /data/plugins/ Feb 18 15:22:13 pifystrm volumio[1010]: info: Loading plugin "system"... Feb 18 15:22:13 pifystrm volumio[1010]: info: Loading plugin "appearance"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "network"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Refreshing Cached IP Addresses Feb 18 15:22:14 pifystrm sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 15:22:14 pifystrm sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:14 pifystrm sudo[1078]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:14 pifystrm sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 15:22:14 pifystrm sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:14 pifystrm sudo[1080]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "services"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "volumio5onboarding"... Feb 18 15:22:14 pifystrm sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 15:22:14 pifystrm sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "alsa_controller"... Feb 18 15:22:14 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "wizard"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "networkfs"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Starting Udev Watcher for removable devices Feb 18 15:22:14 pifystrm sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:22:14 pifystrm sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:14 pifystrm volumio[1010]: info: Ignoring mount for partition: boot Feb 18 15:22:14 pifystrm volumio[1010]: info: Ignoring mount for partition: volumio Feb 18 15:22:14 pifystrm volumio[1010]: info: Ignoring mount for partition: volumio_data Feb 18 15:22:14 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "volumio_command_line_client"... Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "upnp"... Feb 18 15:22:14 pifystrm volumio[1010]: info: [1771446134398] Starting Upmpd Daemon Feb 18 15:22:14 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "my_music"... Feb 18 15:22:14 pifystrm volumio-remote-updater[619]: [2026-02-18 15:22:14] [connect] Successful connection Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "mpd"... Feb 18 15:22:14 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 18 15:22:14 pifystrm systemd[1]: Reached target Host and Network Name Lookups. Feb 18 15:22:14 pifystrm systemd[1]: Starting Preprocess NFS configuration... Feb 18 15:22:14 pifystrm systemd[1]: nfs-config.service: Succeeded. Feb 18 15:22:14 pifystrm systemd[1]: Started Preprocess NFS configuration. Feb 18 15:22:14 pifystrm systemd[1]: Starting Notify NFS peers of a restart... Feb 18 15:22:14 pifystrm systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Feb 18 15:22:14 pifystrm sm-notify[1112]: Version 1.3.3 starting Feb 18 15:22:14 pifystrm systemd[1]: rpc-statd-notify.service: Succeeded. Feb 18 15:22:14 pifystrm systemd[1]: Started Notify NFS peers of a restart. Feb 18 15:22:14 pifystrm rpc.statd[1114]: Version 1.3.3 starting Feb 18 15:22:14 pifystrm rpc.statd[1114]: Flags: TI-RPC Feb 18 15:22:14 pifystrm systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Feb 18 15:22:14 pifystrm sudo[1104]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:14 pifystrm volumio[1010]: info: Loading plugin "upnp_browser"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Starting UPNP Browser Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "alarm-clock"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "airplay_emulation"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Starting Shairport Sync Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "last_100"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "webradio"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "i2s_dacs"... Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "volumiodiscovery"... Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** For more information see Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:22:15 pifystrm volumio[1010]: *** WARNING *** For more information see Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** For more information see Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:22:15 pifystrm node[1010]: *** WARNING *** For more information see Feb 18 15:22:15 pifystrm volumio[1010]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 15:22:15 pifystrm volumio[1010]: info: Discovery: Started advertising with name: PiFyStrm Feb 18 15:22:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:22:15 pifystrm volumio[1010]: info: Loading plugin "squeezelite_mc"... Feb 18 15:22:16 pifystrm volumio[1010]: info: Loading plugin "now_playing"... Feb 18 15:22:17 pifystrm volumio-time-update[629]: volumio-time-update-util: Fetching time from Volumio... Feb 18 15:22:17 pifystrm volumio-time-update[629]: volumio-time-update-util: Date not found in response Feb 18 15:22:17 pifystrm volumio-time-update[629]: volumio-time-update-util: Retrying in 5 seconds... Feb 18 15:22:17 pifystrm dhcpcd[932]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 18 15:22:17 pifystrm avahi-daemon[641]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:22:17 pifystrm dhcpcd[932]: wlan0: adding route to 10.1.8.0/21 Feb 18 15:22:17 pifystrm dhcpcd[932]: wlan0: adding default route via 10.1.10.1 Feb 18 15:22:17 pifystrm avahi-daemon[641]: New relevant interface wlan0.IPv4 for mDNS. Feb 18 15:22:17 pifystrm avahi-daemon[641]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "outputs"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "albumart"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Plugin example_plugin is not enabled Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "inputs"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "updater_comm"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Plugin mpdemulation is not enabled Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "rest_api"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "websocket"... Feb 18 15:22:17 pifystrm volumio[1010]: info: Starting Socket.io Server version 2.3.0 Feb 18 15:22:17 pifystrm volumio[1010]: info: Loading plugin "touch_display"... Feb 18 15:22:17 pifystrm volumio[1010]: Forking 3 albumart workers Feb 18 15:22:17 pifystrm volumio[1010]: info: Applying required configuration parameters for plugin touch_display Feb 18 15:22:18 pifystrm nmbd[760]: [2026/02/18 15:22:18.077167, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 18 15:22:18 pifystrm nmbd[760]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 18 15:22:18 pifystrm systemd[1]: Started Samba NMB Daemon. Feb 18 15:22:18 pifystrm nmbd[760]: [2026/02/18 15:22:18.112371, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 18 15:22:18 pifystrm nmbd[760]: query_name_response: Multiple (2) responses received for a query on subnet 10.1.12.92 for name WORKGROUP<1d>. Feb 18 15:22:18 pifystrm nmbd[760]: This response was from IP 10.1.12.149, reporting an IP address of 10.1.12.149. Feb 18 15:22:18 pifystrm systemd[1]: Starting Samba Winbind Daemon... Feb 18 15:22:18 pifystrm volumio[1010]: Starting albumart workers Feb 18 15:22:18 pifystrm volumio[1010]: Starting albumart workers Feb 18 15:22:18 pifystrm volumio[1010]: Starting albumart workers Feb 18 15:22:18 pifystrm volumio[1010]: info: Loading i18n strings for locale en Feb 18 15:22:18 pifystrm volumio[1010]: Updating browse sources language Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:22:18 pifystrm sudo[1089]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:18 pifystrm winbindd[1173]: [2026/02/18 15:22:18.406095, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 18 15:22:18 pifystrm winbindd[1173]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 18 15:22:18 pifystrm systemd[1]: Started Samba Winbind Daemon. Feb 18 15:22:18 pifystrm winbindd[1173]: [2026/02/18 15:22:18.432496, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 18 15:22:18 pifystrm winbindd[1173]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 18 15:22:18 pifystrm systemd[1]: Starting Samba SMB Daemon... Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::initPlayerControls Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: Express server listening on port 3000 Feb 18 15:22:18 pifystrm volumio[1010]: [Metrics] WebUI: 6s 924.29ms Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::resetVolumioState Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::getcurrentVolume Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::pushState Feb 18 15:22:18 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::volumioPushState Feb 18 15:22:18 pifystrm volumio[1010]: info: Volumio Network Manager: Network status updated: 0 Feb 18 15:22:18 pifystrm volumio[1010]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Feb 18 15:22:18 pifystrm volumio-remote-updater[619]: [2026-02-18 15:22:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771446134 101 Feb 18 15:22:18 pifystrm volumio[1010]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Feb 18 15:22:18 pifystrm volumio[1010]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 15:22:18 pifystrm volumio[1010]: info: Reloading queue from file Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::setRepeat false single undefined Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::pushState Feb 18 15:22:18 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::volumioPushState Feb 18 15:22:18 pifystrm smbd[1177]: [2026/02/18 15:22:18.966112, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 18 15:22:18 pifystrm smbd[1177]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 18 15:22:18 pifystrm systemd[1]: Started Samba SMB Daemon. Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::setRandom false Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreStateMachine::pushState Feb 18 15:22:18 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:22:18 pifystrm volumio[1010]: info: CoreCommandRouter::volumioPushState Feb 18 15:22:18 pifystrm volumio[1010]: info: Setting Device type: Raspberry PI Feb 18 15:22:18 pifystrm volumio[1010]: info: Completed loading Core Plugins Feb 18 15:22:18 pifystrm volumio[1010]: info: Preparing to generate the ALSA configuration file Feb 18 15:22:19 pifystrm volumio[1010]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 18 15:22:19 pifystrm volumio[1010]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 18 15:22:19 pifystrm volumio[1010]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 18 15:22:19 pifystrm volumio[1010]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:22:19 pifystrm volumio[1010]: info: Output device has changed, restarting MPD Feb 18 15:22:19 pifystrm sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:22:19 pifystrm sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio[1010]: info: Output device has changed, restarting Shairport Sync Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm sudo[1218]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:22:19 pifystrm sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio[1010]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:22:19 pifystrm volumio[1010]: info: ___________ START PLUGINS ___________ Feb 18 15:22:19 pifystrm volumio[1010]: info: ControllerMpd::onStart: Initializing MPD Feb 18 15:22:19 pifystrm volumio[1010]: info: Creating MPD Configuration file Feb 18 15:22:19 pifystrm sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 18 15:22:19 pifystrm sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm systemd[1]: Listening on mpd.socket. Feb 18 15:22:19 pifystrm systemd[1]: Starting Music Player Daemon... Feb 18 15:22:19 pifystrm sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:22:19 pifystrm sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm sudo[1231]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:22:19 pifystrm volumio[1010]: info: [1771446139148] CoreMusicLibrary::Adding element Media Servers Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:22:19 pifystrm sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:22:19 pifystrm sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio[1010]: info: UPNP Browser: Client initialized successfully Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm systemd[1]: Started Volumio5 Onboarding Server. Feb 18 15:22:19 pifystrm sudo[1228]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 18 15:22:19 pifystrm systemd[1]: mpd.service: Succeeded. Feb 18 15:22:19 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 18 15:22:19 pifystrm systemd[1]: Starting Music Player Daemon... Feb 18 15:22:19 pifystrm volumio[1010]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:22:19 pifystrm volumio[1010]: info: [1771446139248] CoreMusicLibrary::Adding element Last_100 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:22:19 pifystrm volumio[1010]: info: [1771446139251] CoreMusicLibrary::Adding element Webradio Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:22:19 pifystrm volumio[1010]: info: Initializing BBC Radios Feb 18 15:22:19 pifystrm sudo[1241]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:22:19 pifystrm sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm sudo[1241]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 18 15:22:19 pifystrm sudo[1241]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Starting proxy server... Feb 18 15:22:19 pifystrm volumio[1010]: info: [now-playing-config] Config is up to date. Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:19 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:19 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:19 pifystrm volumio[1010]: info: Loading i18n strings for locale en Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:22:19 pifystrm volumio[1010]: info: Volumio Calling Home Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Proxy server started on port 43765 Feb 18 15:22:19 pifystrm volumio[1010]: info: [now-playing-app] App is listening on port 4004. Feb 18 15:22:19 pifystrm sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 18 15:22:19 pifystrm sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm systemd[1]: Reloading. Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreStateMachine::pushState Feb 18 15:22:19 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioPushState Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Server discovery started Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Player finder started Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 18 15:22:19 pifystrm volumio[1010]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 18 15:22:19 pifystrm volumio[1010]: info: Discovery: Found device PiFyStrm Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:19 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:19 pifystrm volumio[1010]: info: MPD Permissions set Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["S16_LE","S24_LE"] Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"pifystrm","dsdFormat":"dop","card":"1","mixerType":"None","mixer":null} Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 18 15:22:19 pifystrm volumio[1010]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:22:19 pifystrm sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 18 15:22:19 pifystrm sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:19.758-05:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 18 15:22:19 pifystrm sudo[1285]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Server discovered: {"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"} Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 18 15:22:19 pifystrm volumio[1010]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 18 15:22:19 pifystrm volumio[1010]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Feb 18 15:22:19 pifystrm volumio[1010]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 18 15:22:19 pifystrm volumio[1010]: info: Starting Shairport Sync Feb 18 15:22:19 pifystrm sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 18 15:22:19 pifystrm sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 18 15:22:19 pifystrm volumio[1010]: info: Starting Shairport Sync Feb 18 15:22:19 pifystrm sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:22:19 pifystrm sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio[1010]: info: Starting Shairport Sync Feb 18 15:22:19 pifystrm sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:22:19 pifystrm sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 15:22:19 pifystrm sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm volumio[1010]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 18 15:22:19 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:19 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:19 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:19 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:19.903-05:00 level=INFO msg="system info for 6c3cd78159a2bd225b94fe31e39e8177" deviceName=PiFyStrm deviceVariant=volumio deviceModel= softwareVersion=3.908 Feb 18 15:22:19 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:19 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:19 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:19 pifystrm volumio[1010]: info: Volumio called home Feb 18 15:22:19 pifystrm volumio[1010]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:22:19 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:22:19 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:19.983-05:00 level=INFO msg="enabling local network discovery" Feb 18 15:22:19 pifystrm sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Feb 18 15:22:19 pifystrm sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:19 pifystrm sudo[1315]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:19 pifystrm volumio[1010]: info: touch_display: File permissions for backlight brightness control set. Feb 18 15:22:20 pifystrm sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 18 15:22:20 pifystrm sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:20 pifystrm sudo[1313]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:20 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 18 15:22:20 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:20.023-05:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 18 15:22:20 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:22:20 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:22:20 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:20 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:20 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:20 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:20 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:20 pifystrm sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 18 15:22:20 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 18 15:22:20 pifystrm sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:20 pifystrm systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 18 15:22:20 pifystrm volumio-remote-updater[619]: No test mode Feb 18 15:22:20 pifystrm volumio-remote-updater[619]: No alpha test mode Feb 18 15:22:20 pifystrm systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 18 15:22:20 pifystrm sudo[1266]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:20 pifystrm volumio[1010]: info: touch_display: systemctl daemon-reload succeeded. Feb 18 15:22:20 pifystrm ntpd[785]: Listen normally on 3 wlan0 10.1.12.92:123 Feb 18 15:22:20 pifystrm ntpd[785]: new interface(s) found: waking up resolver Feb 18 15:22:20 pifystrm sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 18 15:22:20 pifystrm sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:20 pifystrm systemd[1]: Reloading. Feb 18 15:22: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. Feb 18 15:22:20 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 18 15:22:20 pifystrm volumio[1010]: info: [squeezelite_mc] Notification listener started Feb 18 15:22:20 pifystrm volumio[1010]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Feb 18 15:22:20 pifystrm systemd[1]: systemd-fsckd.service: Succeeded. Feb 18 15:22:20 pifystrm sudo[1323]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:20 pifystrm volumio[1010]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"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":"bb:bb:79:c8:e3:7b","uuid":null,"ip":"10.1.14.16","name":"UBP-X1100ES","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc: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: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":"c0:f5:35:6b:a7:0c","uuid":null,"ip":"10.1.12.55","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc: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"}}] Feb 18 15:22:20 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:20 pifystrm systemd[1]: Started Volumio Kiosk. Feb 18 15:22:20 pifystrm systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 18 15:22:20 pifystrm systemd[1]: shairport-sync.service: Succeeded. Feb 18 15:22:20 pifystrm systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 18 15:22:20 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:20 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:21 pifystrm systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 18 15:22:21 pifystrm sudo[1327]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm sudo[1308]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm sudo[1306]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm sudo[1303]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:21 pifystrm sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:21 pifystrm volumio[1010]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 18 15:22:21 pifystrm volumio[1010]: info: touch_display: Volumio Kiosk started Feb 18 15:22:21 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:21 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:21 pifystrm sudo[1344]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:21 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:21 pifystrm volumio[1010]: info: Shairport-Sync Started Feb 18 15:22:21 pifystrm volumio[1010]: Error adding Membership: Error: addMembership EINVAL Feb 18 15:22:21 pifystrm volumio[1010]: info: Shairport-Sync Started Feb 18 15:22:21 pifystrm volumio[1010]: info: Shairport-Sync Started Feb 18 15:22:21 pifystrm volumio[1010]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 18 15:22:21 pifystrm sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 18 15:22:21 pifystrm sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:21 pifystrm systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 18 15:22:21 pifystrm systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 18 15:22:21 pifystrm sudo[1374]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:21 pifystrm kernel: process '/opt/squeezelite/squeezelite' started with executable stack Feb 18 15:22:21 pifystrm volumio[1010]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 18 15:22:21 pifystrm startx[1343]: X.Org X Server 1.20.4 Feb 18 15:22:21 pifystrm startx[1343]: X Protocol Version 11, Revision 0 Feb 18 15:22:21 pifystrm startx[1343]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Feb 18 15:22:21 pifystrm startx[1343]: Current Operating System: Linux pifystrm 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Feb 18 15:22:21 pifystrm startx[1343]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:0D:07:DC vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9b6a8fd4-d1e0-41c3-90e3-655c7141a81c imgfile=/volumio_current.sqsh bootpart=UUID=7883-9333 datapart=UUID=6b82388f-38a7-458e-b6c9-99440f2c3b2b uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 18 15:22:21 pifystrm startx[1343]: Build Date: 04 April 2023 07:50:56AM Feb 18 15:22:21 pifystrm startx[1343]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Feb 18 15:22:21 pifystrm startx[1343]: Current version of pixman: 0.36.0 Feb 18 15:22:21 pifystrm startx[1343]: Before reporting problems, check http://wiki.x.org Feb 18 15:22:21 pifystrm startx[1343]: to make sure that you have the latest version. Feb 18 15:22:21 pifystrm startx[1343]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 18 15:22:21 pifystrm startx[1343]: (++) from command line, (!!) notice, (II) informational, Feb 18 15:22:21 pifystrm startx[1343]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 18 15:22:21 pifystrm startx[1343]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Feb 18 15:22:21 2026 Feb 18 15:22:21 pifystrm startx[1343]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 18 15:22:21 pifystrm startx[1343]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 18 15:22:21 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:21 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:21 pifystrm volumio[1010]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] 'client' notification received from Gamez-PD (10.1.14.16); type is 'connect' Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] Getting players connected to Gamez-PD (10.1.14.16) Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] Players connected to Gamez-PD (10.1.14.16): [{"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"}},{"id":"bb:bb:79:c8:e3:7b","uuid":null,"ip":"10.1.14.16","name":"UBP-X1100ES","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:b0:50:2f:c9","uuid":null,"ip":"10.1.14.16","name":"IntegraTHX","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc: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: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":"c0:f5:35:6b:a7:0c","uuid":null,"ip":"10.1.12.55","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:35:6b:a7:0c","uuid":null,"ip":"10.1.14.16","name":"Office","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc: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"}}] Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:0d:07:df","uuid":null,"ip":"10.1.12.92","name":"pifystrm","server":{"ip":"10.1.14.16","name":"Gamez-PD","ver":"9.0.3","uuid":"bbf9149d-4568-464c-9fb5-be5f4673b91a","jsonPort":"9000","cliPort":"9090"}} Feb 18 15:22:21 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:21 pifystrm sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:21 pifystrm sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:21 pifystrm sh[562]: timed out Feb 18 15:22:21 pifystrm dhcpcd[609]: timed out Feb 18 15:22:21 pifystrm sh[562]: dhcpcd exited Feb 18 15:22:21 pifystrm dhcpcd[609]: dhcpcd exited Feb 18 15:22:21 pifystrm sh[562]: ifup: failed to bring up eth0 Feb 18 15:22:21 pifystrm systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:22:21 pifystrm systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 18 15:22:21 pifystrm sudo[1388]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:22 pifystrm mpd[1248]: Feb 18 15:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 15:22:22 pifystrm systemd[1]: Started Music Player Daemon. Feb 18 15:22:22 pifystrm sudo[1234]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:22 pifystrm sudo[1220]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:22 pifystrm volumio-time-update[629]: volumio-time-update-util: Fetching time from Volumio... Feb 18 15:22:22 pifystrm volumio[1010]: error: MPD error: The expression evaluated to a falsy value: Feb 18 15:22:22 pifystrm volumio[1010]: assert.ok(self.idling) Feb 18 15:22:22 pifystrm volumio[1010]: error: The expression evaluated to a falsy value: Feb 18 15:22:22 pifystrm volumio[1010]: assert.ok(self.idling) Feb 18 15:22:22 pifystrm volumio[1010]: error: updateQueue error: null Feb 18 15:22:22 pifystrm volumio[1010]: info: MPD running with PID1248 Feb 18 15:22:22 pifystrm volumio[1010]: ,establishing connection Feb 18 15:22:22 pifystrm volumio[1010]: error: updateQueue error: null Feb 18 15:22:22 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:22 pifystrm sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:22 pifystrm sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:22 pifystrm volumio-time-update[629]: volumio-time-update-util: Setting system time to: 2026-02-18 15:22:51 Feb 18 15:22:22 pifystrm sudo[1400]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:22 pifystrm sudo[1403]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-02-18 15:22:51 Feb 18 15:22:22 pifystrm sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:22 pifystrm dbus-daemon[623]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1404 comm="timedatectl set-time 2026-02-18 15:22:51 ") Feb 18 15:22:22 pifystrm systemd[1]: Starting Time & Date Service... Feb 18 15:22:22 pifystrm dbus-daemon[623]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 18 15:22:22 pifystrm systemd[1]: Started Time & Date Service. Feb 18 15:22:51 pifystrm systemd-timedated[1405]: Changed local time to Wed Feb 18 15:22:51 2026 Feb 18 15:22:51 pifystrm sudo[1403]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:51 pifystrm volumio-time-update[629]: volumio-time-update-util: System time updated successfully. Feb 18 15:22:51 pifystrm systemd[1]: Started Volumio Time Update Utility. Feb 18 15:22:51 pifystrm systemd[1]: Reached target Multi-User System. Feb 18 15:22:51 pifystrm systemd[1]: Reached target Graphical Interface. Feb 18 15:22:51 pifystrm systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 18 15:22:51 pifystrm systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 18 15:22:51 pifystrm systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 18 15:22:51 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: carrier lost Feb 18 15:22:51 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 18 15:22:51 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:22:51 pifystrm volumio[1010]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 15:22:51 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:51.173-05:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 18 15:22:51 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:51.173-05:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 18 15:22:51 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:51.174-05:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 15:22:51 pifystrm volumio[1010]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 18 15:22:51 pifystrm avahi-daemon[641]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 18 15:22:51 pifystrm avahi-daemon[641]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:22:51 pifystrm avahi-daemon[641]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: deleting route to 10.1.8.0/21 Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: deleting default route via 10.1.10.1 Feb 18 15:22:51 pifystrm volumio[1010]: info: Discovery: A device disappeared from network Feb 18 15:22:51 pifystrm volumio[1010]: info: Discovery: Device pifystrm disappeared from network Feb 18 15:22:51 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:22:51 pifystrm sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:51 pifystrm sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:51 pifystrm sudo[1468]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:51 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:22:51 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:51 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:51 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:22:51 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:22:51 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:51.801-05:00 level=INFO msg="enabling BLE discovery" Feb 18 15:22:51 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:51 pifystrm sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:51 pifystrm sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:51 pifystrm sudo[1486]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm volumio[1010]: error: [squeezelite_mc] Error in getting Squeezelite's sync master: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) FetchError: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) Feb 18 15:22:52 pifystrm volumio[1010]: at ClientRequest. (/data/plugins/music_service/squeezelite_mc/node_modules/node-fetch/lib/index.js:1491:11) Feb 18 15:22:52 pifystrm volumio[1010]: at ClientRequest.emit (events.js:315:20) Feb 18 15:22:52 pifystrm volumio[1010]: at Socket.socketErrorListener (_http_client.js:469:9) Feb 18 15:22:52 pifystrm volumio[1010]: at Socket.emit (events.js:315:20) Feb 18 15:22:52 pifystrm volumio[1010]: at emitErrorNT (internal/streams/destroy.js:106:8) Feb 18 15:22:52 pifystrm volumio[1010]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Feb 18 15:22:52 pifystrm volumio[1010]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 18 15:22:52 pifystrm volumio[1010]: (node:1010) UnhandledPromiseRejectionWarning: FetchError: request to http://10.1.14.16:9000/jsonrpc.js failed, reason: connect ENETUNREACH 10.1.14.16:9000 - Local (0.0.0.0:0) Feb 18 15:22:52 pifystrm volumio[1010]: at ClientRequest. (/data/plugins/music_service/squeezelite_mc/node_modules/node-fetch/lib/index.js:1491:11) Feb 18 15:22:52 pifystrm volumio[1010]: at ClientRequest.emit (events.js:315:20) Feb 18 15:22:52 pifystrm volumio[1010]: at Socket.socketErrorListener (_http_client.js:469:9) Feb 18 15:22:52 pifystrm volumio[1010]: at Socket.emit (events.js:315:20) Feb 18 15:22:52 pifystrm volumio[1010]: at emitErrorNT (internal/streams/destroy.js:106:8) Feb 18 15:22:52 pifystrm volumio[1010]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Feb 18 15:22:52 pifystrm volumio[1010]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 18 15:22:52 pifystrm volumio[1010]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 18 15:22:52 pifystrm volumio[1010]: (node:1010) 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) Feb 18 15:22:52 pifystrm volumio[1010]: (node:1010) [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. Feb 18 15:22:52 pifystrm sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:22:52 pifystrm sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:52 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:22:52 pifystrm sudo[1503]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm volumio[1010]: info: Cannot mount NAS Curaudio at system boot, trial number 2 ,retrying in 5 seconds Feb 18 15:22:52 pifystrm volumio[1010]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 18 15:22:52 pifystrm sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 18 15:22:52 pifystrm sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:52 pifystrm sudo[1507]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm volumio[1010]: info: Completed starting Core Plugins Feb 18 15:22:52 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:52 pifystrm volumio[1010]: info: ----- MyVolumio plugins startup ---- Feb 18 15:22:52 pifystrm volumio[1010]: info: ------------------------------------------- Feb 18 15:22:52 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 15:22:52 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:22:52 pifystrm ntpd[785]: Deleting interface #3 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 18 15:22:52 pifystrm sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 15:22:52 pifystrm sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:52 pifystrm sudo[1546]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 15:22:52 pifystrm sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:52 pifystrm sudo[1548]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 15:22:52 pifystrm sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:52 pifystrm systemd[1]: Started UPnP Renderer front-end to MPD. Feb 18 15:22:52 pifystrm sudo[1552]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:52 pifystrm volumio[1010]: info: Upmpdcli Daemon Started Feb 18 15:22:55 pifystrm volumio[1010]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 15:22:57 pifystrm sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:22:57 pifystrm sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:22:57 pifystrm sudo[1601]: pam_unix(sudo:session): session closed for user root Feb 18 15:22:57 pifystrm volumio[1010]: info: Cannot mount NAS Curaudio at system boot, trial number 3 ,retrying in 5 seconds Feb 18 15:22:57 pifystrm dhcpcd[932]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 18 15:22:57 pifystrm avahi-daemon[641]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:22:57 pifystrm dhcpcd[932]: wlan0: adding route to 10.1.8.0/21 Feb 18 15:22:57 pifystrm dhcpcd[932]: wlan0: adding default route via 10.1.10.1 Feb 18 15:22:57 pifystrm avahi-daemon[641]: New relevant interface wlan0.IPv4 for mDNS. Feb 18 15:22:57 pifystrm avahi-daemon[641]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 18 15:22:57 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:22:57 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:57 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:22:57 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:22:58 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:58.075-05:00 level=INFO msg="disabling BLE discovery" Feb 18 15:22:58 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:22:58.080-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 18 15:22:58 pifystrm volumio[1010]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 18 15:22:58 pifystrm volumio[1010]: info: Discovery: Found device PiFyStrm Feb 18 15:22:58 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:22:58 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:22:58 pifystrm ntpd[785]: Listen normally on 4 wlan0 10.1.12.92:123 Feb 18 15:22:58 pifystrm ntpd[785]: new interface(s) found: waking up resolver Feb 18 15:23:01 pifystrm volumio[1554]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 15:23:01 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 15:23:02 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9c:99:a1 Feb 18 15:23:02 pifystrm dhcpcd[932]: wlan0: carrier lost Feb 18 15:23:02 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:99:a1 completed [id=1 id_str=] Feb 18 15:23:02 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:23:02 pifystrm avahi-daemon[641]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 18 15:23:02 pifystrm avahi-daemon[641]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:23:02 pifystrm avahi-daemon[641]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 18 15:23:02 pifystrm dhcpcd[932]: wlan0: deleting route to 10.1.8.0/21 Feb 18 15:23:02 pifystrm dhcpcd[932]: wlan0: deleting default route via 10.1.10.1 Feb 18 15:23:02 pifystrm volumio[1010]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 15:23:02 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 15:23:02 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:02 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:02 pifystrm volumio[1010]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 15:23:02 pifystrm volumio[1010]: info: MyVolumio login type: Token Feb 18 15:23:02 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:23:02 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:23:02 pifystrm volumio[1010]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 15:23:02 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 15:23:03 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:23:03 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:23:03 pifystrm volumio[1010]: info: Starting Streaming Service Transparent Proxy Feb 18 15:23:03 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:23:03 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 15:23:03 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 15:23:03 pifystrm volumio[1010]: info: Streaming services startup Feb 18 15:23:03 pifystrm volumio[1010]: info: Starting Streaming Daemon Feb 18 15:23:03 pifystrm sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 15:23:03 pifystrm sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:03 pifystrm volumio[1010]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 15:23:04 pifystrm sudo[1758]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:04 pifystrm volumio[1010]: info: Volumio Network Manager: Network status updated: 2 Feb 18 15:23:04 pifystrm sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:23:04 pifystrm volumio[1010]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 18 15:23:04 pifystrm volumio[1010]: info: Retrying Login Due to Network Error Feb 18 15:23:04 pifystrm sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:04 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:04 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:04 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:04 pifystrm volumio[1010]: error: Cannot start Volumio Streaming Daemon Feb 18 15:23:04 pifystrm volumio[1010]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 15:23:04 pifystrm volumio[1010]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:23:04 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:23:04 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:04.106-05:00 level=INFO msg="enabling BLE discovery" Feb 18 15:23:04 pifystrm volumio[1010]: info: Discovery: A device disappeared from network Feb 18 15:23:04 pifystrm volumio[1010]: info: Discovery: Device pifystrm disappeared from network Feb 18 15:23:04 pifystrm sudo[1771]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:04 pifystrm volumio[1010]: info: Cannot mount NAS Curaudio at system boot, trial number 4 ,retrying in 5 seconds Feb 18 15:23:04 pifystrm ntpd[785]: Deleting interface #4 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=6 secs Feb 18 15:23:04 pifystrm volumio[1010]: STREAMING PROXY: Starting server on port 3245 Feb 18 15:23:04 pifystrm volumio[1010]: Node JS runtime: 14 Feb 18 15:23:06 pifystrm volumio[1010]: 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 Feb 18 15:23:07 pifystrm volumio[1010]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:07 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 15:23:07 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:07 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:07 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:07 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:07 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:07 pifystrm volumio[1010]: info: Listing playlists Feb 18 15:23:08 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 18 15:23:08 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 15:23:09 pifystrm dhcpcd[932]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 18 15:23:09 pifystrm avahi-daemon[641]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:23:09 pifystrm avahi-daemon[641]: New relevant interface wlan0.IPv4 for mDNS. Feb 18 15:23:09 pifystrm dhcpcd[932]: wlan0: adding route to 10.1.8.0/21 Feb 18 15:23:09 pifystrm avahi-daemon[641]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 18 15:23:09 pifystrm dhcpcd[932]: wlan0: adding default route via 10.1.10.1 Feb 18 15:23:09 pifystrm sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:23:09 pifystrm sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 15:23:09 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:09 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:09 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:09 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:09 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:09 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:23:09 pifystrm volumio[1554]: .................................................................................................++++ Feb 18 15:23:09 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 18 15:23:10 pifystrm volumio[1010]: info: Discovery: adding 8a7efacc-4a4c-45e8-b169-57dc6a52f98c Feb 18 15:23:10 pifystrm volumio[1010]: info: Discovery: Found device PiFyStrm Feb 18 15:23:10 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:10 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:10 pifystrm ntpd[785]: Listen normally on 5 wlan0 10.1.12.92:123 Feb 18 15:23:10 pifystrm ntpd[785]: new interface(s) found: waking up resolver Feb 18 15:23:12 pifystrm volumio[1554]: ......................++++ Feb 18 15:23:12 pifystrm volumio[1554]: e is 65537 (0x010001) Feb 18 15:23:12 pifystrm volumio[1554]: writing RSA key Feb 18 15:23:13 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:13.150-05:00 level=INFO msg="disabling BLE discovery" Feb 18 15:23:13 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:13.153-05:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:0D:07:E0%00 Feb 18 15:23:14 pifystrm volumio[1010]: info: MyVolumio login type: Token Feb 18 15:23:14 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 18 15:23:14 pifystrm dhcpcd[932]: wlan0: carrier lost Feb 18 15:23:14 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9d:13:61 Feb 18 15:23:14 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9d:13:61 completed [id=1 id_str=] Feb 18 15:23:14 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:23:14 pifystrm avahi-daemon[641]: Withdrawing address record for 10.1.12.92 on wlan0. Feb 18 15:23:14 pifystrm avahi-daemon[641]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:23:14 pifystrm dhcpcd[932]: wlan0: deleting route to 10.1.8.0/21 Feb 18 15:23:14 pifystrm dhcpcd[932]: wlan0: deleting default route via 10.1.10.1 Feb 18 15:23:14 pifystrm avahi-daemon[641]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 18 15:23:14 pifystrm volumio[1010]: info: Discovery: A device disappeared from network Feb 18 15:23:14 pifystrm volumio[1010]: info: Discovery: Device pifystrm disappeared from network Feb 18 15:23:14 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:23:14 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:23:15 pifystrm volumio[1010]: info: Received Get System Info Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:15 pifystrm volumio[1010]: info: Discovery: Getting this device information Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:15 pifystrm volumio[1010]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:23:15 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:23:15 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:15.055-05:00 level=INFO msg="enabling BLE discovery" Feb 18 15:23:15 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:23:15 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:23:15 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:23:15 pifystrm ntpd[785]: Deleting interface #5 wlan0, 10.1.12.92#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs Feb 18 15:23:17 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 18 15:23:17 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:23:17 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:17 pifystrm volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 15:23:17 pifystrm volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:23:17 pifystrm volumio[1010]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 18 15:23:17 pifystrm volumio[1010]: at doSend (dgram.js:692:16) Feb 18 15:23:17 pifystrm volumio[1010]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 18 15:23:17 pifystrm volumio[1010]: at afterDns (dgram.js:638:5) Feb 18 15:23:17 pifystrm volumio[1010]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 18 15:23:17 pifystrm volumio[1010]: errno: -101, Feb 18 15:23:17 pifystrm volumio[1010]: code: 'ENETUNREACH', Feb 18 15:23:17 pifystrm volumio[1010]: syscall: 'send', Feb 18 15:23:17 pifystrm volumio[1010]: address: '255.255.255.255', Feb 18 15:23:17 pifystrm volumio[1010]: port: 3483 Feb 18 15:23:17 pifystrm volumio[1010]: } Feb 18 15:23:17 pifystrm volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:23:18 pifystrm sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-18 15:22 Feb 18 15:23:18 pifystrm sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:18 pifystrm sudo[2072]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:19 pifystrm volumio-remote-updater[619]: [2026-02-18 15:23:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 15:23:19 pifystrm volumio-remote-updater[619]: [2026-02-18 15:23:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 15:23:19 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:19.305-05:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 18 15:23:19 pifystrm systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 15:23:19 pifystrm sudo[1802]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:19 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:19.313-05:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 18 15:23:19 pifystrm systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 15:23:19 pifystrm systemd[1]: Started dynamicswap service. Feb 18 15:23:19 pifystrm systemd[1]: dynamicswap.service: Succeeded. Feb 18 15:23:19 pifystrm systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 18 15:23:19 pifystrm systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 18 15:23:19 pifystrm systemd[1]: Started dynamicswap service. Feb 18 15:23:19 pifystrm systemd[1]: Stopped Volumio Backend Module. Feb 18 15:23:19 pifystrm dhcpcd[932]: wlan0: carrier lost Feb 18 15:23:19 pifystrm wpa_supplicant[920]: wlan0: Associated with 9c:c9:eb:9c:ee:61 Feb 18 15:23:19 pifystrm systemd[1]: Started Volumio Backend Module. Feb 18 15:23:19 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:c9:eb:9c:ee:61 completed [id=1 id_str=] Feb 18 15:23:19 pifystrm wpa_supplicant[920]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 18 15:23:19 pifystrm systemd[1]: dynamicswap.service: Succeeded. Feb 18 15:23:19 pifystrm dhcpcd[932]: wlan0: carrier acquired Feb 18 15:23:19 pifystrm dhcpcd[932]: wlan0: IAID dd:0d:07:df Feb 18 15:23:20 pifystrm volumio5-onboarding[1238]: time=2026-02-18T15:23:20.315-05:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 18 15:23:20 pifystrm dhcpcd[932]: wlan0: rebinding lease of 10.1.12.92 Feb 18 15:23:20 pifystrm volumio[2087]: info: ------------------------------------------- Feb 18 15:23:20 pifystrm volumio[2087]: info: ----- Volumio3 ---- Feb 18 15:23:20 pifystrm volumio[2087]: info: ------------------------------------------- Feb 18 15:23:20 pifystrm volumio[2087]: info: ----- System startup ---- Feb 18 15:23:20 pifystrm volumio[2087]: info: ------------------------------------------- Feb 18 15:23:20 pifystrm dhcpcd[932]: wlan0: soliciting an IPv6 router Feb 18 15:23:20 pifystrm dhcpcd[932]: wlan0: probing address 10.1.12.92/21 Feb 18 15:23:21 pifystrm systemd[1]: systemd-timedated.service: Succeeded. Feb 18 15:23:21 pifystrm volumio[2087]: info: MYVOLUMIO Environment detected Feb 18 15:23:21 pifystrm volumio[2087]: info: Plugin folders cleanup Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning into folder /volumio/app/plugins/ Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category audio_interface Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category miscellanea Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category music_service Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category plugins.json Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category system_controller Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category user_interface Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning into folder /data/plugins/ Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category music_service Feb 18 15:23:21 pifystrm volumio[2087]: info: Scanning category user_interface Feb 18 15:23:21 pifystrm volumio[2087]: info: Plugin folders cleanup completed Feb 18 15:23:21 pifystrm volumio[2087]: info: ------------------------------------------- Feb 18 15:23:21 pifystrm volumio[2087]: info: ----- Core plugins startup ---- Feb 18 15:23:21 pifystrm volumio[2087]: info: ------------------------------------------- Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 15:23:21 pifystrm volumio[2087]: info: Adding plugin upnp to MyMusic Plugins Feb 18 15:23:21 pifystrm volumio[2087]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 15:23:21 pifystrm volumio[2087]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugins from folder /data/plugins/ Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "system"... Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "appearance"... Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "network"... Feb 18 15:23:21 pifystrm volumio[2087]: info: Refreshing Cached IP Addresses Feb 18 15:23:21 pifystrm sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 15:23:21 pifystrm sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:21 pifystrm sudo[2176]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:21 pifystrm sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 15:23:21 pifystrm sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:21 pifystrm sudo[2178]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "services"... Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "volumio5onboarding"... Feb 18 15:23:21 pifystrm sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 15:23:21 pifystrm sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "alsa_controller"... Feb 18 15:23:21 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:23:21 pifystrm volumio[2087]: info: Loading plugin "wizard"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "networkfs"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Starting Udev Watcher for removable devices Feb 18 15:23:22 pifystrm sudo[2202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.0 192.168.99.188:/\\\\GAMEZ-PD\\CurAudio /mnt/NAS/Curaudio Feb 18 15:23:22 pifystrm sudo[2202]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:22 pifystrm volumio[2087]: info: Ignoring mount for partition: boot Feb 18 15:23:22 pifystrm volumio[2087]: info: Ignoring mount for partition: volumio Feb 18 15:23:22 pifystrm volumio[2087]: info: Ignoring mount for partition: volumio_data Feb 18 15:23:22 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "volumio_command_line_client"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "upnp"... Feb 18 15:23:22 pifystrm volumio[2087]: info: [1771446202060] Starting Upmpd Daemon Feb 18 15:23:22 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "my_music"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "mpd"... Feb 18 15:23:22 pifystrm sudo[2202]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "upnp_browser"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Starting UPNP Browser Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "alarm-clock"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "airplay_emulation"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Starting Shairport Sync Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "last_100"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "webradio"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "i2s_dacs"... Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "volumiodiscovery"... Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** For more information see Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:23:22 pifystrm volumio[2087]: *** WARNING *** For more information see Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** For more information see Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 15:23:22 pifystrm node[2087]: *** WARNING *** For more information see Feb 18 15:23:22 pifystrm volumio[2087]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 15:23:22 pifystrm volumio[2087]: info: Discovery: Started advertising with name: PiFyStrm Feb 18 15:23:22 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 15:23:22 pifystrm volumio[2087]: info: Loading plugin "squeezelite_mc"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "now_playing"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "outputs"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "albumart"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Plugin example_plugin is not enabled Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "inputs"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "updater_comm"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Plugin mpdemulation is not enabled Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "rest_api"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "websocket"... Feb 18 15:23:23 pifystrm volumio[2087]: info: Starting Socket.io Server version 2.3.0 Feb 18 15:23:23 pifystrm volumio[2087]: info: Loading plugin "touch_display"... Feb 18 15:23:23 pifystrm volumio[2087]: Forking 3 albumart workers Feb 18 15:23:24 pifystrm volumio[2087]: info: Applying required configuration parameters for plugin touch_display Feb 18 15:23:24 pifystrm volumio[2087]: info: Loading i18n strings for locale en Feb 18 15:23:24 pifystrm volumio[2087]: Updating browse sources language Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::initPlayerControls Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: Express server listening on port 3000 Feb 18 15:23:24 pifystrm volumio[2087]: [Metrics] WebUI: 3s 848.42ms Feb 18 15:23:24 pifystrm volumio-remote-updater[619]: [2026-02-18 15:23:24] [connect] Successful connection Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::resetVolumioState Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::getcurrentVolume Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::pushState Feb 18 15:23:24 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioPushState Feb 18 15:23:24 pifystrm volumio[2087]: info: Volumio Network Manager: Network status updated: 0 Feb 18 15:23:24 pifystrm volumio[2087]: info: Cannot mount NAS Curaudio at system boot, trial number 1 ,retrying in 5 seconds Feb 18 15:23:24 pifystrm volumio[2087]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 18 15:23:24 pifystrm volumio[2087]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 18 15:23:24 pifystrm volumio-remote-updater[619]: [2026-02-18 15:23:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771446204 101 Feb 18 15:23:24 pifystrm volumio[2087]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Feb 18 15:23:24 pifystrm volumio[2087]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 18 15:23:24 pifystrm volumio[2087]: info: Received Get System Info Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:24 pifystrm volumio[2087]: info: Discovery: Getting this device information Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:24 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:24 pifystrm volumio[2087]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: Reloading queue from file Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 15:23:24 pifystrm volumio[2087]: Starting albumart workers Feb 18 15:23:24 pifystrm volumio[2087]: Starting albumart workers Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::setRepeat false single undefined Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::pushState Feb 18 15:23:24 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioPushState Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::setRandom false Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreStateMachine::pushState Feb 18 15:23:24 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioPushState Feb 18 15:23:24 pifystrm volumio[2087]: info: Setting Device type: Raspberry PI Feb 18 15:23:24 pifystrm volumio[2087]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 18 15:23:24 pifystrm volumio[2087]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Feb 18 15:23:24 pifystrm volumio[2087]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 18 15:23:24 pifystrm volumio[2087]: info: Completed loading Core Plugins Feb 18 15:23:24 pifystrm volumio[2087]: info: Preparing to generate the ALSA configuration file Feb 18 15:23:24 pifystrm volumio[2087]: info: Asound.conf file unchanged, so no further update is needed Feb 18 15:23:24 pifystrm volumio[2087]: info: Output device has changed, restarting MPD Feb 18 15:23:24 pifystrm volumio[2087]: Starting albumart workers Feb 18 15:23:24 pifystrm volumio[2087]: info: Output device has changed, restarting Shairport Sync Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:24 pifystrm sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:23:24 pifystrm sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm sudo[2243]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:24 pifystrm sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:23:24 pifystrm sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm systemd[1]: Stopping Music Player Daemon... Feb 18 15:23:24 pifystrm volumio[2087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:23:24 pifystrm volumio[2087]: info: ___________ START PLUGINS ___________ Feb 18 15:23:24 pifystrm systemd[1]: mpd.service: Succeeded. Feb 18 15:23:24 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 18 15:23:24 pifystrm volumio[2087]: info: ControllerMpd::onStart: Initializing MPD Feb 18 15:23:24 pifystrm volumio[2087]: info: Creating MPD Configuration file Feb 18 15:23:24 pifystrm sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 18 15:23:24 pifystrm sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm systemd[1]: Starting Music Player Daemon... Feb 18 15:23:24 pifystrm sudo[2255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 15:23:24 pifystrm sudo[2255]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm sudo[2255]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:23:24 pifystrm volumio[2087]: info: [1771446204883] CoreMusicLibrary::Adding element Media Servers Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:23:24 pifystrm volumio[2087]: info: UPNP Browser: Client initialized successfully Feb 18 15:23:24 pifystrm sudo[2253]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:24 pifystrm sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 15:23:24 pifystrm sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm sudo[2251]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:23:24 pifystrm sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:24 pifystrm sudo[2251]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:24 pifystrm systemd[1]: mpd.service: Succeeded. Feb 18 15:23:24 pifystrm systemd[1]: Stopped Music Player Daemon. Feb 18 15:23:24 pifystrm volumio[2087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:24 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:25 pifystrm systemd[1]: Starting Music Player Daemon... Feb 18 15:23:25 pifystrm volumio[2087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:23:25 pifystrm volumio[2087]: info: [1771446205078] CoreMusicLibrary::Adding element Last_100 Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 15:23:25 pifystrm volumio[2087]: info: [1771446205082] CoreMusicLibrary::Adding element Webradio Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:23:25 pifystrm volumio[2087]: info: Initializing BBC Radios Feb 18 15:23:25 pifystrm sudo[2265]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 15:23:25 pifystrm sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:25 pifystrm sudo[2265]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 15:23:25 pifystrm volumio[2087]: info: [squeezelite_mc] Starting proxy server... Feb 18 15:23:25 pifystrm volumio[2087]: info: [now-playing-config] Config is up to date. Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 15:23:25 pifystrm volumio[2087]: info: Discovery: Getting this device information Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::volumioGetState Feb 18 15:23:25 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 15:23:25 pifystrm volumio[2087]: info: Loading i18n strings for locale en Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 15:23:25 pifystrm volumio[2087]: info: Volumio Calling Home Feb 18 15:23:25 pifystrm volumio[2087]: info: [squeezelite_mc] Proxy server started on port 43143 Feb 18 15:23:25 pifystrm volumio[2087]: info: [now-playing-app] App is listening on port 4004. Feb 18 15:23:25 pifystrm sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 18 15:23:25 pifystrm sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:25 pifystrm systemd[1]: Reloading. Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreStateMachine::pushState Feb 18 15:23:25 pifystrm volumio[2087]: info: CorePlayQueue::getTrack 0 Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::volumioPushState Feb 18 15:23:25 pifystrm volumio[2087]: info: [squeezelite_mc] Server discovery started Feb 18 15:23:25 pifystrm volumio[2087]: info: [squeezelite_mc] Player finder started Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 15:23:25 pifystrm volumio[2087]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 15:23:25 pifystrm volumio[2087]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 18 15:23:25 pifystrm volumio[2087]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:23:25 pifystrm volumio[2087]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 18 15:23:25 pifystrm volumio[2087]: at doSend (dgram.js:692:16) Feb 18 15:23:25 pifystrm volumio[2087]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 18 15:23:25 pifystrm volumio[2087]: at afterDns (dgram.js:638:5) Feb 18 15:23:25 pifystrm volumio[2087]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 18 15:23:25 pifystrm volumio[2087]: errno: -101, Feb 18 15:23:25 pifystrm volumio[2087]: code: 'ENETUNREACH', Feb 18 15:23:25 pifystrm volumio[2087]: syscall: 'send', Feb 18 15:23:25 pifystrm volumio[2087]: address: '255.255.255.255', Feb 18 15:23:25 pifystrm volumio[2087]: port: 3483 Feb 18 15:23:25 pifystrm volumio[2087]: } Feb 18 15:23:25 pifystrm volumio[2087]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 15:23:25 pifystrm systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 18 15:23:25 pifystrm dhcpcd[932]: wlan0: leased 10.1.12.92 for 604800 seconds Feb 18 15:23:25 pifystrm avahi-daemon[641]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.1.12.92. Feb 18 15:23:25 pifystrm dhcpcd[932]: wlan0: adding route to 10.1.8.0/21 Feb 18 15:23:25 pifystrm avahi-daemon[641]: New relevant interface wlan0.IPv4 for mDNS. Feb 18 15:23:25 pifystrm dhcpcd[932]: wlan0: adding default route via 10.1.10.1 Feb 18 15:23:25 pifystrm avahi-daemon[641]: Registering new address record for 10.1.12.92 on wlan0.IPv4. Feb 18 15:23:25 pifystrm systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 18 15:23:26 pifystrm sudo[2188]: pam_unix(sudo:session): session closed for user root Feb 18 15:23:26 pifystrm sudo[2357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-18 15:22 Feb 18 15:23:26 pifystrm sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 15:23:26 pifystrm sudo[2309]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"