-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2026-02-24 18:10:53 CET. --
Feb 24 18:09:01 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 24 18:09:01 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:09:01 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:09:01 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:09:01 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:09:01 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:01 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:01 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:09:02 volumio volumio[12558]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 24 18:09:02 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Feb 24 18:09:03 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 24 18:09:03 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:03 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:03 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Feb 24 18:09:04 volumio sudo[13138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Feb 24 18:09:04 volumio sudo[13138]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13138]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio sudo[13145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 24 18:09:04 volumio sudo[13145]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13145]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio sudo[13153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 24 18:09:04 volumio sudo[13153]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13153]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio sudo[13158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 24 18:09:04 volumio sudo[13158]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13158]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio sudo[13164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 24 18:09:04 volumio sudo[13164]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13164]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Feb 24 18:09:04 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Feb 24 18:09:04 volumio sudo[13167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:09:04 volumio sudo[13167]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:04 volumio sudo[13167]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:04 volumio sudo[13170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Feb 24 18:09:04 volumio sudo[13170]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:07 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Feb 24 18:09:07 volumio sudo[13170]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:08 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:09:08 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:08 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:09:08 volumio volumio5-onboarding[1663]: time=2026-02-24T18:09:08.427+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=true macAddress=dc:a6:32:b2:8a:70 ip4Address=192.168.1.115/24 ip6Address= ssid=WebPocket-A432
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:09:08 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin multiroom to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin qobuzconnect to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 24 18:09:08 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 24 18:09:09 volumio volumio[12558]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 24 18:09:09 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 24 18:09:09 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:09 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:09 volumio volumio[12558]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 24 18:09:09 volumio volumio[12558]: info: MyVolumio login type: Token
Feb 24 18:09:09 volumio volumio[12558]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 24 18:09:09 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 24 18:09:10 volumio volumio[12558]: info: Starting Streaming Service Transparent Proxy
Feb 24 18:09:10 volumio volumio[12558]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 24 18:09:10 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 24 18:09:10 volumio volumio[12558]: info: Streaming services startup
Feb 24 18:09:10 volumio volumio[12558]: info: Starting Streaming Daemon
Feb 24 18:09:10 volumio sudo[13231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 24 18:09:10 volumio sudo[13231]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:10 volumio sudo[13231]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:10 volumio volumio[12558]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 24 18:09:10 volumio volumio[12558]: error: Cannot start Volumio Streaming Daemon
Feb 24 18:09:10 volumio volumio[12558]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 24 18:09:10 volumio volumio[12558]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 24 18:09:10 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:10 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:10 volumio volumio[12558]: error: MyVolumio Custom Token format not valid, refreshing it
Feb 24 18:09:10 volumio volumio[12558]: STREAMING PROXY: Starting server on port 3245
Feb 24 18:09:10 volumio volumio[12558]: Node JS runtime: 14
Feb 24 18:09:11 volumio volumio[12558]: info: MyVolumio login type: Token
Feb 24 18:09:12 volumio volumio[12558]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Feb 24 18:09:12 volumio volumio[12558]: info: MyVolumio token set successfully
Feb 24 18:09:12 volumio volumio[12558]: info: MYVOLUMIO: Adding device
Feb 24 18:09:12 volumio volumio[12558]: info: MYVOLUMIO: Evaluating Server
Feb 24 18:09:13 volumio volumio[12558]: info: Upgrading User Account from device code
Feb 24 18:09:13 volumio volumio[12558]: info: MyVolumio Plan changed: premium
Feb 24 18:09:13 volumio volumio[12558]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Feb 24 18:09:13 volumio volumio[12558]: info: Removing browser output: myVolumio user plan is not superstar
Feb 24 18:09:13 volumio volumio[12558]: info: Removing audio output:
Feb 24 18:09:13 volumio volumio[12558]: info: MYVOLUMIO: Adding device
Feb 24 18:09:13 volumio volumio[12558]: info: MYVOLUMIO: Evaluating Server
Feb 24 18:09:13 volumio volumio[12558]: info: Remote config written successfully
Feb 24 18:09:13 volumio volumio[12558]: info: Starting Tunnel 1
Feb 24 18:09:13 volumio volumio[12558]: info: Starting Tunnel Connection Checker
Feb 24 18:09:13 volumio volumio[12558]: info: Completed starting MyVolumio Plugin
Feb 24 18:09:14 volumio systemd[1]: systemd-timedated.service: Succeeded.
Feb 24 18:09:14 volumio volumio[12558]: info: MYVolumio Device enabled
Feb 24 18:09:14 volumio volumio[12558]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Feb 24 18:09:14 volumio volumio[12558]: info: MyVolumio status changed
Feb 24 18:09:14 volumio volumio[12558]: info: Streaming services startup
Feb 24 18:09:14 volumio volumio[12558]: info: Starting Streaming Daemon
Feb 24 18:09:14 volumio sudo[13281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 24 18:09:14 volumio sudo[13281]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:14 volumio sudo[13281]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:14 volumio volumio[12558]: info: Setting Geolocation for MyVolumio to eu3
Feb 24 18:09:14 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:14 volumio volumio[12558]: error: Could not upgrade user account. Error: Device not activated
Feb 24 18:09:14 volumio volumio[12558]: error: Cannot start Volumio Streaming Daemon
Feb 24 18:09:14 volumio volumio[12558]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 24 18:09:14 volumio volumio[12558]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 24 18:09:14 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Feb 24 18:09:14 volumio volumio[12558]: error: [MyVolumio PluginManager] Cache data is invalid!
Feb 24 18:09:14 volumio volumio[12558]: info: Setting Geolocation for MyVolumio to eu9
Feb 24 18:09:14 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:14 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:14 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: Successfully Added MyVolumio device
Feb 24 18:09:15 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:15 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:15 volumio volumio[12558]: info: Updating MyVolumio device info
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: Updating MyVolumio device info
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:15 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Feb 24 18:09:15 volumio volumio[12558]: info: [1771952955944] Starting BluetoothController
Feb 24 18:09:16 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Feb 24 18:09:17 volumio sudo[13286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Feb 24 18:09:17 volumio sudo[13286]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13286]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: MultiRoom plugin initialized
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: STOPPING SNAPCLIENT
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: Snap server stop
Feb 24 18:09:17 volumio sudo[13302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Feb 24 18:09:17 volumio sudo[13302]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: STOPPING volumioStreaming
Feb 24 18:09:17 volumio sudo[13302]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio sudo[13305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Feb 24 18:09:17 volumio sudo[13305]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13305]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio sudo[13308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Feb 24 18:09:17 volumio sudo[13308]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Feb 24 18:09:17 volumio sudo[13308]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio sudo[13311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Feb 24 18:09:17 volumio sudo[13311]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13311]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Feb 24 18:09:17 volumio volumio[12558]: info: Preparing to generate the ALSA configuration file
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Feb 24 18:09:17 volumio volumio[12558]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 24 18:09:17 volumio volumio[12558]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Feb 24 18:09:17 volumio volumio[12558]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf
Feb 24 18:09:17 volumio volumio[12558]: info: Reading ALSA contributions from plugins.
Feb 24 18:09:17 volumio volumio[12558]: info: Successfully Added MyVolumio device
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 24 18:09:17 volumio volumio[12558]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 24 18:09:17 volumio volumio[12558]: info: Received Get System Version
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 24 18:09:17 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:09:17 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:17 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:09:17 volumio volumio[12558]: info: Successfully Updated MyVolumio device
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: Removed streaming files
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: volumioStreaming STOPPED
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: SNAPSERVER STOPPED
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: SNAPCLIENT STOPPED
Feb 24 18:09:17 volumio volumio[12558]: info: Asound.conf file written
Feb 24 18:09:17 volumio sudo[13314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 24 18:09:17 volumio sudo[13314]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13314]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: Output device has changed, restarting MPD
Feb 24 18:09:17 volumio sudo[13319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 24 18:09:17 volumio sudo[13319]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13319]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: Output device has changed, restarting Shairport Sync
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:17 volumio sudo[13322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 24 18:09:17 volumio sudo[13322]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio systemd[1]: Stopping Music Player Daemon...
Feb 24 18:09:17 volumio volumio[12558]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 24 18:09:17 volumio systemd[1]: mpd.service: Succeeded.
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:17 volumio systemd[1]: Stopped Music Player Daemon.
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 24 18:09:17 volumio systemd[1]: Starting Music Player Daemon...
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Feb 24 18:09:17 volumio sudo[13331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio
Feb 24 18:09:17 volumio sudo[13331]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13331]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Feb 24 18:09:17 volumio volumio[12558]: info: Adding METAVOLUMIO REST API Endpoints
Feb 24 18:09:17 volumio volumio[12558]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Feb 24 18:09:17 volumio volumio[12558]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Feb 24 18:09:17 volumio volumio[12558]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Feb 24 18:09:17 volumio volumio[12558]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Feb 24 18:09:17 volumio volumio[12558]: info: Preparing CD Folders
Feb 24 18:09:17 volumio volumio[12558]: info: Adding CD REST API Endpoints
Feb 24 18:09:17 volumio volumio[12558]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Feb 24 18:09:17 volumio volumio[12558]: info: Starting UDEV Watcher for CD
Feb 24 18:09:17 volumio volumio[12558]: info: Detecting CD presence with UDEV
Feb 24 18:09:17 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Feb 24 18:09:17 volumio sudo[13327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 24 18:09:17 volumio sudo[13327]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:17 volumio sudo[13327]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:17 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Feb 24 18:09:17 volumio volumio[12558]: info: Adding inputs REST Endpoints
Feb 24 18:09:17 volumio volumio[12558]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Feb 24 18:09:17 volumio volumio[12558]: info: Scanning Audio Inputs
Feb 24 18:09:18 volumio volumio[12558]: info: Checking against Known Cards name
Feb 24 18:09:18 volumio volumio[12558]: info: Checking against Known Cards name
Feb 24 18:09:18 volumio volumio[12558]: info: Adding Server instance for streaming
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Feb 24 18:09:18 volumio volumio[12558]: error: Hi Res Audio Failed Login: Missing Login Data
Feb 24 18:09:18 volumio volumio[12558]: info: Adding HIGHRESAUDIO REST API Endpoints
Feb 24 18:09:18 volumio volumio[12558]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Feb 24 18:09:18 volumio volumio[12558]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Feb 24 18:09:18 volumio volumio[12558]: info: Refreshing TIDAL token
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Feb 24 18:09:18 volumio volumio[12558]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting
Feb 24 18:09:18 volumio volumio[12558]: info: Stopping AccessToken refresher cron for QOBUZ
Feb 24 18:09:18 volumio sudo[13341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Feb 24 18:09:18 volumio sudo[13341]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:18 volumio volumio[12558]: info: AccessToken refresher cron started for QOBUZ
Feb 24 18:09:18 volumio volumio[12558]: info: Adding QOBUZ REST API Endpoints
Feb 24 18:09:18 volumio volumio[12558]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Feb 24 18:09:18 volumio sudo[13341]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:18 volumio volumio[12558]: info: Successfully Updated MyVolumio device
Feb 24 18:09:18 volumio volumio[12558]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Feb 24 18:09:18 volumio volumio[12558]: info: Bluetooth name changed to Volumio
Feb 24 18:09:18 volumio volumio[12558]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Feb 24 18:09:18 volumio volumio[12558]: info: MPD Permissions set
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:18 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:18 volumio volumio[12558]: info: Starting Shairport Sync
Feb 24 18:09:18 volumio volumio[12558]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:18 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:18 volumio volumio[12558]: info: CoreCommandRouter::volumioPause
Feb 24 18:09:18 volumio volumio[12558]: info: CoreStateMachine::pause
Feb 24 18:09:18 volumio volumio[12558]: info: mpdhttpout --- Volumio set on pause
Feb 24 18:09:18 volumio sudo[13349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 24 18:09:18 volumio sudo[13349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 24 18:09:18 volumio systemd[1]: shairport-sync.service: Succeeded.
Feb 24 18:09:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 24 18:09:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 24 18:09:18 volumio sudo[13349]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:18 volumio volumio[12558]: info: Shairport-Sync Started
Feb 24 18:09:18 volumio mpd[13337]: Feb 24 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 24 18:09:18 volumio volumio[12558]: info: Access Token successfully retrieved
Feb 24 18:09:18 volumio systemd[1]: Started Music Player Daemon.
Feb 24 18:09:18 volumio sudo[13322]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:18 volumio volumio[12558]: error: updateQueue error: null
Feb 24 18:09:19 volumio volumio[12558]: info: Successfully retrieved User Session From TIDAL
Feb 24 18:09:19 volumio sudo[13375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Feb 24 18:09:19 volumio sudo[13375]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:19 volumio systemd[1]: Started MyVolumio SSH Tunnel.
Feb 24 18:09:19 volumio sudo[13375]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:19 volumio volumio[12558]: info: Remote SSH Started
Feb 24 18:09:19 volumio autossh[13378]: port set to 0, monitoring disabled
Feb 24 18:09:19 volumio autossh[13378]: starting ssh (count 1)
Feb 24 18:09:19 volumio autossh[13378]: ssh child pid is 13381
Feb 24 18:09:19 volumio volumio[12558]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:19 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 24 18:09:19 volumio volumio[12558]: info: Successfully retrieved User Subscription From TIDAL
Feb 24 18:09:19 volumio volumio[12558]: info: Adding TIDAL to Browse Sources
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 24 18:09:19 volumio volumio[12558]: info: [1771952959387] CoreMusicLibrary::Adding element TIDAL
Feb 24 18:09:19 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source Bandcamp Discover
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source Calm Radio
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source Jellyfin
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source YouTube2
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source YouTube Music
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source 80s80s Radio
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source SWARM Station
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source Volusonic
Feb 24 18:09:19 volumio volumio[12558]: Cannot find translation for source TIDAL
Feb 24 18:09:19 volumio volumio[12558]: info: Adding TIDAL REST API Endpoints
Feb 24 18:09:19 volumio volumio[12558]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Feb 24 18:09:19 volumio volumiossh-tunnel[13377]: Warning: Permanently added '[eu9.myvolumio.org]:2222,[164.92.213.40]:2222' (RSA) to the list of known hosts.
Feb 24 18:09:20 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Feb 24 18:09:20 volumio sudo[13383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 24 18:09:20 volumio sudo[13383]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:20 volumio systemd[1]: Stopping Music Player Daemon...
Feb 24 18:09:20 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:20 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:20 volumio systemd[1]: mpd.service: Succeeded.
Feb 24 18:09:20 volumio systemd[1]: Stopped Music Player Daemon.
Feb 24 18:09:20 volumio systemd[1]: Starting Music Player Daemon...
Feb 24 18:09:20 volumio sudo[13385]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 24 18:09:20 volumio sudo[13385]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:20 volumio sudo[13385]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:20 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:20 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:20 volumio sudo[13392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Feb 24 18:09:20 volumio sudo[13392]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:21 volumio systemd[1]: Started Volumio Bluetooth Module.
Feb 24 18:09:21 volumio sudo[13392]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio volumio[12558]: info: Volumio BT Module successfully started
Feb 24 18:09:21 volumio sudo[13395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
Feb 24 18:09:21 volumio sudo[13395]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:21 volumio volumiobt[13394]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
Feb 24 18:09:21 volumio sudo[13395]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio volumio[12558]: info: TidalConnect service stoped!
Feb 24 18:09:21 volumio volumio[12558]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Feb 24 18:09:21 volumio volumio[12558]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Feb 24 18:09:21 volumio mpd[13389]: Feb 24 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 24 18:09:21 volumio sudo[13407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Feb 24 18:09:21 volumio sudo[13407]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:21 volumio systemd[1]: Started Volumio Tidal Connect Service.
Feb 24 18:09:21 volumio sudo[13407]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio systemd[1]: Started Music Player Daemon.
Feb 24 18:09:21 volumio sudo[13383]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio volumio[12558]: error: updateQueue error: null
Feb 24 18:09:21 volumio volumio[12558]: info: Executing endpoint tc_getconfig
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Feb 24 18:09:21 volumio vtcs[13409]: STARTING TidalConnect services, version: 1.6.1
Feb 24 18:09:21 volumio vtcs[13409]: STARTED TidalConnect services.
Feb 24 18:09:21 volumio volumio[12558]: info: Executing endpoint tc_connect
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Feb 24 18:09:21 volumio volumio[12558]: info: Connecting to TidalConnect
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::servicePushState
Feb 24 18:09:21 volumio volumio[12558]: info: CoreStateMachine::pushState
Feb 24 18:09:21 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::volumioPushState
Feb 24 18:09:21 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:21 volumio volumio[12558]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::servicePushState
Feb 24 18:09:21 volumio volumio[12558]: info: CoreStateMachine::pushState
Feb 24 18:09:21 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:21 volumio volumio[12558]: info: CoreCommandRouter::volumioPushState
Feb 24 18:09:21 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:21 volumio volumio[12558]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect
Feb 24 18:09:21 volumio volumio5-onboarding[1663]: time=2026-02-24T18:09:21.595+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" state=STATUS_STOPPED positionMs=0 volume=100
Feb 24 18:09:21 volumio volumio5-onboarding[1663]: time=2026-02-24T18:09:21.597+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" state=STATUS_STOPPED positionMs=0 volume=100
Feb 24 18:09:21 volumio volumio5-onboarding[1663]: time=2026-02-24T18:09:21.597+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" id="music-library/USB/Lexar/J A Z Z/V.A. - The Best Jazz Audiophile Recommended (2012) {HDCD}/Vol.1 [HDCD 0003]/01. Crazy.flac" title=Crazy
Feb 24 18:09:21 volumio volumio5-onboarding[1663]: time=2026-02-24T18:09:21.598+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" id="music-library/USB/Lexar/J A Z Z/V.A. - The Best Jazz Audiophile Recommended (2012) {HDCD}/Vol.1 [HDCD 0003]/01. Crazy.flac" title=Crazy
Feb 24 18:09:21 volumio volumio[12558]: info: [ASDebug] CurState: stop PrevState: stop
Feb 24 18:09:21 volumio volumio[12558]: info: [ASDebug] CurState: stop PrevState: stop
Feb 24 18:09:21 volumio volumio[12558]: info: PeppySpectrum ---peppyspectrum status stop
Feb 24 18:09:21 volumio volumio[12558]: info: PeppySpectrum ---peppyspectrum status stop
Feb 24 18:09:21 volumio sudo[13431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service
Feb 24 18:09:21 volumio sudo[13431]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:21 volumio sudo[13434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service
Feb 24 18:09:21 volumio sudo[13431]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio sudo[13434]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:21 volumio sudo[13434]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:21 volumio volumio[12558]: info: peppyspectrum Daemon Stop
Feb 24 18:09:21 volumio volumio[12558]: info: peppyspectrum Daemon Stop
Feb 24 18:09:22 volumio sudo[13436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
Feb 24 18:09:22 volumio sudo[13436]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:22 volumio volumiobt[13394]: pulseaudio: no process found
Feb 24 18:09:22 volumio sudo[13436]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:22 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.115' (uid=1000 pid=13440 comm="/usr/bin/pulseaudio --high-priority=true ")
Feb 24 18:09:22 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Feb 24 18:09:22 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:09:22 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Feb 24 18:09:22 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Successfully called chroot.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Successfully dropped privileges.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Successfully limited resources.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Running.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Canary thread running.
Feb 24 18:09:22 volumio rtkit-daemon[13441]: Watchdog thread running.
Feb 24 18:09:22 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.116' (uid=0 pid=13441 comm="/usr/lib/rtkit/rtkit-daemon ")
Feb 24 18:09:22 volumio systemd[1]: Starting Authorization Manager...
Feb 24 18:09:22 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:09:22 volumio polkitd[13445]: started daemon version 0.105 using authority implementation `local' version `0.105'
Feb 24 18:09:22 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Feb 24 18:09:22 volumio systemd[1]: Started Authorization Manager.
Feb 24 18:09:22 volumio pulseaudio[13440]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Feb 24 18:09:22 volumio pulseaudio[13440]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Feb 24 18:09:22 volumio bluetoothd[782]: Endpoint registered: sender=:1.136 path=/MediaEndpoint/A2DPSource
Feb 24 18:09:22 volumio bluetoothd[782]: Endpoint registered: sender=:1.136 path=/MediaEndpoint/A2DPSink
Feb 24 18:09:22 volumio volumiobt[13394]: Applying permissions
Feb 24 18:09:22 volumio volumiobt[13394]: Setting BT discoverable and pairable
Feb 24 18:09:22 volumio pulseaudio[13440]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
Feb 24 18:09:22 volumio kernel: Bluetooth: RFCOMM TTY layer initialized
Feb 24 18:09:22 volumio kernel: Bluetooth: RFCOMM socket layer initialized
Feb 24 18:09:22 volumio kernel: Bluetooth: RFCOMM ver 1.11
Feb 24 18:09:22 volumio volumiobt[13394]: [117B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [81B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [81B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [82B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [37B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [83B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [99B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [59B blob data]
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> -e power on
Feb 24 18:09:22 volumio volumiobt[13394]: Invalid command in menu main: -e
Feb 24 18:09:22 volumio volumiobt[13394]:
Feb 24 18:09:22 volumio volumiobt[13394]: Use "help" for a list of available commands in a menu.
Feb 24 18:09:22 volumio volumiobt[13394]: Use "menu " if you want to enter any submenu.
Feb 24 18:09:22 volumio volumiobt[13394]: Use "back" if you want to return to menu main.
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> agent on
Feb 24 18:09:22 volumio volumiobt[13394]: Agent is already registered
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> discoverable on
Feb 24 18:09:22 volumio volumiobt[13394]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> pairable on
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> agent NoInputNoOutput
Feb 24 18:09:22 volumio volumiobt[13394]: Agent is already registered
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> default-agent
Feb 24 18:09:22 volumio volumiobt[13394]: [OPPO Reno13 F 5G]> quit
Feb 24 18:09:22 volumio volumiobt[13394]: [73B blob data]
Feb 24 18:09:22 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 24 18:09:22 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:22 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 24 18:09:22 volumio volumio[12558]: info: MRS: Getting audio outputs on start
Feb 24 18:09:22 volumio volumio[12558]: info: MRS: Requesting all other devices output
Feb 24 18:09:22 volumio volumio-remote-updater[663]: No test mode
Feb 24 18:09:22 volumio volumio-remote-updater[663]: No alpha test mode
Feb 24 18:09:22 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 24 18:09:23 volumio volumio[12558]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Feb 24 18:09:23 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 24 18:09:24 volumio volumio[12558]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:24 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:24 volumio volumio[12558]: info: TidalConnect service started!
Feb 24 18:09:24 volumio volumio[12558]: [Metrics] CommandRouter: 46s 899.58ms
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::Close All Modals sent
Feb 24 18:09:24 volumio volumio[12558]: info: CoreCommandRouter::Close All Modals sent
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 24 18:09:24 volumio volumio[12558]: info: camilladsp stopping service pid 13058...
Feb 24 18:09:24 volumio volumio[12558]: info: camilladsp service terminated, instance 1
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 24 18:09:24 volumio volumio[12558]: info: camilladsp service started and running in background, instance 1
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 24 18:09:24 volumio volumio[12558]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 24 18:09:24 volumio volumio[12558]: error: FusionDsp - WebSocket error: [object Object]
Feb 24 18:09:24 volumio volumio[1243]: Generating RSA private key, 4096 bit long modulus (2 primes)
Feb 24 18:09:25 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 24 18:09:25 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 24 18:09:25 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 24 18:09:25 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:25 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 24 18:09:29 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 24 18:09:30 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:30 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:31 volumio volumio[12558]: info: BOOT COMPLETED
Feb 24 18:09:31 volumio volumio[12558]: info: mpdhttpout ---Boot completed detected! Patching mpd now!
Feb 24 18:09:31 volumio volumio[12558]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf.
Feb 24 18:09:31 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:31 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:31 volumio volumio[12558]: info: CoreCommandRouter::volumioPause
Feb 24 18:09:31 volumio volumio[12558]: info: CoreStateMachine::pause
Feb 24 18:09:31 volumio volumio[12558]: info: mpdhttpout --- Volumio set on pause
Feb 24 18:09:33 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Feb 24 18:09:33 volumio sudo[13513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 24 18:09:33 volumio sudo[13513]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:33 volumio systemd[1]: Stopping Music Player Daemon...
Feb 24 18:09:33 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:09:33 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:09:33 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:09:33 volumio systemd[1]: mpd.service: Succeeded.
Feb 24 18:09:33 volumio systemd[1]: Stopped Music Player Daemon.
Feb 24 18:09:33 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Feb 24 18:09:33 volumio volumio[1243]: ....................................................................................................++++
Feb 24 18:09:34 volumio systemd[1]: Starting Music Player Daemon...
Feb 24 18:09:34 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:09:34 volumio sudo[13515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 24 18:09:34 volumio sudo[13515]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:09:34 volumio sudo[13515]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:34 volumio mpd[13517]: Feb 24 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 24 18:09:34 volumio volumio[1243]: ........++++
Feb 24 18:09:34 volumio volumio[1243]: e is 65537 (0x010001)
Feb 24 18:09:34 volumio volumio[1243]: writing RSA key
Feb 24 18:09:34 volumio systemd[1]: Started Music Player Daemon.
Feb 24 18:09:34 volumio sudo[13513]: pam_unix(sudo:session): session closed for user root
Feb 24 18:09:34 volumio volumio[12558]: error: updateQueue error: null
Feb 24 18:09:35 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:35 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:41 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:41 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:46 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:46 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:51 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:51 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:09:56 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:09:56 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:01 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:01 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:06 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:06 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:11 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:11 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:16 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:16 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:21 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:21 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:26 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:26 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:32 volumio volumio[12558]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
Feb 24 18:10:32 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:34 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings
Feb 24 18:10:34 volumio volumio[12558]: info: Saving new wireless network
Feb 24 18:10:34 volumio sudo[13686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf
Feb 24 18:10:34 volumio sudo[13686]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:34 volumio sudo[13686]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:34 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Feb 24 18:10:34 volumio sudo[13689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Feb 24 18:10:34 volumio sudo[13689]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:34 volumio systemd[1]: wireless.service: Killing process 941 (wpa_supplicant) with signal SIGKILL.
Feb 24 18:10:34 volumio systemd[1]: wireless.service: Killing process 953 (dhcpcd) with signal SIGKILL.
Feb 24 18:10:34 volumio systemd[1]: Stopping Wireless Services...
Feb 24 18:10:34 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:10:34 volumio systemd[1]: wireless.service: Succeeded.
Feb 24 18:10:34 volumio systemd[1]: Stopped Wireless Services.
Feb 24 18:10:34 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Feb 24 18:10:34 volumio systemd[1]: Starting Wireless Services...
Feb 24 18:10:34 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:10:35 volumio wireless.js[13691]: Cleaning previous...
Feb 24 18:10:35 volumio sudo[13705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Feb 24 18:10:35 volumio sudo[13705]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio avahi-daemon[664]: Withdrawing address record for 192.168.1.115 on wlan0.
Feb 24 18:10:35 volumio avahi-daemon[664]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.115.
Feb 24 18:10:35 volumio avahi-daemon[664]: Interface wlan0.IPv4 no longer relevant for mDNS.
Feb 24 18:10:35 volumio sudo[13705]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio sudo[13707]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio sudo[13707]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: A device disappeared from network
Feb 24 18:10:35 volumio sudo[13707]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:35.862+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 24 18:10:35 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:35 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:35 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:35 volumio sudo[13710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:35 volumio sudo[13710]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:35 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:35 volumio sudo[13710]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:35.878+01:00 level=INFO msg="enabling BLE discovery"
Feb 24 18:10:35 volumio wireless.js[13691]: Stopped aP
Feb 24 18:10:35 volumio sudo[13717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Feb 24 18:10:35 volumio sudo[13717]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Feb 24 18:10:35 volumio sudo[13717]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio sudo[13719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Feb 24 18:10:35 volumio sudo[13719]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio sudo[13719]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio sudo[13726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Feb 24 18:10:35 volumio sudo[13726]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:35 volumio sudo[13726]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:35 volumio sudo[13728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Feb 24 18:10:36 volumio sudo[13728]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:36 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:36.440+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 24 18:10:36 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:36 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:36 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:36 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:36 volumio sudo[13733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:36 volumio sudo[13733]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:36 volumio sudo[13733]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:37 volumio ntpd[819]: Deleting interface #3 wlan0, 192.168.1.115#123, interface stats: received=121, sent=121, dropped=0, active_time=115 secs
Feb 24 18:10:37 volumio ntpd[819]: 93.94.88.51 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 5.249.149.252 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 85.199.214.99 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 212.45.144.206 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 95.110.254.234 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 129.152.16.145 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 185.157.229.254 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 89.46.74.148 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 162.159.200.123 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 162.159.200.1 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 172.232.209.103 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 172.232.208.229 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio ntpd[819]: 195.32.70.195 local addr 192.168.1.115 ->
Feb 24 18:10:37 volumio sudo[13737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:37 volumio sudo[13737]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:37 volumio sudo[13737]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:38 volumio sudo[13728]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:38 volumio wireless.js[13691]: SETTING APPROPRIATE REG DOMAIN: IT
Feb 24 18:10:38 volumio sudo[13750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Feb 24 18:10:38 volumio sudo[13750]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:38 volumio sudo[13750]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:38 volumio sudo[13752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IT
Feb 24 18:10:38 volumio sudo[13752]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:38 volumio sudo[13752]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:38 volumio wireless.js[13691]: SUCCESSFULLY SET NEW REGDOMAIN: IT
Feb 24 18:10:38 volumio wireless.js[13691]: Start wireless flow
Feb 24 18:10:38 volumio wireless.js[13691]: Stopped hotspot (if there)..
Feb 24 18:10:38 volumio sudo[13757]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Feb 24 18:10:38 volumio sudo[13757]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:38 volumio sudo[13757]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:38 volumio sudo[13760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Feb 24 18:10:38 volumio sudo[13760]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:39 volumio sudo[13760]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:39 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:39.183+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 24 18:10:39 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:39 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:39 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:39 volumio sudo[13761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:39 volumio sudo[13761]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:39 volumio sudo[13761]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:39 volumio wireless.js[13691]: DHCP IP
Feb 24 18:10:39 volumio wireless.js[13691]: Start ap
Feb 24 18:10:39 volumio wpa_supplicant[13767]: Successfully initialized wpa_supplicant
Feb 24 18:10:39 volumio sudo[13773]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Feb 24 18:10:39 volumio sudo[13773]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:39 volumio volumio[12558]: info: Volumio Network Manager: Network status updated: 0
Feb 24 18:10:39 volumio dhcpcd[13779]: main: control_open: Connection refused
Feb 24 18:10:39 volumio dhcpcd[13779]: dev: loaded udev
Feb 24 18:10:39 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Feb 24 18:10:39 volumio dhcpcd-run-hooks[13805]: wlan0: starting wpa_supplicant
Feb 24 18:10:39 volumio dhcpcd-run-hooks[13810]: wlan0: failed to start wpa_supplicant
Feb 24 18:10:39 volumio dhcpcd-run-hooks[13811]: wlan0: Successfully initialized wpa_supplicant
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Match already configured
nl80211: kernel reports: Ma
Feb 24 18:10:39 volumio dhcpcd[13779]: wlan0: connected to Access Point `'
Feb 24 18:10:39 volumio dhcpcd[13779]: no interfaces have a carrier
Feb 24 18:10:39 volumio dhcpcd[13779]: forked to background, child pid 13829
Feb 24 18:10:39 volumio dhcpcd[13829]: eth0: waiting for carrier
Feb 24 18:10:39 volumio dhcpcd[13829]: wlan0: waiting for carrier
Feb 24 18:10:39 volumio sudo[13773]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:39 volumio sudo[13831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:39 volumio sudo[13831]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:39 volumio sudo[13831]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:39 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:39.728+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address=
Feb 24 18:10:39 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:39 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:39 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:39 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:40 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:40 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:40 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:40.082+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 24 18:10:40 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:40 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:40 volumio wireless.js[13691]: trying...
Feb 24 18:10:40 volumio sudo[13834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:40 volumio sudo[13834]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:40 volumio sudo[13834]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:40 volumio sudo[13837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:40 volumio sudo[13837]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:40 volumio sudo[13837]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:41 volumio wireless.js[13691]: trying...
Feb 24 18:10:41 volumio sudo[13840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:41 volumio sudo[13840]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:41 volumio sudo[13840]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:41 volumio sudo[13843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:41 volumio sudo[13843]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:41 volumio sudo[13843]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:42 volumio wireless.js[13691]: trying...
Feb 24 18:10:42 volumio sudo[13846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:42 volumio sudo[13846]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:42 volumio sudo[13846]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:43 volumio wireless.js[13691]: trying...
Feb 24 18:10:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52
Feb 24 18:10:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52
Feb 24 18:10:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52
Feb 24 18:10:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52
Feb 24 18:10:44 volumio volumio[12558]: info: Discovery: Networking Restart detected, restarting advertisement and browsing
Feb 24 18:10:44 volumio volumio[12558]: info: Discovery: Restarting Advertising
Feb 24 18:10:44 volumio volumio[12558]: info: Discovery: Stopping existing advertisement
Feb 24 18:10:44 volumio volumio[12558]: info: Discovery: Restarting Browsing
Feb 24 18:10:45 volumio wpa_supplicant[13809]: wlan0: Trying to associate with SSID 'WINDTRE - 9BC7FE'
Feb 24 18:10:45 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:45.582+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 24 18:10:45 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:45 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:45 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52
Feb 24 18:10:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52
Feb 24 18:10:45 volumio sudo[13849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:45 volumio sudo[13849]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:45 volumio sudo[13851]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13851]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:45 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:45 volumio sudo[13855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:45 volumio sudo[13855]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13849]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio wireless.js[13691]: trying...
Feb 24 18:10:45 volumio sudo[13857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:45 volumio sudo[13857]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13855]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio sudo[13857]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio sudo[13864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:45 volumio sudo[13864]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13864]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio sudo[13867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:45 volumio sudo[13867]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:45 volumio sudo[13867]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: carrier acquired
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: connected to Access Point `WINDTRE - 9BC7FE'
Feb 24 18:10:45 volumio wpa_supplicant[13809]: wlan0: Associated with 38:91:48:9b:c7:ff
Feb 24 18:10:45 volumio wpa_supplicant[13809]: wlan0: CTRL-EVENT-CONNECTED - Connection to 38:91:48:9b:c7:ff completed [id=0 id_str=]
Feb 24 18:10:45 volumio wpa_supplicant[13809]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Feb 24 18:10:45 volumio wpa_supplicant[13809]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT
Feb 24 18:10:45 volumio dhcpcd[13829]: DUID 00:01:00:01:2e:f2:aa:af:dc:a6:32:b2:8a:70
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: IAID 32:b2:8a:70
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: adding address fe80::d637:c0dd:60ac:86c5
Feb 24 18:10:45 volumio dhcpcd[13829]: ipv6_addaddr1: Permission denied
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: carrier lost
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: carrier acquired
Feb 24 18:10:45 volumio dhcpcd[13829]: wlan0: IAID 32:b2:8a:70
Feb 24 18:10:46 volumio dhcpcd[13829]: wlan0: soliciting a DHCP lease
Feb 24 18:10:46 volumio volumio[12558]: info: Received Get System Info
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 24 18:10:46 volumio volumio[12558]: info: Discovery: Getting this device information
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::volumioGetState
Feb 24 18:10:46 volumio volumio[12558]: info: CorePlayQueue::getTrack 0
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 24 18:10:46 volumio volumio5-onboarding[1663]: time=2026-02-24T18:10:46.256+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.148:38912 @ 0x2158d20" available=true connected=true macAddress=dc:a6:32:b2:8a:70 ip4Address= ip6Address= ssid="WINDTRE - 9BC7FE"
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 24 18:10:46 volumio volumio[12558]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 24 18:10:46 volumio wireless.js[13691]: trying...
Feb 24 18:10:46 volumio sudo[13889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:46 volumio sudo[13889]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:46 volumio sudo[13889]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:46 volumio sudo[13892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:46 volumio sudo[13892]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:46 volumio sudo[13892]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:46 volumio dhcpcd[13829]: wlan0: soliciting an IPv6 router
Feb 24 18:10:47 volumio wireless.js[13691]: trying...
Feb 24 18:10:47 volumio sudo[13895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:47 volumio sudo[13895]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:47 volumio sudo[13895]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:47 volumio sudo[13898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:47 volumio sudo[13898]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:47 volumio sudo[13898]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:48 volumio dhcpcd[13829]: wlan0: offered 192.168.1.3 from 192.168.1.1
Feb 24 18:10:48 volumio dhcpcd[13829]: wlan0: probing address 192.168.1.3/24
Feb 24 18:10:48 volumio systemd[1]: Starting Cleanup of Temporary Directories...
Feb 24 18:10:48 volumio volumio[12558]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 24 18:10:48 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded.
Feb 24 18:10:48 volumio systemd[1]: Started Cleanup of Temporary Directories.
Feb 24 18:10:48 volumio wireless.js[13691]: trying...
Feb 24 18:10:48 volumio sudo[13902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:48 volumio sudo[13902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:48 volumio sudo[13902]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:48 volumio sudo[13905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:48 volumio sudo[13905]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:48 volumio sudo[13905]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:49 volumio wireless.js[13691]: trying...
Feb 24 18:10:49 volumio sudo[13922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:49 volumio sudo[13922]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:49 volumio sudo[13922]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:49 volumio volumio[12558]: info: Discovery: Started advertising with name: Volumio
Feb 24 18:10:49 volumio sudo[13927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:49 volumio sudo[13927]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:49 volumio sudo[13927]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:50 volumio wireless.js[13691]: trying...
Feb 24 18:10:50 volumio sudo[13930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:50 volumio sudo[13930]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:50 volumio sudo[13930]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:50 volumio sudo[13933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:50 volumio sudo[13933]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:50 volumio sudo[13933]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:51 volumio wireless.js[13691]: trying...
Feb 24 18:10:51 volumio sudo[13936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:51 volumio sudo[13936]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:51 volumio sudo[13936]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:51 volumio sudo[13939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:51 volumio sudo[13939]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:51 volumio sudo[13939]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:52 volumio wireless.js[13691]: trying...
Feb 24 18:10:52 volumio sudo[13942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:52 volumio sudo[13942]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:52 volumio sudo[13942]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:52 volumio sudo[13945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:52 volumio sudo[13945]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:52 volumio sudo[13945]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:53 volumio volumio[12558]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 24 18:10:53 volumio volumio[12558]: Error: send ENETUNREACH 255.255.255.255:3483
Feb 24 18:10:53 volumio volumio[12558]: at doSend (dgram.js:692:16)
Feb 24 18:10:53 volumio volumio[12558]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12)
Feb 24 18:10:53 volumio volumio[12558]: at afterDns (dgram.js:638:5)
Feb 24 18:10:53 volumio volumio[12558]: at processTicksAndRejections (internal/process/task_queues.js:81:21) {
Feb 24 18:10:53 volumio volumio[12558]: errno: -101,
Feb 24 18:10:53 volumio volumio[12558]: code: 'ENETUNREACH',
Feb 24 18:10:53 volumio volumio[12558]: syscall: 'send',
Feb 24 18:10:53 volumio volumio[12558]: address: '255.255.255.255',
Feb 24 18:10:53 volumio volumio[12558]: port: 3483
Feb 24 18:10:53 volumio volumio[12558]: }
Feb 24 18:10:53 volumio volumio[12558]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 24 18:10:53 volumio sudo[13956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 18:09
Feb 24 18:10:53 volumio dhcpcd[13829]: wlan0: leased 192.168.1.3 for 86400 seconds
Feb 24 18:10:53 volumio avahi-daemon[664]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3.
Feb 24 18:10:53 volumio sudo[13956]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:53 volumio wireless.js[13691]: trying...
Feb 24 18:10:53 volumio dhcpcd[13829]: wlan0: adding route to 192.168.1.0/24
Feb 24 18:10:53 volumio dhcpcd[13829]: wlan0: adding default route via 192.168.1.1
Feb 24 18:10:53 volumio avahi-daemon[664]: New relevant interface wlan0.IPv4 for mDNS.
Feb 24 18:10:53 volumio avahi-daemon[664]: Registering new address record for 192.168.1.3 on wlan0.IPv4.
Feb 24 18:10:53 volumio sudo[13961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Feb 24 18:10:53 volumio sudo[13961]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:53 volumio sudo[13961]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:53 volumio wireless.js[13691]: Connected to: ----WINDTRE - 9BC7FE
Feb 24 18:10:53 volumio wireless.js[13691]: ----
Feb 24 18:10:53 volumio sudo[13975]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 24 18:10:53 volumio sudo[13975]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 24 18:10:53 volumio sudo[13975]: pam_unix(sudo:session): session closed for user root
Feb 24 18:10:53 volumio wireless.js[13691]: ... joined AP, wlan0 IPv4 is 192.168.1.3, ipV6 is undefined
Feb 24 18:10:53 volumio wireless.js[13691]: It's done! AP
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"