-- Logs begin at Thu 2019-02-14 03:11:58 MST, end at Wed 2025-09-17 21:23:55 MDT. -- Sep 17 21:22:01 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:22:01 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:22:01 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:22:01 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:01 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:01 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:22:12 volumio ntpd[988]: 172.234.44.141 local addr 192.168.6.40 -> Sep 17 21:22:14 volumio ntpd[988]: 172.232.15.202 local addr 192.168.6.40 -> Sep 17 21:22:27 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-DISCONNECTED bssid=40:47:5e:2d:3a:67 reason=0 locally_generated=1 Sep 17 21:22:27 volumio dhcpcd[1203]: wlan0: carrier lost Sep 17 21:22:27 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 17 21:22:27 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 17 21:22:27 volumio avahi-daemon[830]: Withdrawing address record for 192.168.5.104 on wlan0. Sep 17 21:22:27 volumio avahi-daemon[830]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.5.104. Sep 17 21:22:27 volumio dhcpcd[1203]: wlan0: deleting route to 192.168.4.0/22 Sep 17 21:22:27 volumio dhcpcd[1203]: wlan0: deleting default route via 192.168.4.1 Sep 17 21:22:27 volumio avahi-daemon[830]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 17 21:22:27 volumio volumio[1368]: info: Discovery: A device disappeared from network Sep 17 21:22:27 volumio volumio[1368]: info: Discovery: Device volumio-mitsubishi disappeared from network Sep 17 21:22:27 volumio volumio[1368]: info: Discovery: Disconnected from remote: 192.168.6.71 Sep 17 21:22:27 volumio volumio[1368]: info: Discovery: A device disappeared from network Sep 17 21:22:27 volumio volumio[1368]: info: Discovery: Device volumio disappeared from network Sep 17 21:22:29 volumio ntpd[988]: Deleting interface #8 wlan0, 192.168.5.104#123, interface stats: received=0, sent=0, dropped=0, active_time=73 secs Sep 17 21:22:31 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:22:31 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:22:31 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:22:31 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:31 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:31 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:22:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 21:22:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 21:22:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 21:22:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 21:22:34 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:34 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:34 volumio wpa_supplicant[1095]: wlan0: Trying to associate with SSID 'Q-Tronics' Sep 17 21:22:34 volumio volumio[1368]: info: Volumio Network Manager: Network status updated: 1 Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: carrier acquired Sep 17 21:22:35 volumio wpa_supplicant[1095]: wlan0: Associated with 40:47:5e:2d:3a:67 Sep 17 21:22:35 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-CONNECTED - Connection to 40:47:5e:2d:3a:67 completed [id=0 id_str=] Sep 17 21:22:35 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 17 21:22:35 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: IAID dd:e4:a3:30 Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: carrier lost Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: carrier acquired Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: IAID dd:e4:a3:30 Sep 17 21:22:35 volumio dhcpcd[1203]: wlan0: soliciting an IPv6 router Sep 17 21:22:36 volumio dhcpcd[1203]: wlan0: rebinding lease of 192.168.5.104 Sep 17 21:22:36 volumio dhcpcd[1203]: wlan0: probing address 192.168.5.104/22 Sep 17 21:22:38 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 21:22:41 volumio dhcpcd[1203]: wlan0: leased 192.168.5.104 for 12759 seconds Sep 17 21:22:41 volumio avahi-daemon[830]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.5.104. Sep 17 21:22:41 volumio dhcpcd[1203]: wlan0: adding route to 192.168.4.0/22 Sep 17 21:22:41 volumio avahi-daemon[830]: New relevant interface wlan0.IPv4 for mDNS. Sep 17 21:22:41 volumio dhcpcd[1203]: wlan0: adding default route via 192.168.4.1 Sep 17 21:22:41 volumio avahi-daemon[830]: Registering new address record for 192.168.5.104 on wlan0.IPv4. Sep 17 21:22:41 volumio sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 21:22:41 volumio sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:22:41 volumio sudo[5593]: pam_unix(sudo:session): session closed for user root Sep 17 21:22:41 volumio sudo[5596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 21:22:41 volumio sudo[5596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:22:41 volumio sudo[5596]: pam_unix(sudo:session): session closed for user root Sep 17 21:22:41 volumio volumio[1368]: verbose: New Socket.io Connection to 192.168.6.40 from 192.168.6.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: Received Get System Info Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:22:41 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 21:22:41 volumio volumio[1368]: info: Received Get System Info Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:22:41 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:41 volumio volumio[1368]: info: Listing playlists Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetQueue Sep 17 21:22:41 volumio volumio[1368]: info: CoreStateMachine::getQueue Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getQueue Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 17 21:22:41 volumio volumio[1368]: info: Discovery: adding bcf62faa-9d10-430c-968b-07ff07c91014 Sep 17 21:22:41 volumio volumio[1368]: info: Discovery: Found device Volumio Sep 17 21:22:41 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:41 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:43 volumio ntpd[988]: Listen normally on 9 wlan0 192.168.5.104:123 Sep 17 21:22:43 volumio ntpd[988]: new interface(s) found: waking up resolver Sep 17 21:22:44 volumio volumio[1368]: info: Volumio Network Manager: Network status updated: 3 Sep 17 21:22:49 volumio volumio[1368]: verbose: New Socket.io Connection to 192.168.6.40:3000 from 192.168.6.32 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 17 21:22:51 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 17 21:22:55 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:22:55 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:22:55 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:22:55 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:22:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:22:55 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:22:56 volumio volumio[1368]: verbose: New Socket.io Connection to 192.168.6.40:3000 from 192.168.6.32 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Sep 17 21:23:06 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:06 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:06 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:06 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:06 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:06 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:13 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 17 21:23:13 volumio volumio[1368]: info: MyVolumio login type: Token Sep 17 21:23:13 volumio volumio[1368]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 17 21:23:14 volumio volumio[1368]: info: MyVolumio token set successfully Sep 17 21:23:14 volumio volumio[1368]: info: MYVOLUMIO: Adding device Sep 17 21:23:14 volumio volumio[1368]: info: MYVOLUMIO: Evaluating Server Sep 17 21:23:14 volumio volumio[1368]: info: MyVolumio status changed Sep 17 21:23:14 volumio volumio[1368]: info: Streaming services startup Sep 17 21:23:14 volumio volumio[1368]: info: Starting Streaming Daemon Sep 17 21:23:14 volumio volumio[1368]: info: Removing browser output: myVolumio user plan is not superstar Sep 17 21:23:14 volumio volumio[1368]: info: Removing audio output: Sep 17 21:23:14 volumio volumio[1368]: info: Stoppping Tunnel 1 Sep 17 21:23:14 volumio sudo[5714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 17 21:23:14 volumio sudo[5714]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:14 volumio sudo[5717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 17 21:23:14 volumio sudo[5717]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:14 volumio sudo[5714]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:14 volumio sudo[5717]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:14 volumio volumio[1368]: info: Remote SSH Stopped Sep 17 21:23:14 volumio volumio[1368]: error: Cannot start Volumio Streaming Daemon Sep 17 21:23:14 volumio volumio[1368]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 17 21:23:14 volumio volumio[1368]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 17 21:23:14 volumio volumio[1368]: info: Setting Geolocation for MyVolumio to us2 Sep 17 21:23:14 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:14 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:14 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:15 volumio volumio[1368]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 21:23:15 volumio ntpd[988]: 67.217.246.204 local addr 192.168.6.40 -> Sep 17 21:23:15 volumio volumio[1368]: info: Updating MyVolumio device info Sep 17 21:23:15 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:15 volumio volumio[1368]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 21:23:16 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 21:23:16 volumio volumio[1368]: info: Received Get System Info Sep 17 21:23:16 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:16 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:16 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:16 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:16 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:16 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:17 volumio sudo[5734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 21:23:17 volumio sudo[5734]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:17 volumio sudo[5734]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:17 volumio sudo[5737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 21:23:17 volumio sudo[5737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:17 volumio sudo[5737]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:17 volumio volumio[1368]: verbose: New Socket.io Connection to 192.168.6.40 from 192.168.6.3 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: Received Get System Info Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:17 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 21:23:17 volumio volumio[1368]: info: Received Get System Info Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:17 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: Listing playlists Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetQueue Sep 17 21:23:17 volumio volumio[1368]: info: CoreStateMachine::getQueue Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getQueue Sep 17 21:23:17 volumio volumio[1368]: info: Received Get System Info Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:17 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:17 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:17 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 17 21:23:18 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-DISCONNECTED bssid=40:47:5e:2d:3a:67 reason=0 locally_generated=1 Sep 17 21:23:18 volumio dhcpcd[1203]: wlan0: carrier lost Sep 17 21:23:18 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 17 21:23:18 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Sep 17 21:23:18 volumio avahi-daemon[830]: Withdrawing address record for 192.168.5.104 on wlan0. Sep 17 21:23:18 volumio avahi-daemon[830]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.5.104. Sep 17 21:23:18 volumio dhcpcd[1203]: wlan0: deleting route to 192.168.4.0/22 Sep 17 21:23:18 volumio dhcpcd[1203]: wlan0: deleting default route via 192.168.4.1 Sep 17 21:23:18 volumio avahi-daemon[830]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 17 21:23:18 volumio volumio[1368]: info: Discovery: A device disappeared from network Sep 17 21:23:18 volumio volumio[1368]: info: Discovery: Device volumio disappeared from network Sep 17 21:23:18 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:18 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:19 volumio volumio[1368]: info: MYVOLUMIO: Adding device Sep 17 21:23:19 volumio volumio[1368]: info: MYVOLUMIO: Evaluating Server Sep 17 21:23:19 volumio volumio[1368]: info: Setting Geolocation for MyVolumio to us2 Sep 17 21:23:19 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:19 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:19 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:20 volumio ntpd[988]: Deleting interface #9 wlan0, 192.168.5.104#123, interface stats: received=0, sent=0, dropped=0, active_time=37 secs Sep 17 21:23:20 volumio volumio[1368]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 21:23:20 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 21:23:20 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 17 21:23:20 volumio volumio[1368]: info: Updating MyVolumio device info Sep 17 21:23:20 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:20 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:20 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 21:23:20 volumio volumio[1368]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 21:23:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 21:23:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 21:23:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 21:23:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 21:23:24 volumio volumio[1368]: info: Volumio Network Manager: Network status updated: 1 Sep 17 21:23:24 volumio wpa_supplicant[1095]: wlan0: Trying to associate with SSID 'Q-Tronics' Sep 17 21:23:25 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 17 21:23:27 volumio volumio[1368]: info: [1758165807413] [Pandora] ExpireOldTracks::stop: Stopping. Sep 17 21:23:27 volumio volumio[1368]: info: [1758165807414] [Pandora] PreventAuthTimeout::stop: Stopping. Sep 17 21:23:27 volumio volumio[1368]: info: [1758165807414] ControllerPandora::flushPandora Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::getQueue Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::clearAddPlayQueue Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::saveQueue Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::serviceClearAddPlayTracks Sep 17 21:23:27 volumio volumio[1368]: error: WARNING: No clearAddPlayTracks method for service mpd Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::volumioPushQueue Sep 17 21:23:27 volumio volumio[1368]: info: [1758165807420] ControllerPandora::stop Sep 17 21:23:27 volumio volumio[1368]: info: ControllerMpd::stop Sep 17 21:23:27 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 21:23:27 volumio volumio[1368]: info: sendMpdCommand stop took 8 milliseconds Sep 17 21:23:27 volumio volumio[1368]: info: ControllerMpd::clear Sep 17 21:23:27 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 21:23:27 volumio volumio[1368]: info: Sep 17 21:23:27 volumio volumio[1368]: ---------------------------- MPD announces state update: player Sep 17 21:23:27 volumio volumio[1368]: info: Sep 17 21:23:27 volumio volumio[1368]: ---------------------------- MPD announces system playlist update Sep 17 21:23:27 volumio volumio[1368]: info: Ignoring MPD Status Update Sep 17 21:23:27 volumio volumio[1368]: info: ControllerMpd::getState Sep 17 21:23:27 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status Sep 17 21:23:27 volumio volumio[1368]: info: sendMpdCommand clear took 1 milliseconds Sep 17 21:23:27 volumio volumio[1368]: info: [1758165807429] ControllerPandora::pushState Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::servicePushState Sep 17 21:23:27 volumio volumio[1368]: info: CoreStateMachine::pushState Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:27 volumio volumio[1368]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received pandora Sep 17 21:23:27 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService pandora Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesPandora Radio Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 21:23:27 volumio volumio[1368]: Cannot find translation for source YouTube Music Sep 17 21:23:27 volumio volumio[1368]: info: Disabling plugin pandora Sep 17 21:23:27 volumio volumio[1368]: info: Done. Sep 17 21:23:27 volumio volumio[1368]: error: updateQueue error: null Sep 17 21:23:27 volumio volumio[1368]: info: sendMpdCommand status took 15 milliseconds Sep 17 21:23:27 volumio volumio[1368]: info: ------------------------------ 15ms Sep 17 21:23:27 volumio volumio[1368]: verbose: ControllerMpd::parseState Sep 17 21:23:27 volumio volumio[1368]: info: ControllerMpd::pushState Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::servicePushState Sep 17 21:23:27 volumio volumio[1368]: info: CoreStateMachine::pushState Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState Sep 17 21:23:27 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:27 volumio volumio[1368]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current pandora Received mpd Sep 17 21:23:27 volumio volumio[1368]: info: ------------------------------ 21ms Sep 17 21:23:27 volumio volumio[1368]: info: [ASDebug] CurState: play PrevState: play Sep 17 21:23:27 volumio volumio[1368]: info: [ASDebug] CurState: PrevState: play Sep 17 21:23:27 volumio volumio[1368]: info: [ASDebug] Togle GPIO: ON Sep 17 21:23:27 volumio volumio[1368]: info: PeppyMeterBasic ---peppymeterbasic status play Sep 17 21:23:27 volumio volumio[1368]: info: PeppyMeterBasic ---peppymeterbasic status Sep 17 21:23:27 volumio sudo[5816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Sep 17 21:23:27 volumio sudo[5816]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:27 volumio sudo[5816]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:27 volumio volumio[1368]: info: peppymeterbasic Daemon Started Sep 17 21:23:27 volumio volumio[1368]: info: touch_display: Setting screensaver timeout to 1200 seconds. Sep 17 21:23:27 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 17 21:23:29 volumio volumio[1368]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Sep 17 21:23:29 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 21:23:29 volumio volumio[1368]: info: Disabling plugin ytmusic Sep 17 21:23:29 volumio volumio[1368]: info: Done. Sep 17 21:23:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 21:23:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 21:23:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 21:23:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 21:23:30 volumio volumio[1368]: info: [ASDebug] Port: 24 Sep 17 21:23:30 volumio volumio[1368]: info: [ASDebug] Inverted: false Sep 17 21:23:30 volumio volumio[1368]: info: [ASDebug] Delay: 720 Sep 17 21:23:31 volumio wpa_supplicant[1095]: wlan0: Trying to associate with SSID 'Q-Tronics' Sep 17 21:23:32 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 17 21:23:32 volumio sudo[5825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Sep 17 21:23:32 volumio sudo[5825]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:32 volumio lircd[1719]: lircd-0.10.1[1719]: Notice: caught signal Sep 17 21:23:32 volumio lircd-0.10.1[1719]: Notice: caught signal Sep 17 21:23:32 volumio systemd[1]: Stopping Flexible IR remote input/output application support... Sep 17 21:23:32 volumio systemd[1]: irexec.service: Succeeded. Sep 17 21:23:32 volumio systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Sep 17 21:23:32 volumio systemd[1]: lircd.service: Succeeded. Sep 17 21:23:32 volumio systemd[1]: Stopped Flexible IR remote input/output application support. Sep 17 21:23:32 volumio sudo[5825]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:32 volumio volumio[1368]: info: ir_controller: systemctl stop lircd.service succeeded. Sep 17 21:23:32 volumio volumio[1368]: info: Disabling plugin ir_controller Sep 17 21:23:32 volumio volumio[1368]: info: Done. Sep 17 21:23:32 volumio sudo[5830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Sep 17 21:23:32 volumio sudo[5830]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:32 volumio sudo[5830]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:32 volumio volumio[1368]: info: ir_controller: gpio-ir overlay removed. Sep 17 21:23:34 volumio volumio[1368]: info: Enabling plugin ampswitch Sep 17 21:23:34 volumio volumio[1368]: info: Loading plugin "ampswitch"... Sep 17 21:23:34 volumio volumio[1368]: info: Applying required configuration parameters for plugin ampswitch Sep 17 21:23:34 volumio volumio[1368]: info: PLUGIN START: ampswitch Sep 17 21:23:34 volumio volumio[1368]: info: Done. Sep 17 21:23:34 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:34 volumio volumio[1368]: info: [ASDebug] CurState: undefined PrevState: Sep 17 21:23:34 volumio volumio[1368]: info: [ASDebug] Togle GPIO: ON Sep 17 21:23:34 volumio volumio[1368]: info: [ASDebug] CurState: undefined PrevState: undefined Sep 17 21:23:34 volumio volumio[1368]: info: [ASDebug] CurState: undefined PrevState: undefined Sep 17 21:23:35 volumio volumio[1368]: info: [ASDebug] Port: 24 Sep 17 21:23:35 volumio volumio[1368]: info: [ASDebug] Inverted: false Sep 17 21:23:35 volumio volumio[1368]: info: [ASDebug] Delay: 720 Sep 17 21:23:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 21:23:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 21:23:36 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 21:23:36 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 21:23:36 volumio volumio[1368]: info: Discovery: Getting this device information Sep 17 21:23:36 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:36 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 21:23:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 21:23:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 21:23:39 volumio wpa_supplicant[1095]: wlan0: Trying to associate with SSID 'Q-Tronics' Sep 17 21:23:41 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 17 21:23:41 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Q-Tronics" auth_failures=1 duration=10 reason=CONN_FAILED Sep 17 21:23:43 volumio volumio[1368]: info: CoreCommandRouter::volumioGetState Sep 17 21:23:44 volumio volumio[1368]: info: CoreCommandRouter::volumioPlay Sep 17 21:23:44 volumio volumio[1368]: info: CoreStateMachine::play index undefined Sep 17 21:23:44 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:44 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:46 volumio volumio[1368]: info: CoreCommandRouter::volumioPlay Sep 17 21:23:46 volumio volumio[1368]: info: CoreStateMachine::play index undefined Sep 17 21:23:46 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:46 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Sep 17 21:23:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Sep 17 21:23:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Sep 17 21:23:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Sep 17 21:23:52 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:52 volumio volumio[1368]: info: CorePlayQueue::getTrack 22 Sep 17 21:23:52 volumio volumio[1368]: info: Prefetching next song Sep 17 21:23:52 volumio volumio[1368]: info: DOING PREFETCH IN MPD Sep 17 21:23:52 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand add "NAS/Studio-Albums/Billy Joel/(1985) Greatest Hits/2-11 - Uptown Girl.flac" Sep 17 21:23:52 volumio volumio[1368]: info: Sep 17 21:23:52 volumio volumio[1368]: ---------------------------- MPD announces system playlist update Sep 17 21:23:52 volumio volumio[1368]: info: Ignoring MPD Status Update Sep 17 21:23:52 volumio volumio[1368]: info: sendMpdCommand add "NAS/Studio-Albums/Billy Joel/(1985) Greatest Hits/2-11 - Uptown Girl.flac" took 1 milliseconds Sep 17 21:23:52 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 17 21:23:52 volumio volumio[1368]: info: ------------------------------ 1ms Sep 17 21:23:52 volumio volumio[1368]: info: sendMpdCommand consume 1 took 0 milliseconds Sep 17 21:23:52 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Q-Tronics" Sep 17 21:23:52 volumio wpa_supplicant[1095]: wlan0: Trying to associate with SSID 'Q-Tronics' Sep 17 21:23:52 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 17 21:23:52 volumio wpa_supplicant[1095]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Q-Tronics" auth_failures=2 duration=29 reason=CONN_FAILED Sep 17 21:23:55 volumio volumio[1368]: info: Preload queue cleared Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::ClearQueue Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::stop Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::stPlaybackTimer Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::updateTrackBlock Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrackBlock Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::pushState Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::serviceStop Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 24 Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::serviceStop Sep 17 21:23:55 volumio volumio[1368]: info: ControllerMpd::stop Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::clearPlayQueue Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::saveQueue Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPushQueue Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::addQueueItems Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::addQueueItems Sep 17 21:23:55 volumio volumio[1368]: info: Preload queue cleared Sep 17 21:23:55 volumio volumio[1368]: info: Adding Item to queue: music-library/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III Sep 17 21:23:55 volumio volumio[1368]: info: Exploding uri music-library/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III in service mpd Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] CurState: stop PrevState: undefined Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] CurState: stop PrevState: undefined Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Sep 17 21:23:55 volumio volumio[1368]: info: PeppyMeterBasic ---peppymeterbasic status stop Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-01%20-%20Keeping%20the%20Faith%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-02%20-%20An%20Innocent%20Man%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-02 - An Innocent Man (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-03%20-%20A%20Matter%20of%20Trust%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-03 - A Matter of Trust (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Ray%20Charles/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-04%20-%20Baby%20Grand%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-04 - Baby Grand (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-05%20-%20This%20Is%20the%20Time%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-05 - This Is the Time (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-06%20-%20Leningrad%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-06 - Leningrad (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-07%20-%20We%20Didn't%20Start%20the%20Fire%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-07 - We Didn't Start the Fire (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-08%20-%20I%20Go%20to%20Extremes%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-08 - I Go to Extremes (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-09%20-%20And%20So%20It%20Goes%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-09 - And So It Goes (Album Version).flac Sep 17 21:23:55 volumio sudo[5939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-10%20-%20The%20Downeaster%20'Alexa'%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-10 - The Downeaster 'Alexa' (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-11%20-%20Shameless%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-11 - Shameless (Album Version).flac Sep 17 21:23:55 volumio sudo[5939]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-12%20-%20All%20About%20Soul%20(Remix).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-12 - All About Soul (Remix).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-13%20-%20Lullabye%20(Goodnight%2C%20My%20Angel)%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-13 - Lullabye (Goodnight, My Angel) (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-14%20-%20The%20River%20of%20Dreams%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-14 - The River of Dreams (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-15%20-%20To%20Make%20You%20Feel%20My%20Love%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-15 - To Make You Feel My Love (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-16%20-%20Hey%20Girl%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-16 - Hey Girl (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: ALBUMART /albumart?cacheid=645&web=Billy%20Joel/Greatest%20Hits%20Vol.%20III/extralarge&path=%2Fmnt%2FNAS%2FStudio-Albums%2FBilly%20Joel%2F(1997)%20Greatest%20Hits%20Vol.%20III%2F1-17%20-%20Light%20As%20the%20Breeze%20(Album%20Version).flac&metadata=false Sep 17 21:23:55 volumio volumio[1368]: info: URI /mnt/NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-17 - Light As the Breeze (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand stop took 54 milliseconds Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPushQueue Sep 17 21:23:55 volumio systemd[1]: Stopping peppymeterbasic Daemon... Sep 17 21:23:55 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::saveQueue Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::updateTrackBlock Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrackBlock Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPlay Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::play index 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::stop Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::play index undefined Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::startPlaybackTimer Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::clearAddPlayTracks NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand stop took 3 milliseconds Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand clear Sep 17 21:23:55 volumio volumio[1368]: info: Sep 17 21:23:55 volumio volumio[1368]: ---------------------------- MPD announces system playlist update Sep 17 21:23:55 volumio volumio[1368]: info: Ignoring MPD Status Update Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand clear took 2 milliseconds Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand add "NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac" Sep 17 21:23:55 volumio volumio[1368]: error: updateQueue error: null Sep 17 21:23:55 volumio volumio[1368]: info: ------------------------------ 0ms Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand add "NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac" took 0 milliseconds Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand play Sep 17 21:23:55 volumio volumio[1368]: info: Sep 17 21:23:55 volumio volumio[1368]: ---------------------------- MPD announces system playlist update Sep 17 21:23:55 volumio volumio[1368]: info: Ignoring MPD Status Update Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand play took 1 milliseconds Sep 17 21:23:55 volumio volumio[1368]: info: ------------------------------ 0ms Sep 17 21:23:55 volumio volumio[1368]: info: Sep 17 21:23:55 volumio volumio[1368]: ---------------------------- MPD announces state update: player Sep 17 21:23:55 volumio volumio[1368]: info: ControllerMpd::getState Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status Sep 17 21:23:55 volumio volumio[1368]: info: Sep 17 21:23:55 volumio volumio[1368]: ---------------------------- MPD announces state update: player Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand status took 25 milliseconds Sep 17 21:23:55 volumio volumio[1368]: info: ControllerMpd::getState Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand status Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::parseState Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand status took 14 milliseconds Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::parseState Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::parseTrackInfo Sep 17 21:23:55 volumio volumio[1368]: info: ControllerMpd::pushState Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::servicePushState Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2421 Kbps","isStreaming":false,"title":"Keeping the Faith (Album Version)","artist":"Billy Joel","album":"Greatest Hits Vol. III","uri":"NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac","trackType":"flac"} Sep 17 21:23:55 volumio volumio[1368]: verbose: CURRENT POSITION 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::syncState stateService play Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::syncState currentStatus stop Sep 17 21:23:55 volumio volumio[1368]: info: ------------------------------ 41ms Sep 17 21:23:55 volumio volumio[1368]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 17 21:23:55 volumio volumio[1368]: verbose: ControllerMpd::parseTrackInfo Sep 17 21:23:55 volumio volumio[1368]: info: ControllerMpd::pushState Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::servicePushState Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":692,"duration":279,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2712 Kbps","isStreaming":false,"title":"Keeping the Faith (Album Version)","artist":"Billy Joel","album":"Greatest Hits Vol. III","uri":"NAS/Studio-Albums/Billy Joel/(1997) Greatest Hits Vol. III/1-01 - Keeping the Faith (Album Version).flac","trackType":"flac"} Sep 17 21:23:55 volumio volumio[1368]: verbose: CURRENT POSITION 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::syncState stateService play Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::syncState currentStatus play Sep 17 21:23:55 volumio volumio[1368]: info: Received an update from plugin. extracting info from payload Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::pushState Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState Sep 17 21:23:55 volumio volumio[1368]: info: CoreStateMachine::pushState Sep 17 21:23:55 volumio volumio[1368]: info: CorePlayQueue::getTrack 0 Sep 17 21:23:55 volumio volumio[1368]: info: CoreCommandRouter::volumioPushState Sep 17 21:23:55 volumio volumio[1368]: info: ------------------------------ 27ms Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] CurState: play PrevState: undefined Sep 17 21:23:55 volumio volumio[1368]: info: [ASDebug] Togle GPIO: ON Sep 17 21:23:55 volumio volumio[1368]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 21:23:55 volumio volumio[1368]: Error: ESPIPE: invalid seek, write Sep 17 21:23:55 volumio volumio[1368]: at Object.writeSync (fs.js:711:3) Sep 17 21:23:55 volumio volumio[1368]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Sep 17 21:23:55 volumio volumio[1368]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Sep 17 21:23:55 volumio volumio[1368]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Sep 17 21:23:55 volumio volumio[1368]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Sep 17 21:23:55 volumio volumio[1368]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Sep 17 21:23:55 volumio volumio[1368]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Sep 17 21:23:55 volumio volumio[1368]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Sep 17 21:23:55 volumio volumio[1368]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Sep 17 21:23:55 volumio volumio[1368]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) { Sep 17 21:23:55 volumio volumio[1368]: errno: -29, Sep 17 21:23:55 volumio volumio[1368]: syscall: 'write', Sep 17 21:23:55 volumio volumio[1368]: code: 'ESPIPE' Sep 17 21:23:55 volumio volumio[1368]: } Sep 17 21:23:55 volumio volumio[1368]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 21:23:55 volumio systemd[1]: peppymeterbasic.service: Succeeded. Sep 17 21:23:55 volumio systemd[1]: Stopped peppymeterbasic Daemon. Sep 17 21:23:55 volumio sudo[5939]: pam_unix(sudo:session): session closed for user root Sep 17 21:23:55 volumio sudo[5951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 21:22 Sep 17 21:23:55 volumio sudo[5951]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"