-- Logs begin at Mon 2025-10-06 19:27:08 CEST, end at Sat 2025-10-25 14:11:55 CEST. -- Oct 06 19:35:00 volumio volumio[1035]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 06 19:35:00 volumio volumio[1035]: info: MyVolumio token set successfully Oct 06 19:35:00 volumio volumio[1035]: info: MYVOLUMIO: Adding device Oct 06 19:35:00 volumio volumio[1035]: info: MYVOLUMIO: Evaluating Server Oct 06 19:35:01 volumio volumio[1035]: info: MyVolumio Plan changed: premium Oct 06 19:35:01 volumio volumio[1035]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 06 19:35:01 volumio volumio[1035]: info: Removing browser output: myVolumio user plan is not superstar Oct 06 19:35:01 volumio volumio[1035]: info: Removing audio output: Oct 06 19:35:01 volumio volumio[1035]: info: MYVOLUMIO: Adding device Oct 06 19:35:01 volumio volumio[1035]: info: MYVOLUMIO: Evaluating Server Oct 06 19:35:01 volumio volumio[1035]: info: Remote config written successfully Oct 06 19:35:01 volumio volumio[1035]: info: Starting Tunnel 1 Oct 06 19:35:01 volumio volumio[1035]: info: Starting Tunnel Connection Checker Oct 06 19:35:01 volumio volumio[1035]: info: MYVolumio Device enabled Oct 06 19:35:01 volumio volumio[1035]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 06 19:35:01 volumio volumio[1035]: info: MyVolumio status changed Oct 06 19:35:01 volumio volumio[1035]: info: Streaming services startup Oct 06 19:35:01 volumio volumio[1035]: info: Starting Streaming Daemon Oct 06 19:35:01 volumio sudo[2882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 06 19:35:01 volumio sudo[2882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:01 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 06 19:35:01 volumio sudo[2882]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:01 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 06 19:35:01 volumio volumio[1035]: info: [1759772101791] Starting BluetoothController Oct 06 19:35:01 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 06 19:35:02 volumio volumio[1035]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 06 19:35:02 volumio sudo[2900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 06 19:35:02 volumio sudo[2900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:02 volumio sudo[2900]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:02 volumio volumio[1035]: info: MRS: MultiRoom plugin initialized Oct 06 19:35:02 volumio volumio[1035]: info: MRS: STOPPING SNAPCLIENT Oct 06 19:35:02 volumio volumio[1035]: info: MRS: Snap server stop Oct 06 19:35:02 volumio sudo[2916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 06 19:35:02 volumio sudo[2916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:02 volumio volumio[1035]: info: MRS: STOPPING volumioStreaming Oct 06 19:35:03 volumio sudo[2919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 06 19:35:03 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 06 19:35:03 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2916]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 06 19:35:03 volumio sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2924]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 06 19:35:03 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 06 19:35:03 volumio volumio[1035]: info: Preparing to generate the ALSA configuration file Oct 06 19:35:03 volumio volumio[1035]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 06 19:35:03 volumio volumio[1035]: info: Reading ALSA contributions from plugins. Oct 06 19:35:03 volumio volumio[1035]: info: Setting Geolocation for MyVolumio to eu10 Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: Setting Geolocation for MyVolumio to eu6 Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: Volumio Network Manager: Network status updated: 2 Oct 06 19:35:03 volumio volumio[1035]: info: MRS: Removed streaming files Oct 06 19:35:03 volumio volumio[1035]: info: MRS: volumioStreaming STOPPED Oct 06 19:35:03 volumio volumio[1035]: info: MRS: SNAPSERVER STOPPED Oct 06 19:35:03 volumio volumio[1035]: info: MRS: SNAPCLIENT STOPPED Oct 06 19:35:03 volumio volumio[1035]: error: Cannot start Volumio Streaming Daemon Oct 06 19:35:03 volumio volumio[1035]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 06 19:35:03 volumio volumio[1035]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 06 19:35:03 volumio volumio[1035]: info: Asound.conf file unchanged, so no further update is needed Oct 06 19:35:03 volumio volumio[1035]: info: Output device has changed, restarting MPD Oct 06 19:35:03 volumio sudo[2928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 06 19:35:03 volumio volumio[1035]: info: Output device has changed, restarting Shairport Sync Oct 06 19:35:03 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio sudo[2928]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio sudo[2930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 06 19:35:03 volumio sudo[2930]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio systemd[1]: Stopping Music Player Daemon... Oct 06 19:35:03 volumio volumio[1035]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 06 19:35:03 volumio volumio[1035]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio systemd[1]: mpd.service: Succeeded. Oct 06 19:35:03 volumio systemd[1]: Stopped Music Player Daemon. Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 06 19:35:03 volumio volumio[1035]: info: MRS: Pushing multiroomSync output for this device Oct 06 19:35:03 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:35:03 volumio volumio[1035]: info: Adding audio output: Oct 06 19:35:03 volumio sudo[2940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Oct 06 19:35:03 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio volumio[1035]: info: Adding audio output: Oct 06 19:35:03 volumio volumio[1035]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio systemd[1]: Starting Music Player Daemon... Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 06 19:35:03 volumio volumio[1035]: info: Adding METAVOLUMIO REST API Endpoints Oct 06 19:35:03 volumio volumio[1035]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 06 19:35:03 volumio volumio[1035]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 06 19:35:03 volumio volumio[1035]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 06 19:35:03 volumio volumio[1035]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 06 19:35:03 volumio volumio[1035]: info: Adding inputs REST Endpoints Oct 06 19:35:03 volumio volumio[1035]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 06 19:35:03 volumio volumio[1035]: info: Scanning Audio Inputs Oct 06 19:35:03 volumio volumio[1035]: info: Checking against Known Cards name Oct 06 19:35:03 volumio volumio[1035]: info: Adding Server instance for streaming Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 06 19:35:03 volumio volumio[1035]: error: Hi Res Audio Failed Login: Missing Login Data Oct 06 19:35:03 volumio sudo[2937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 06 19:35:03 volumio sudo[2937]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio volumio[1035]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 06 19:35:03 volumio volumio[1035]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 06 19:35:03 volumio volumio[1035]: info: Refreshing TIDAL token Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 06 19:35:03 volumio sudo[2937]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 06 19:35:03 volumio volumio[1035]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Oct 06 19:35:03 volumio sudo[2950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 06 19:35:03 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio volumio[1035]: info: Stopping AccessToken refresher cron for QOBUZ Oct 06 19:35:03 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio volumio[1035]: info: AccessToken refresher cron started for QOBUZ Oct 06 19:35:03 volumio volumio[1035]: info: Adding QOBUZ REST API Endpoints Oct 06 19:35:03 volumio volumio[1035]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 06 19:35:03 volumio volumio[1035]: info: Refreshing Cached IP Addresses Oct 06 19:35:03 volumio sudo[2954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 06 19:35:03 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio sudo[2957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:03 volumio sudo[2957]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2957]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio sudo[2960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:03 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:03 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:03 volumio volumio[1035]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 19:35:03 volumio volumio[1035]: info: Bluetooth name changed to Volumio Oct 06 19:35:03 volumio volumio[1035]: info: MPD Permissions set Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:03 volumio ifplugd(eth0)[767]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Oct 06 19:35:03 volumio volumio[1035]: info: Starting Shairport Sync Oct 06 19:35:03 volumio dhcpcd[2977]: sending commands to master dhcpcd process Oct 06 19:35:03 volumio ifplugd(eth0)[767]: client: sending commands to master dhcpcd process Oct 06 19:35:03 volumio dhcpcd[874]: control command: /sbin/dhcpcd -k eth0 Oct 06 19:35:03 volumio dhcpcd[874]: eth0: removing interface Oct 06 19:35:03 volumio volumio[1035]: info: Access Token successfully retrieved Oct 06 19:35:03 volumio volumio[1035]: info: Successfully Added MyVolumio device Oct 06 19:35:03 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 06 19:35:03 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 06 19:35:04 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 06 19:35:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 06 19:35:04 volumio ifplugd(eth0)[767]: Program executed successfully. Oct 06 19:35:04 volumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Oct 06 19:35:04 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 06 19:35:04 volumio sudo[3012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 06 19:35:04 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio volumio[1035]: info: Shairport-Sync Started Oct 06 19:35:04 volumio sudo[3014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:04 volumio sudo[3012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3014]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3012]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio volumio[1035]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.138 UA: Mozilla/5.0 (Linux; Android 15; SM-A165F Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 06 19:35:04 volumio volumio[1035]: info: Updating MyVolumio device info Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:04 volumio mpd[2948]: Oct 06 19:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 06 19:35:04 volumio volumio[1035]: info: Updating MyVolumio device info Oct 06 19:35:04 volumio systemd[1]: Started Music Player Daemon. Oct 06 19:35:04 volumio sudo[2930]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 19:35:04 volumio volumio[1035]: error: updateQueue error: null Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 06 19:35:04 volumio volumio[1035]: info: Successfully Added MyVolumio device Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:04 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 19:35:04 volumio volumio[1035]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 06 19:35:04 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:04 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:04 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:04 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:04 volumio volumio[1035]: info: Listing playlists Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 06 19:35:04 volumio volumio[1035]: info: MRS: Found cast device: BboxTV-0be897dea1bd17b8fa93678ae5a160ea Oct 06 19:35:04 volumio volumio[1035]: info: Adding audio output: Oct 06 19:35:04 volumio volumio[1035]: info: MRS: Found cast device: Android-TV-75a9b393693e16dcc449cf105f757e3b Oct 06 19:35:04 volumio volumio[1035]: info: Adding audio output: Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 06 19:35:04 volumio sudo[3024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 06 19:35:04 volumio sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3024]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 19:35:04 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 19:35:04 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 19:35:04 volumio sudo[3042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:04 volumio sudo[3042]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 06 19:35:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 06 19:35:04 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:04 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:04 volumio sudo[3056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 06 19:35:04 volumio sudo[3056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:04 volumio volumio[1035]: info: Successfully retrieved User Session From TIDAL Oct 06 19:35:05 volumio volumio[1035]: info: Successfully Updated MyVolumio device Oct 06 19:35:05 volumio volumio[1035]: info: Successfully retrieved User Subscription From TIDAL Oct 06 19:35:05 volumio volumio[1035]: info: Adding TIDAL to Browse Sources Oct 06 19:35:05 volumio volumio[1035]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 06 19:35:05 volumio volumio[1035]: info: [1759772105035] CoreMusicLibrary::Adding element TIDAL Oct 06 19:35:05 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 19:35:05 volumio volumio[1035]: Cannot find translation for source TIDAL Oct 06 19:35:05 volumio volumio[1035]: info: Stopping AccessToken refresher cron Oct 06 19:35:05 volumio volumio[1035]: info: AccessToken refresher cron started Oct 06 19:35:05 volumio volumio[1035]: info: Adding TIDAL REST API Endpoints Oct 06 19:35:05 volumio volumio[1035]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 06 19:35:05 volumio volumio[1035]: info: Successfully Updated MyVolumio device Oct 06 19:35:05 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 06 19:35:05 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 19:35:06 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:06 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:06 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:06 volumio sudo[3063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 06 19:35:06 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:06 volumio sudo[3067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 06 19:35:06 volumio sudo[3067]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:06 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 06 19:35:06 volumio sudo[3063]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:06 volumio volumio[1035]: info: Remote SSH Started Oct 06 19:35:06 volumio systemd[1]: Started Volumio Bluetooth Module. Oct 06 19:35:06 volumio sudo[3067]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:06 volumio autossh[3069]: port set to 0, monitoring disabled Oct 06 19:35:06 volumio autossh[3069]: starting ssh (count 1) Oct 06 19:35:06 volumio autossh[3069]: ssh child pid is 3073 Oct 06 19:35:06 volumio sudo[3074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 06 19:35:06 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:06 volumio volumiobt[3072]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 06 19:35:06 volumio sudo[3074]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:06 volumio volumio[1035]: info: TidalConnect service stoped! Oct 06 19:35:06 volumio volumio[1035]: info: Volumio BT Module successfully started Oct 06 19:35:06 volumio volumio[1035]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:06 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:06 volumio volumio[1035]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 06 19:35:06 volumio volumio[1035]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 06 19:35:06 volumio sudo[3086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 06 19:35:06 volumio sudo[3086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 19:35:06 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:06 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:06 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:06 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 06 19:35:06 volumio sudo[3086]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:06 volumio volumiossh-tunnel[3065]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Oct 06 19:35:07 volumio volumio[1035]: info: Executing endpoint tc_getconfig Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 06 19:35:07 volumio vtcs[3088]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 06 19:35:07 volumio vtcs[3088]: STARTED TidalConnect services. Oct 06 19:35:07 volumio volumio[1035]: info: Executing endpoint tc_connect Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 06 19:35:07 volumio volumio[1035]: info: Connecting to TidalConnect Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:35:07 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:35:07 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:35:07 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:35:07 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:35:07 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:07 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:35:07 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:35:07 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:07 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:35:07 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:35:07 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:35:07 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:07 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Oct 06 19:35:07 volumio sudo[3107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 06 19:35:07 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:07 volumio volumiobt[3072]: pulseaudio: no process found Oct 06 19:35:07 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:07 volumio dbus-daemon[540]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.26' (uid=1000 pid=3111 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 06 19:35:07 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 06 19:35:07 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 06 19:35:07 volumio dbus-daemon[540]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 06 19:35:07 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Successfully called chroot. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Successfully dropped privileges. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Successfully limited resources. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Running. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Canary thread running. Oct 06 19:35:07 volumio rtkit-daemon[3112]: Watchdog thread running. Oct 06 19:35:07 volumio dbus-daemon[540]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.27' (uid=0 pid=3112 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 06 19:35:07 volumio systemd[1]: Starting Authorization Manager... Oct 06 19:35:07 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 06 19:35:07 volumio polkitd[3116]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 06 19:35:07 volumio dbus-daemon[540]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 06 19:35:07 volumio systemd[1]: Started Authorization Manager. Oct 06 19:35:08 volumio sudo[3056]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:08 volumio pulseaudio[3111]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 06 19:35:08 volumio pulseaudio[3111]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 06 19:35:08 volumio bluetoothd[694]: Endpoint registered: sender=:1.47 path=/MediaEndpoint/A2DPSource Oct 06 19:35:08 volumio bluetoothd[694]: Endpoint registered: sender=:1.47 path=/MediaEndpoint/A2DPSink Oct 06 19:35:08 volumio pulseaudio[3111]: 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 Oct 06 19:35:08 volumio volumiobt[3072]: Applying permissions Oct 06 19:35:08 volumio volumiobt[3072]: Setting BT discoverable and pairable Oct 06 19:35:08 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Oct 06 19:35:08 volumio kernel: Bluetooth: RFCOMM socket layer initialized Oct 06 19:35:08 volumio kernel: Bluetooth: RFCOMM ver 1.11 Oct 06 19:35:08 volumio volumiobt[3072]: [CHG] Controller D8:3A:DD:AD:78:64 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [55B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [117B blob data] Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# -e power on Oct 06 19:35:08 volumio volumiobt[3072]: Invalid command in menu main: -e Oct 06 19:35:08 volumio volumiobt[3072]: Oct 06 19:35:08 volumio volumiobt[3072]: Use "help" for a list of available commands in a menu. Oct 06 19:35:08 volumio volumiobt[3072]: Use "menu " if you want to enter any submenu. Oct 06 19:35:08 volumio volumiobt[3072]: Use "back" if you want to return to menu main. Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# agent on Oct 06 19:35:08 volumio volumiobt[3072]: Agent is already registered Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# discoverable on Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# pairable on Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# agent NoInputNoOutput Oct 06 19:35:08 volumio volumiobt[3072]: Agent is already registered Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# default-agent Oct 06 19:35:08 volumio volumiobt[3072]: [bluetooth]# quit Oct 06 19:35:08 volumio volumiobt[3072]: [59B blob data] Oct 06 19:35:08 volumio volumio[1035]: info: MRS: Getting audio outputs on start Oct 06 19:35:08 volumio volumio[1035]: info: MRS: Requesting all other devices output Oct 06 19:35:09 volumio volumio[1035]: info: TidalConnect service started! Oct 06 19:35:11 volumio dhcpcd[988]: eth0: carrier acquired Oct 06 19:35:11 volumio dhcpcd[988]: eth0: confile file changed, expiring leases Oct 06 19:35:11 volumio dhcpcd[988]: eth0: IAID dd:ad:78:62 Oct 06 19:35:11 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Oct 06 19:35:11 volumio dhcpcd[988]: eth0: soliciting a DHCP lease Oct 06 19:35:11 volumio dhcpcd[988]: eth0: soliciting an IPv6 router Oct 06 19:35:12 volumio ifplugd(eth0)[767]: Link beat detected. Oct 06 19:35:12 volumio ifplugd(eth0)[767]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 06 19:35:12 volumio dhcpcd[3188]: sending commands to master dhcpcd process Oct 06 19:35:12 volumio ifplugd(eth0)[767]: client: sending commands to master dhcpcd process Oct 06 19:35:12 volumio dhcpcd[874]: control command: /sbin/dhcpcd eth0 Oct 06 19:35:12 volumio dhcpcd[874]: eth0: confile file changed, expiring leases Oct 06 19:35:12 volumio dhcpcd[874]: eth0: carrier acquired Oct 06 19:35:12 volumio dhcpcd[874]: eth0: IAID dd:ad:78:62 Oct 06 19:35:12 volumio ifplugd(eth0)[767]: Program executed successfully. Oct 06 19:35:12 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 06 19:35:12 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 06 19:35:12 volumio sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 06 19:35:12 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:12 volumio dhcpcd[874]: eth0: soliciting a DHCP lease Oct 06 19:35:12 volumio dhcpcd[874]: eth0: soliciting an IPv6 router Oct 06 19:35:12 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 19:35:12 volumio volumio[1035]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 06 19:35:12 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:12 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:13 volumio sudo[3233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:13 volumio sudo[3233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:13 volumio sudo[3233]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:13 volumio sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:13 volumio sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:13 volumio sudo[3235]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:13 volumio sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 06 19:35:13 volumio sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:13 volumio sudo[3239]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:13 volumio volumio[1035]: info: Upmpdcli Daemon Started Oct 06 19:35:14 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 06 19:35:15 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:16 volumio dhcpcd[988]: eth0: probing for an IPv4LL address Oct 06 19:35:17 volumio dhcpcd[874]: eth0: probing for an IPv4LL address Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 19:35:17 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 19:35:21 volumio dhcpcd[988]: eth0: using IPv4LL address 169.254.129.148 Oct 06 19:35:21 volumio dhcpcd[988]: eth0: adding route to 169.254.0.0/16 Oct 06 19:35:21 volumio dhcpcd[988]: eth0: adding default route Oct 06 19:35:21 volumio avahi-daemon[544]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.129.148. Oct 06 19:35:21 volumio avahi-daemon[544]: New relevant interface eth0.IPv4 for mDNS. Oct 06 19:35:21 volumio avahi-daemon[544]: Registering new address record for 169.254.129.148 on eth0.IPv4. Oct 06 19:35:22 volumio volumio[1035]: info: Discovery: this is already registered, 641dedab-9063-4e77-9ccd-26f834d7350c Oct 06 19:35:22 volumio volumio[1035]: info: Discovery: Found device Volumio Oct 06 19:35:22 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:22 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:22 volumio dhcpcd[874]: eth0: using IPv4LL address 169.254.129.148 Oct 06 19:35:22 volumio dhcpcd[874]: eth0: adding route to 169.254.0.0/16 Oct 06 19:35:22 volumio ntpd[770]: Listen normally on 6 eth0 169.254.129.148:123 Oct 06 19:35:22 volumio ntpd[770]: new interface(s) found: waking up resolver Oct 06 19:35:22 volumio volumio[1035]: info: Volumio Network Manager: Network status updated: 3 Oct 06 19:35:26 volumio volumio[1035]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Oct 06 19:35:26 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Oct 06 19:35:27 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Oct 06 19:35:27 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:27 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:27 volumio volumio[1035]: info: Permissions for /etc/hostapd/hostapd.conf Oct 06 19:35:27 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 06 19:35:27 volumio volumio[1035]: info: Discovery: Restarting Advertising due to device name change Oct 06 19:35:27 volumio sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 06 19:35:27 volumio sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:27 volumio systemd[1]: wireless.service: Killing process 862 (wpa_supplicant) with signal SIGKILL. Oct 06 19:35:27 volumio systemd[1]: wireless.service: Killing process 874 (dhcpcd) with signal SIGKILL. Oct 06 19:35:27 volumio systemd[1]: Stopping Wireless Services... Oct 06 19:35:27 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 06 19:35:27 volumio systemd[1]: wireless.service: Succeeded. Oct 06 19:35:27 volumio systemd[1]: Stopped Wireless Services. Oct 06 19:35:27 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 06 19:35:27 volumio systemd[1]: Starting Wireless Services... Oct 06 19:35:27 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 06 19:35:27 volumio wireless.js[3307]: Cleaning previous... Oct 06 19:35:27 volumio sudo[3321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 19:35:27 volumio sudo[3321]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:27 volumio avahi-daemon[544]: Withdrawing address record for 192.168.1.137 on wlan0. Oct 06 19:35:27 volumio avahi-daemon[544]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.137. Oct 06 19:35:27 volumio avahi-daemon[544]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 06 19:35:27 volumio sudo[3321]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:27 volumio volumio[1035]: info: Discovery: A device disappeared from network Oct 06 19:35:27 volumio volumio[1035]: info: Discovery: Device volumio disappeared from network Oct 06 19:35:27 volumio sudo[3323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 19:35:27 volumio sudo[3323]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:28 volumio volumio[1035]: info: Discovery: A device disappeared from network Oct 06 19:35:28 volumio sudo[3323]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:28 volumio wireless.js[3307]: Stopped aP Oct 06 19:35:28 volumio sudo[3330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 06 19:35:28 volumio sudo[3330]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:28 volumio sudo[3330]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 06 19:35:28 volumio sudo[3332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 06 19:35:28 volumio sudo[3332]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:28 volumio sudo[3332]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:28 volumio sudo[3339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 06 19:35:28 volumio sudo[3339]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:28 volumio sudo[3339]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:28 volumio sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 06 19:35:28 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:29 volumio ntpd[770]: Deleting interface #5 wlan0, 192.168.1.137#123, interface stats: received=0, sent=0, dropped=0, active_time=444 secs Oct 06 19:35:31 volumio sudo[3341]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:31 volumio wireless.js[3307]: SETTING APPROPRIATE REG DOMAIN: FR Oct 06 19:35:31 volumio sudo[3346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 06 19:35:31 volumio sudo[3346]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:31 volumio sudo[3346]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:31 volumio sudo[3348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Oct 06 19:35:31 volumio sudo[3348]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:31 volumio sudo[3348]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:31 volumio wireless.js[3307]: SUCCESSFULLY SET NEW REGDOMAIN: FR Oct 06 19:35:31 volumio wireless.js[3307]: Start wireless flow Oct 06 19:35:31 volumio wireless.js[3307]: Stopped hotspot (if there).. Oct 06 19:35:31 volumio sudo[3353]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 19:35:31 volumio sudo[3353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:31 volumio sudo[3353]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:31 volumio sudo[3355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 19:35:31 volumio sudo[3355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:31 volumio sudo[3355]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:31 volumio wireless.js[3307]: DHCP IP Oct 06 19:35:31 volumio wireless.js[3307]: Start ap Oct 06 19:35:31 volumio wpa_supplicant[3357]: Successfully initialized wpa_supplicant Oct 06 19:35:31 volumio sudo[3358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 06 19:35:31 volumio sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:31 volumio dhcpcd[3359]: main: control_open: Connection refused Oct 06 19:35:31 volumio dhcpcd[3359]: dev: loaded udev Oct 06 19:35:31 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 06 19:35:31 volumio dhcpcd-run-hooks[3368]: wlan0: starting wpa_supplicant Oct 06 19:35:32 volumio dhcpcd-run-hooks[3373]: wlan0: failed to start wpa_supplicant Oct 06 19:35:32 volumio dhcpcd-run-hooks[3374]: 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 Oct 06 19:35:32 volumio dhcpcd[3359]: wlan0: connected to Access Point `' Oct 06 19:35:32 volumio dhcpcd[3359]: DUID 00:01:00:01:2f:b6:20:42:d8:3a:dd:ad:78:62 Oct 06 19:35:32 volumio dhcpcd[3359]: eth0: IAID dd:ad:78:62 Oct 06 19:35:32 volumio dhcpcd[3359]: eth0: adding address fe80::da3a:ddff:fead:7862 Oct 06 19:35:32 volumio dhcpcd[3359]: ipv6_addaddr1: Permission denied Oct 06 19:35:32 volumio dhcpcd[3359]: wlan0: waiting for carrier Oct 06 19:35:32 volumio volumio[1035]: info: Discovery: Started advertising with name: Volumio Oct 06 19:35:32 volumio dhcpcd[3359]: eth0: soliciting a DHCP lease Oct 06 19:35:32 volumio volumio[1035]: info: Volumio Network Manager: Network status updated: 1 Oct 06 19:35:32 volumio wireless.js[3307]: trying... Oct 06 19:35:32 volumio sudo[3409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:32 volumio sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:32 volumio sudo[3409]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:33 volumio dhcpcd[3359]: eth0: soliciting an IPv6 router Oct 06 19:35:33 volumio wireless.js[3307]: trying... Oct 06 19:35:33 volumio sudo[3413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:33 volumio sudo[3413]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:33 volumio sudo[3413]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:34 volumio wireless.js[3307]: trying... Oct 06 19:35:34 volumio sudo[3419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:34 volumio sudo[3419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:34 volumio sudo[3419]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:35 volumio wireless.js[3307]: trying... Oct 06 19:35:35 volumio sudo[3422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:35 volumio sudo[3422]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:35 volumio sudo[3422]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Oct 06 19:35:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Oct 06 19:35:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Oct 06 19:35:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Oct 06 19:35:36 volumio wireless.js[3307]: trying... Oct 06 19:35:36 volumio sudo[3425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:36 volumio sudo[3425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:36 volumio sudo[3425]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:37 volumio dhcpcd[3359]: eth0: using IPv4LL address 169.254.129.148 Oct 06 19:35:37 volumio dhcpcd[3359]: eth0: adding route to 169.254.0.0/16 Oct 06 19:35:37 volumio dhcpcd[3359]: eth0: adding default route Oct 06 19:35:37 volumio dhcpcd[3359]: forked to background, child pid 3447 Oct 06 19:35:37 volumio sudo[3358]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:37 volumio wireless.js[3307]: trying... Oct 06 19:35:37 volumio sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:37 volumio sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:37 volumio sudo[3449]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:37 volumio wpa_supplicant[3372]: wlan0: Trying to associate with SSID 'Bbox-275586ED' Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Oct 06 19:35:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: carrier acquired Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: connected to Access Point `Bbox-275586ED' Oct 06 19:35:38 volumio wpa_supplicant[3372]: wlan0: Associated with 82:29:52:d6:57:5d Oct 06 19:35:38 volumio wpa_supplicant[3372]: wlan0: CTRL-EVENT-CONNECTED - Connection to 82:29:52:d6:57:5d completed [id=0 id_str=] Oct 06 19:35:38 volumio wpa_supplicant[3372]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 06 19:35:38 volumio wpa_supplicant[3372]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: IAID dd:ad:78:63 Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: adding address fe80::da3a:ddff:fead:7863 Oct 06 19:35:38 volumio dhcpcd[3447]: ipv6_addaddr1: Permission denied Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: carrier lost Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: carrier acquired Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: IAID dd:ad:78:63 Oct 06 19:35:38 volumio wireless.js[3307]: trying... Oct 06 19:35:38 volumio sudo[3472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:38 volumio sudo[3472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:38 volumio sudo[3472]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: rebinding lease of 192.168.1.137 Oct 06 19:35:38 volumio dhcpcd[3447]: wlan0: soliciting an IPv6 router Oct 06 19:35:39 volumio wireless.js[3307]: trying... Oct 06 19:35:39 volumio sudo[3475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:39 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:39 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:40 volumio wireless.js[3307]: trying... Oct 06 19:35:40 volumio sudo[3478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:40 volumio sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:40 volumio sudo[3478]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:41 volumio wireless.js[3307]: trying... Oct 06 19:35:41 volumio sudo[3481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:41 volumio sudo[3481]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:41 volumio sudo[3481]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:42 volumio wireless.js[3307]: trying... Oct 06 19:35:42 volumio sudo[3499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:42 volumio sudo[3499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:42 volumio sudo[3499]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:42 volumio dhcpcd[3447]: wlan0: probing address 192.168.1.137/24 Oct 06 19:35:43 volumio wireless.js[3307]: trying... Oct 06 19:35:43 volumio sudo[3502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:43 volumio sudo[3502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:43 volumio sudo[3502]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:44 volumio wireless.js[3307]: trying... Oct 06 19:35:44 volumio sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:44 volumio sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:44 volumio sudo[3505]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:45 volumio wireless.js[3307]: trying... Oct 06 19:35:45 volumio sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:45 volumio sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:45 volumio sudo[3508]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:46 volumio wireless.js[3307]: trying... Oct 06 19:35:46 volumio sudo[3511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:46 volumio sudo[3511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:46 volumio sudo[3511]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:47 volumio wireless.js[3307]: trying... Oct 06 19:35:47 volumio sudo[3514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:47 volumio sudo[3514]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:47 volumio sudo[3514]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:48 volumio wireless.js[3307]: trying... Oct 06 19:35:48 volumio dhcpcd[3447]: wlan0: leased 192.168.1.137 for 86400 seconds Oct 06 19:35:48 volumio avahi-daemon[544]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.137. Oct 06 19:35:48 volumio dhcpcd[3447]: wlan0: adding route to 192.168.1.0/24 Oct 06 19:35:48 volumio avahi-daemon[544]: New relevant interface wlan0.IPv4 for mDNS. Oct 06 19:35:48 volumio dhcpcd[3447]: wlan0: adding default route via 192.168.1.254 Oct 06 19:35:48 volumio dhcpcd[3447]: eth0: deleting default route Oct 06 19:35:48 volumio avahi-daemon[544]: Registering new address record for 192.168.1.137 on wlan0.IPv4. Oct 06 19:35:48 volumio dhcpcd[988]: eth0: pid 3447 deleted default route Oct 06 19:35:48 volumio sudo[3517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 19:35:48 volumio sudo[3517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:48 volumio sudo[3517]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:48 volumio wireless.js[3307]: Connected to: ----Bbox-275586ED Oct 06 19:35:48 volumio wireless.js[3307]: ---- Oct 06 19:35:48 volumio sudo[3532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:48 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:48 volumio sudo[3532]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:48 volumio wireless.js[3307]: ... joined AP, wlan0 IPv4 is 192.168.1.137, ipV6 is undefined Oct 06 19:35:48 volumio wireless.js[3307]: It's done! AP Oct 06 19:35:48 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 06 19:35:48 volumio systemd[1]: Started Wireless Services. Oct 06 19:35:48 volumio sudo[3304]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:49 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:49 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:49 volumio volumio[1035]: verbose: New Socket.io Connection to 192.168.1.137:3000 from 192.168.1.138 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:49 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 06 19:35:49 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 06 19:35:50 volumio sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:50 volumio sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:50 volumio sudo[3550]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:50 volumio sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:50 volumio sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:50 volumio sudo[3553]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:50 volumio ntpd[770]: Listen normally on 7 wlan0 192.168.1.137:123 Oct 06 19:35:50 volumio ntpd[770]: new interface(s) found: waking up resolver Oct 06 19:35:50 volumio volumio[1035]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.138 UA: Mozilla/5.0 (Linux; Android 15; SM-A165F Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 06 19:35:50 volumio sudo[3556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:50 volumio sudo[3556]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:50 volumio sudo[3556]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:50 volumio sudo[3558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:50 volumio sudo[3558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:50 volumio sudo[3558]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:50 volumio volumio[1035]: verbose: New Socket.io Connection to 192.168.1.137 from 192.168.1.138 UA: Mozilla/5.0 (Linux; Android 15; SM-A165F Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 06 19:35:51 volumio volumio[1035]: verbose: New Socket.io Connection to 192.168.1.137:3000 from 192.168.1.138 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:51 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 19:35:51 volumio volumio[1035]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 06 19:35:51 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:51 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:51 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:51 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:51 volumio volumio[1035]: info: Listing playlists Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 19:35:51 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 06 19:35:52 volumio volumio[1035]: info: Volumio Network Manager: Network status updated: 3 Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 19:35:52 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:52 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:52 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:52 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 19:35:53 volumio volumio[1035]: info: Received Get System Info Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:53 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:53 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 06 19:35:53 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 06 19:35:53 volumio sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:53 volumio sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:53 volumio sudo[3576]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:53 volumio sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 19:35:53 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:53 volumio sudo[3579]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: Retrieving Cloud Streaming UI Oct 06 19:35:55 volumio volumio[1035]: info: Getting Tidal Cloud Configuration Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: Getting Qobuz Cloud Configuration Oct 06 19:35:55 volumio volumio[1035]: info: Asking plugin for UI Config Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: Getting Spotify Cloud Configuration Oct 06 19:35:55 volumio volumio[1035]: info: Asking plugin for UI Config Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: Saving Spotify Acccount Oct 06 19:35:55 volumio volumio[1035]: info: Got it Oct 06 19:35:55 volumio volumio[1035]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 06 19:35:55 volumio volumio[1035]: info: Got it Oct 06 19:35:55 volumio volumio[1035]: info: Got Tidal Cloud Configuration Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 19:35:55 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 06 19:35:56 volumio ntpd[770]: Soliciting pool server 82.64.81.218 Oct 06 19:35:57 volumio ntpd[770]: Soliciting pool server 109.190.177.203 Oct 06 19:35:57 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:57 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:57 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:57 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:57 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:57 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:57 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand update Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:35:57 volumio volumio[1035]: info: Oct 06 19:35:57 volumio volumio[1035]: ---------------------------- MPD announces state update: update Oct 06 19:35:57 volumio volumio[1035]: info: sendMpdCommand update took 5 milliseconds Oct 06 19:35:57 volumio volumio[1035]: info: ControllerMpd::getState Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:35:57 volumio volumio[1035]: info: Oct 06 19:35:57 volumio volumio[1035]: ---------------------------- MPD announces state update: update Oct 06 19:35:57 volumio volumio[1035]: info: sendMpdCommand status took 8 milliseconds Oct 06 19:35:57 volumio volumio[1035]: info: ControllerMpd::getState Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:35:57 volumio volumio[1035]: info: sendMpdCommand status took 5 milliseconds Oct 06 19:35:57 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:35:58 volumio volumio[1035]: info: Command Router : Notfying DB Updatetrue Oct 06 19:35:58 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:35:58 volumio volumio[1035]: info: ControllerMpd::pushState Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:35:58 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:35:58 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:35:58 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:35:58 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:35:58 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:58 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Oct 06 19:35:58 volumio volumio[1035]: info: ------------------------------ 85ms Oct 06 19:35:58 volumio volumio[1035]: info: sendMpdCommand status took 80 milliseconds Oct 06 19:35:58 volumio volumio[1035]: info: sendMpdCommand status took 79 milliseconds Oct 06 19:35:58 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:35:58 volumio volumio[1035]: info: Command Router : Notfying DB Updatefalse Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::Close All Modals sent Oct 06 19:35:58 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:35:58 volumio volumio[1035]: info: ControllerMpd::pushState Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:35:58 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:35:58 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:35:58 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:35:58 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:35:58 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:58 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Oct 06 19:35:58 volumio volumio[1035]: info: ------------------------------ 116ms Oct 06 19:35:58 volumio ntpd[770]: Soliciting pool server 195.35.29.178 Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 06 19:35:58 volumio volumio[1035]: info: Refreshing Cached IP Addresses Oct 06 19:35:58 volumio sudo[3592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 06 19:35:58 volumio sudo[3592]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:58 volumio sudo[3592]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:58 volumio sudo[3595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 19:35:58 volumio sudo[3595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:58 volumio sudo[3595]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:58 volumio volumio[1035]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 19:35:58 volumio sudo[3597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 19:35:58 volumio sudo[3597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:35:58 volumio sudo[3597]: pam_unix(sudo:session): session closed for user root Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:35:58 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:35:58 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:35:58 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:35:59 volumio ntpd[770]: Soliciting pool server 2a12:8ac1:a0::123:2 Oct 06 19:35:59 volumio ntpd[770]: Soliciting pool server 194.57.169.1 Oct 06 19:35:59 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 19:36:00 volumio ntpd[770]: Soliciting pool server 176.31.251.158 Oct 06 19:36:00 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand rescan Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:36:01 volumio volumio[1035]: info: Oct 06 19:36:01 volumio volumio[1035]: ---------------------------- MPD announces state update: update Oct 06 19:36:01 volumio volumio[1035]: info: sendMpdCommand rescan took 4 milliseconds Oct 06 19:36:01 volumio volumio[1035]: info: ControllerMpd::getState Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:36:01 volumio volumio[1035]: info: Oct 06 19:36:01 volumio volumio[1035]: ---------------------------- MPD announces state update: update Oct 06 19:36:01 volumio volumio[1035]: info: ControllerMpd::getState Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::sendMpdCommand status Oct 06 19:36:01 volumio volumio[1035]: info: sendMpdCommand status took 3 milliseconds Oct 06 19:36:01 volumio volumio[1035]: info: sendMpdCommand status took 2 milliseconds Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:36:01 volumio volumio[1035]: info: Command Router : Notfying DB Updatefalse Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::Close All Modals sent Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:36:01 volumio volumio[1035]: info: ControllerMpd::pushState Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:36:01 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Oct 06 19:36:01 volumio volumio[1035]: info: ------------------------------ 39ms Oct 06 19:36:01 volumio volumio[1035]: info: sendMpdCommand status took 38 milliseconds Oct 06 19:36:01 volumio volumio[1035]: info: sendMpdCommand status took 37 milliseconds Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:36:01 volumio volumio[1035]: info: Command Router : Notfying DB Updatefalse Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::Close All Modals sent Oct 06 19:36:01 volumio volumio[1035]: verbose: ControllerMpd::parseState Oct 06 19:36:01 volumio volumio[1035]: info: ControllerMpd::pushState Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::servicePushState Oct 06 19:36:01 volumio volumio[1035]: info: CoreStateMachine::pushState Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::volumioPushState Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Pushing multiroomSync output update for this device Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Pushing multiroomSync output Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio volumio[1035]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Oct 06 19:36:01 volumio volumio[1035]: info: ------------------------------ 76ms Oct 06 19:36:01 volumio volumio[1035]: info: Discovery: adding 641dedab-9063-4e77-9ccd-26f834d7350c Oct 06 19:36:01 volumio volumio[1035]: info: Discovery: Found device Volumio Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Found cast device: BboxTV-0be897dea1bd17b8fa93678ae5a160ea Oct 06 19:36:01 volumio volumio[1035]: info: MRS: Found cast device: Android-TV-75a9b393693e16dcc449cf105f757e3b Oct 06 19:36:01 volumio volumio[1035]: info: Discovery: this is already registered, 641dedab-9063-4e77-9ccd-26f834d7350c Oct 06 19:36:01 volumio volumio[1035]: info: Discovery: Found device Volumio Oct 06 19:36:01 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:36:01 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:01 volumio ntpd[770]: Soliciting pool server 109.190.177.205 Oct 06 19:36:02 volumio ntpd[770]: Soliciting pool server 91.224.149.41 Oct 06 19:36:03 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 19:36:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 19:36:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 19:36:04 volumio volumio[1035]: info: Discovery: Getting this device information Oct 06 19:36:04 volumio volumio[1035]: info: CoreCommandRouter::volumioGetState Oct 06 19:36:04 volumio volumio[1035]: info: CorePlayQueue::getTrack 0 Oct 06 19:36:04 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 19:36:04 volumio volumio[1035]: info: Disabling MyMusic plugin upnp Oct 06 19:36:04 volumio sudo[3621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 06 19:36:04 volumio sudo[3621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 19:36:04 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 06 19:36:04 volumio volumio[1035]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 19:36:04 volumio volumio[1035]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 06 19:36:06 volumio volumio[1035]: info: Enabling MyMusic plugin upnp Oct 06 19:36:06 volumio volumio[1035]: info: Enabling plugin upnp Oct 06 19:36:06 volumio volumio[1035]: info: Loading plugin "upnp"... Oct 06 19:36:06 volumio volumio[1035]: info: [1759772166152] Starting Upmpd Daemon Oct 06 19:36:06 volumio volumio[1035]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 19:36:06 volumio volumio[1035]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 19:36:06 volumio volumio[1035]: Error: listen EADDRINUSE: address already in use :::6599 Oct 06 19:36:06 volumio volumio[1035]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 06 19:36:06 volumio volumio[1035]: at listenInCluster (net.js:1366:12) Oct 06 19:36:06 volumio volumio[1035]: at Server.listen (net.js:1452:7) Oct 06 19:36:06 volumio volumio[1035]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 06 19:36:06 volumio volumio[1035]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 06 19:36:06 volumio volumio[1035]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 06 19:36:06 volumio volumio[1035]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 06 19:36:06 volumio volumio[1035]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 06 19:36:06 volumio volumio[1035]: code: 'EADDRINUSE', Oct 06 19:36:06 volumio volumio[1035]: errno: -98, Oct 06 19:36:06 volumio volumio[1035]: syscall: 'listen', Oct 06 19:36:06 volumio volumio[1035]: address: '::', Oct 06 19:36:06 volumio volumio[1035]: port: 6599 Oct 06 19:36:06 volumio volumio[1035]: } Oct 06 19:36:06 volumio volumio[1035]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 14:11:55 volumio ntpd[770]: receive: Unexpected origin timestamp 0xec8e7c86.73d713b4 does not match aorg 0000000000.00000000 from server@109.190.177.205 xmt 0xeca73d0b.157ab014 Oct 25 14:11:55 volumio sudo[3632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-06 19:35 Oct 25 14:11:55 volumio sudo[3632]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 14:11:55 volumio systemd[1]: Starting Daily apt download activities... PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"