-- Logs begin at Wed 2025-04-16 14:45:38 BST, end at Wed 2025-04-16 21:08:07 BST. --
Apr 16 21:07:01 volumiojbah wpa_supplicant[911]: wlan0: Failed to initiate sched scan
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52
Apr 16 21:07:01 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52
Apr 16 21:07:10 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52
Apr 16 21:07:10 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52
Apr 16 21:07:10 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52
Apr 16 21:07:10 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52
Apr 16 21:07:12 volumiojbah wpa_supplicant[911]: wlan0: Failed to initiate sched scan
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52
Apr 16 21:07:12 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52
Apr 16 21:07:17 volumiojbah ntpd[805]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Apr 16 21:07:21 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52
Apr 16 21:07:21 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52
Apr 16 21:07:21 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52
Apr 16 21:07:21 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52
Apr 16 21:07:23 volumiojbah wpa_supplicant[911]: wlan0: Trying to associate with SSID 'Pixel 8 '
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52
Apr 16 21:07:23 volumiojbah kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: carrier acquired
Apr 16 21:07:23 volumiojbah wpa_supplicant[911]: wlan0: Associated with b6:3f:b6:36:c9:6a
Apr 16 21:07:23 volumiojbah wpa_supplicant[911]: wlan0: CTRL-EVENT-CONNECTED - Connection to b6:3f:b6:36:c9:6a completed [id=0 id_str=]
Apr 16 21:07:23 volumiojbah wpa_supplicant[911]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 16 21:07:23 volumiojbah wpa_supplicant[911]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: IAID 32:79:44:b6
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: carrier lost
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: carrier acquired
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: IAID 32:79:44:b6
Apr 16 21:07:23 volumiojbah dhcpcd[923]: wlan0: soliciting an IPv6 router
Apr 16 21:07:24 volumiojbah dhcpcd[923]: wlan0: soliciting a DHCP lease
Apr 16 21:07:24 volumiojbah dhcpcd[923]: wlan0: probing address 10.24.25.39/24
Apr 16 21:07:29 volumiojbah dhcpcd[923]: wlan0: leased 10.24.25.39 for 3599 seconds
Apr 16 21:07:29 volumiojbah avahi-daemon[630]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.24.25.39.
Apr 16 21:07:29 volumiojbah avahi-daemon[630]: New relevant interface wlan0.IPv4 for mDNS.
Apr 16 21:07:29 volumiojbah dhcpcd[923]: wlan0: adding route to 10.24.25.0/24
Apr 16 21:07:29 volumiojbah dhcpcd[923]: wlan0: adding default route via 10.24.25.102
Apr 16 21:07:29 volumiojbah avahi-daemon[630]: Registering new address record for 10.24.25.39 on wlan0.IPv4.
Apr 16 21:07:30 volumiojbah ntpd[805]: Listen normally on 11 wlan0 10.24.25.39:123
Apr 16 21:07:30 volumiojbah ntpd[805]: new interface(s) found: waking up resolver
Apr 16 21:07:35 volumiojbah volumio[1012]: verbose: New Socket.io Connection to 10.24.25.39 from 10.24.25.102 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetQueue
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreStateMachine::getQueue
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CorePlayQueue::getQueue
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Listing playlists
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Received Get System Info
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Discovery: Getting this device information
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 16 21:07:35 volumiojbah volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Tunnel connection is inactive, restarting it
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Starting Tunnel 1
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Starting Tunnel Connection Checker
Apr 16 21:07:35 volumiojbah sudo[27171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Apr 16 21:07:35 volumiojbah sudo[27171]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 16 21:07:35 volumiojbah systemd[1]: Started MyVolumio SSH Tunnel.
Apr 16 21:07:35 volumiojbah sudo[27171]: pam_unix(sudo:session): session closed for user root
Apr 16 21:07:35 volumiojbah volumio[1012]: info: Remote SSH Started
Apr 16 21:07:35 volumiojbah autossh[27174]: port set to 0, monitoring disabled
Apr 16 21:07:35 volumiojbah autossh[27174]: starting ssh (count 1)
Apr 16 21:07:35 volumiojbah autossh[27174]: ssh child pid is 27177
Apr 16 21:07:36 volumiojbah volumio[1012]: info: Volumio Network Manager: Network status updated: 2
Apr 16 21:07:36 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 16 21:07:37 volumiojbah volumio[1012]: info: Received Get System Info
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 16 21:07:37 volumiojbah volumio[1012]: info: Discovery: Getting this device information
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 16 21:07:37 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Apr 16 21:07:39 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri
Apr 16 21:07:41 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri
Apr 16 21:07:45 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 16 21:07:50 volumiojbah volumio[1012]: info: Discovery: adding d54f834c-dcbd-4a6c-bdb4-a4219b379738
Apr 16 21:07:50 volumiojbah volumio[1012]: info: Discovery: Found device VolumioJbah
Apr 16 21:07:50 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:50 volumiojbah volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Apr 16 21:07:57 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:07:57 volumiojbah volumiossh-tunnel[27173]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts.
Apr 16 21:07:58 volumiojbah ntpd[805]: Soliciting pool server 85.199.214.98
Apr 16 21:08:02 volumiojbah volumio[1012]: info: browseTIDALUri took 21510 milliseconds
Apr 16 21:08:02 volumiojbah volumio[1012]: info: Preload queue cleared
Apr 16 21:08:02 volumiojbah volumio[1012]: info: Preload queue cleared
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 16 21:08:04 volumiojbah volumio[1012]: info: Received Get System Version
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 16 21:08:04 volumiojbah volumio[1012]: info: Received Get System Info
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 16 21:08:04 volumiojbah volumio[1012]: info: Discovery: Getting this device information
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::volumioGetState
Apr 16 21:08:04 volumiojbah volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 16 21:08:07 volumiojbah volumio[1012]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 21:08:07 volumiojbah volumio[1012]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] {
Apr 16 21:08:07 volumiojbah volumio[1012]: code: 'auth/network-request-failed',
Apr 16 21:08:07 volumiojbah volumio[1012]: a: null
Apr 16 21:08:07 volumiojbah volumio[1012]: }
Apr 16 21:08:07 volumiojbah volumio[1012]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 16 21:08:07 volumiojbah sudo[27288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-16 21:07
Apr 16 21:08:07 volumiojbah sudo[27288]: 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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST"
VOLUMIO_VERSION="3.799"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"