-- Logs begin at Wed 2025-12-17 20:53:18 EET, end at Thu 2025-12-18 02:00:19 EET. -- Dec 18 01:59:02 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:14 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:20 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 01:59:27 volumio volumio[22883]: info: Discovery: Getting this device information Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::volumioGetState Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 01:59:27 volumio volumio[22883]: verbose: New Socket.io Connection to 192.168.88.30:3000 from 192.168.88.198 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 01:59:27 volumio volumio[22883]: info: Discovery: Getting this device information Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::volumioGetState Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 01:59:27 volumio volumio[22883]: verbose: New Socket.io Connection to 192.168.88.32:3000 from 192.168.88.198 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 01:59:27 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 01:59:28 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 01:59:40 volumio volumio[22883]: info: Discovery: Getting this device information Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::volumioGetState Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 01:59:40 volumio volumio[22883]: verbose: New Socket.io Connection to 192.168.88.30:3000 from 192.168.88.198 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 01:59:40 volumio volumio[22883]: info: Discovery: Getting this device information Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::volumioGetState Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 01:59:40 volumio volumio[22883]: verbose: New Socket.io Connection to 192.168.88.32:3000 from 192.168.88.198 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 01:59:40 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 01:59:41 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:47 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:53 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 01:59:59 volumio wpa_supplicant[888]: RRM: Ignoring radio measurement request: Not RRM network Dec 18 02:00:05 volumio volumio[22883]: info: Dec 18 02:00:05 volumio volumio[22883]: ---------------------------- MPD announces system playlist update Dec 18 02:00:05 volumio volumio[22883]: info: Ignoring MPD Status Update Dec 18 02:00:05 volumio volumio[22883]: info: Dec 18 02:00:05 volumio volumio[22883]: ---------------------------- MPD announces state update: player Dec 18 02:00:05 volumio volumio[22883]: info: ControllerMpd::getState Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand status Dec 18 02:00:05 volumio volumio[22883]: info: Dec 18 02:00:05 volumio volumio[22883]: ---------------------------- MPD announces system playlist update Dec 18 02:00:05 volumio volumio[22883]: info: Ignoring MPD Status Update Dec 18 02:00:05 volumio volumio[22883]: info: Dec 18 02:00:05 volumio volumio[22883]: ---------------------------- MPD announces state update: player Dec 18 02:00:05 volumio volumio[22883]: info: ControllerMpd::getState Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand status Dec 18 02:00:05 volumio volumio[22883]: info: ------------------------------ 2ms Dec 18 02:00:05 volumio volumio[22883]: info: sendMpdCommand status took 2 milliseconds Dec 18 02:00:05 volumio volumio[22883]: info: ------------------------------ 1ms Dec 18 02:00:05 volumio volumio[22883]: info: sendMpdCommand status took 1 milliseconds Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::parseState Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::parseState Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 02:00:05 volumio volumio[22883]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::parseTrackInfo Dec 18 02:00:05 volumio volumio[22883]: info: ControllerMpd::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::servicePushState Dec 18 02:00:05 volumio volumio[22883]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:05 volumio volumio[22883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12780323,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO 1 ROCK - ЕДНО РОК РАДИО","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Dec 18 02:00:05 volumio volumio[22883]: verbose: CURRENT POSITION 0 Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::syncState stateService play Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::syncState currentStatus play Dec 18 02:00:05 volumio volumio[22883]: info: Received an update from plugin. extracting info from payload Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:05 volumio volumio[22883]: info: ------------------------------ 11ms Dec 18 02:00:05 volumio volumio[22883]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 18 02:00:05 volumio volumio[22883]: verbose: ControllerMpd::parseTrackInfo Dec 18 02:00:05 volumio volumio[22883]: info: ControllerMpd::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::servicePushState Dec 18 02:00:05 volumio volumio[22883]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:05 volumio volumio[22883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12780323,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO 1 ROCK - ЕДНО РОК РАДИО","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Dec 18 02:00:05 volumio volumio[22883]: verbose: CURRENT POSITION 0 Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::syncState stateService play Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::syncState currentStatus play Dec 18 02:00:05 volumio volumio[22883]: info: Received an update from plugin. extracting info from payload Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:05 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:05 volumio volumio[22883]: info: ------------------------------ 19ms Dec 18 02:00:05 volumio volumio[22883]: info: [LastFM] Split composite title into artist: RADIO 1 ROCK and title: ЕДНО РОК РАДИО Dec 18 02:00:06 volumio volumio[22883]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"ЕДНО РОК РАДИО","url":"https://www.last.fm/music/Radio+1+Rock/_/%D0%95%D0%94%D0%9D%D0%9E+%D0%A0%D0%9E%D0%9A+%D0%A0%D0%90%D0%94%D0%98%D0%9E","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"54","playcount":"30143","artist":{"name":"Radio 1 Rock","url":"https://www.last.fm/music/Radio+1+Rock"},"userplaycount":"1714","userloved":"0","toptags":""}} Dec 18 02:00:06 volumio volumio[22883]: info: [LastFM] Updated missing track duration: 0 Dec 18 02:00:11 volumio wpa_supplicant[888]: wlan0: CTRL-EVENT-DISCONNECTED bssid=12:ef:15:ef:98:d6 reason=8 Dec 18 02:00:11 volumio dhcpcd[961]: wlan0: carrier lost Dec 18 02:00:11 volumio wpa_supplicant[888]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 18 02:00:11 volumio wpa_supplicant[888]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Dec 18 02:00:11 volumio avahi-daemon[614]: Withdrawing address record for 192.168.88.32 on wlan0. Dec 18 02:00:11 volumio avahi-daemon[614]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.88.32. Dec 18 02:00:11 volumio dhcpcd[961]: wlan0: deleting route to 192.168.88.0/24 Dec 18 02:00:11 volumio dhcpcd[961]: eth0: deleting route to 192.168.88.0/24 Dec 18 02:00:11 volumio dhcpcd[961]: wlan0: deleting default route via 192.168.88.3 Dec 18 02:00:11 volumio avahi-daemon[614]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 18 02:00:11 volumio dhcpcd[961]: eth0: deleting default route via 192.168.88.3 Dec 18 02:00:11 volumio dhcpcd[942]: eth0: pid 961 deleted route to 192.168.88.0/24 Dec 18 02:00:11 volumio dhcpcd[942]: eth0: pid 961 deleted default route via 192.168.88.3 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: A device disappeared from network Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Device volumio disappeared from network Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: A device disappeared from network Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Device volumiomaza disappeared from network Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:11 volumio volumio[22883]: info: Discovery: Disconnected from remote: 192.168.88.196 Dec 18 02:00:12 volumio volumio[22883]: info: Dec 18 02:00:12 volumio volumio[22883]: ---------------------------- MPD announces system playlist update Dec 18 02:00:12 volumio volumio[22883]: info: Ignoring MPD Status Update Dec 18 02:00:12 volumio volumio[22883]: info: Dec 18 02:00:12 volumio volumio[22883]: ---------------------------- MPD announces state update: player Dec 18 02:00:12 volumio volumio[22883]: info: ControllerMpd::getState Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand status Dec 18 02:00:12 volumio volumio[22883]: info: Dec 18 02:00:12 volumio volumio[22883]: ---------------------------- MPD announces system playlist update Dec 18 02:00:12 volumio volumio[22883]: info: Ignoring MPD Status Update Dec 18 02:00:12 volumio volumio[22883]: info: Dec 18 02:00:12 volumio volumio[22883]: ---------------------------- MPD announces state update: player Dec 18 02:00:12 volumio volumio[22883]: info: ControllerMpd::getState Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand status Dec 18 02:00:12 volumio volumio[22883]: info: ------------------------------ 3ms Dec 18 02:00:12 volumio volumio[22883]: info: sendMpdCommand status took 3 milliseconds Dec 18 02:00:12 volumio volumio[22883]: info: ------------------------------ 2ms Dec 18 02:00:12 volumio volumio[22883]: info: sendMpdCommand status took 2 milliseconds Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::parseState Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::parseState Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 02:00:12 volumio volumio[22883]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 02:00:12 volumio volumio[22883]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::parseTrackInfo Dec 18 02:00:12 volumio volumio[22883]: verbose: ControllerMpd::parseTrackInfo Dec 18 02:00:12 volumio volumio[22883]: info: ControllerMpd::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::servicePushState Dec 18 02:00:12 volumio volumio[22883]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:12 volumio volumio[22883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12788195,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KINGS OF LEON - USE SOMEBODY","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Dec 18 02:00:12 volumio volumio[22883]: verbose: CURRENT POSITION 0 Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::syncState stateService play Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::syncState currentStatus play Dec 18 02:00:12 volumio volumio[22883]: info: Received an update from plugin. extracting info from payload Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:12 volumio volumio[22883]: info: ControllerMpd::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::servicePushState Dec 18 02:00:12 volumio volumio[22883]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:12 volumio volumio[22883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12788195,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KINGS OF LEON - USE SOMEBODY","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Dec 18 02:00:12 volumio volumio[22883]: verbose: CURRENT POSITION 0 Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::syncState stateService play Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::syncState currentStatus play Dec 18 02:00:12 volumio volumio[22883]: info: Received an update from plugin. extracting info from payload Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreStateMachine::pushState Dec 18 02:00:12 volumio volumio[22883]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:12 volumio volumio[22883]: info: ------------------------------ 18ms Dec 18 02:00:12 volumio volumio[22883]: info: ------------------------------ 17ms Dec 18 02:00:12 volumio volumio[22883]: info: [LastFM] Split composite title into artist: KINGS OF LEON and title: USE SOMEBODY Dec 18 02:00:13 volumio volumio[22883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 02:00:13 volumio volumio[22883]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Dec 18 02:00:13 volumio volumio[22883]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 18 02:00:13 volumio volumio[22883]: errno: -3007, Dec 18 02:00:13 volumio volumio[22883]: code: 'ENOTFOUND', Dec 18 02:00:13 volumio volumio[22883]: syscall: 'getaddrinfo', Dec 18 02:00:13 volumio volumio[22883]: hostname: 'ws.audioscrobbler.com' Dec 18 02:00:13 volumio volumio[22883]: } Dec 18 02:00:13 volumio volumio[22883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 02:00:13 volumio ntpd[791]: Deleting interface #12 wlan0, 192.168.88.32#123, interface stats: received=0, sent=0, dropped=0, active_time=86193 secs Dec 18 02:00:13 volumio sudo[7680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 01:59 Dec 18 02:00:13 volumio sudo[7680]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:13 volumio sudo[7680]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:13 volumio volumio-remote-updater[617]: [2025-12-18 02:00:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 18 02:00:13 volumio volumio-remote-updater[617]: [2025-12-18 02:00:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 18 02:00:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 18 02:00:13 volumio systemd[1]: upmpdcli.service: Main process exited, code=killed, status=11/SEGV Dec 18 02:00:13 volumio systemd[1]: upmpdcli.service: Failed with result 'signal'. Dec 18 02:00:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 18 02:00:13 volumio systemd[1]: Started dynamicswap service. Dec 18 02:00:13 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 18 02:00:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 18 02:00:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Dec 18 02:00:13 volumio systemd[1]: Stopped Volumio Backend Module. Dec 18 02:00:13 volumio systemd[1]: Started Volumio Backend Module. Dec 18 02:00:13 volumio systemd[1]: Started dynamicswap service. Dec 18 02:00:13 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 18 02:00:14 volumio volumio[7737]: info: ------------------------------------------- Dec 18 02:00:14 volumio volumio[7737]: info: ----- Volumio3 ---- Dec 18 02:00:14 volumio volumio[7737]: info: ------------------------------------------- Dec 18 02:00:14 volumio volumio[7737]: info: ----- System startup ---- Dec 18 02:00:14 volumio volumio[7737]: info: ------------------------------------------- Dec 18 02:00:15 volumio volumio[7737]: info: MYVOLUMIO Environment detected Dec 18 02:00:15 volumio volumio[7737]: info: Plugin folders cleanup Dec 18 02:00:15 volumio volumio[7737]: info: Scanning into folder /volumio/app/plugins/ Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category audio_interface Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category miscellanea Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category music_service Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category plugins.json Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category system_controller Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category user_interface Dec 18 02:00:15 volumio volumio[7737]: info: Scanning into folder /data/plugins/ Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category audio_interface Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category system_controller Dec 18 02:00:15 volumio volumio[7737]: info: Scanning category user_interface Dec 18 02:00:15 volumio volumio[7737]: info: Plugin folders cleanup completed Dec 18 02:00:15 volumio volumio[7737]: info: ------------------------------------------- Dec 18 02:00:15 volumio volumio[7737]: info: ----- Core plugins startup ---- Dec 18 02:00:15 volumio volumio[7737]: info: ------------------------------------------- Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugins from folder /volumio/app/plugins/ Dec 18 02:00:15 volumio volumio[7737]: info: Adding plugin upnp to MyMusic Plugins Dec 18 02:00:15 volumio volumio[7737]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 18 02:00:15 volumio volumio[7737]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugins from folder /data/plugins/ Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "system"... Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "appearance"... Dec 18 02:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 18 02:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "network"... Dec 18 02:00:15 volumio volumio[7737]: info: Refreshing Cached IP Addresses Dec 18 02:00:15 volumio sudo[7764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 02:00:15 volumio sudo[7764]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:15 volumio sudo[7764]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:15 volumio sudo[7766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 02:00:15 volumio sudo[7766]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 18 02:00:15 volumio sudo[7766]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "services"... Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "alsa_controller"... Dec 18 02:00:15 volumio sudo[7777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 18 02:00:15 volumio sudo[7777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:15 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "wizard"... Dec 18 02:00:15 volumio volumio[7737]: info: Loading plugin "networkfs"... Dec 18 02:00:15 volumio volumio[7737]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Dec 18 02:00:15 volumio volumio[7737]: info: Cannot mount NAS GreenHamr at system boot, trial number 1 ,retrying in 5 seconds Dec 18 02:00:15 volumio volumio[7737]: info: Starting Udev Watcher for removable devices Dec 18 02:00:16 volumio volumio[7737]: info: Ignoring mount for partition: boot Dec 18 02:00:16 volumio volumio[7737]: info: Ignoring mount for partition: volumio Dec 18 02:00:16 volumio volumio[7737]: info: Ignoring mount for partition: volumio_data Dec 18 02:00:16 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "volumio_command_line_client"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "upnp"... Dec 18 02:00:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 18 02:00:16 volumio volumio[7737]: info: [1766016016026] Starting Upmpd Daemon Dec 18 02:00:16 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "my_music"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "mpd"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "upnp_browser"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "alarm-clock"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "airplay_emulation"... Dec 18 02:00:16 volumio volumio[7737]: info: Starting Shairport Sync Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "last_100"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "webradio"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "i2s_dacs"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "volumiodiscovery"... Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** For more information see Dec 18 02:00:16 volumio node[7737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 02:00:16 volumio volumio[7737]: *** WARNING *** For more information see Dec 18 02:00:16 volumio node[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 02:00:16 volumio node[7737]: *** WARNING *** For more information see Dec 18 02:00:16 volumio node[7737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 18 02:00:16 volumio node[7737]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 18 02:00:16 volumio node[7737]: *** WARNING *** For more information see Dec 18 02:00:16 volumio volumio[7737]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 18 02:00:16 volumio volumio[7737]: info: Discovery: Started advertising with name: Volumio Dec 18 02:00:16 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "outputs"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "albumart"... Dec 18 02:00:16 volumio volumio[7737]: info: Plugin example_plugin is not enabled Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "inputs"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "updater_comm"... Dec 18 02:00:16 volumio volumio[7737]: info: Plugin mpdemulation is not enabled Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "rest_api"... Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "websocket"... Dec 18 02:00:16 volumio volumio[7737]: info: Starting Socket.io Server version 2.3.0 Dec 18 02:00:16 volumio volumio[7737]: info: Loading plugin "fusiondsp"... Dec 18 02:00:16 volumio volumio[7737]: Forking 3 albumart workers Dec 18 02:00:17 volumio volumio[7737]: info: Applying required configuration parameters for plugin fusiondsp Dec 18 02:00:17 volumio volumio[7737]: info: Loading plugin "backup_restore"... Dec 18 02:00:17 volumio volumio[7737]: info: Applying required configuration parameters for plugin backup_restore Dec 18 02:00:17 volumio volumio[7737]: info: Loading plugin "lastfm"... Dec 18 02:00:17 volumio volumio[7737]: Starting albumart workers Dec 18 02:00:17 volumio volumio[7737]: Starting albumart workers Dec 18 02:00:17 volumio volumio[7737]: info: Loading plugin "randomizer"... Dec 18 02:00:17 volumio volumio[7737]: Starting albumart workers Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Dec 18 02:00:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Dec 18 02:00:17 volumio volumio[7737]: info: Applying required configuration parameters for plugin randomizer Dec 18 02:00:18 volumio volumio[7737]: info: Loading plugin "touch_display"... Dec 18 02:00:18 volumio volumio[7737]: info: Applying required configuration parameters for plugin touch_display Dec 18 02:00:18 volumio volumio[7737]: info: Loading i18n strings for locale en Dec 18 02:00:18 volumio volumio[7737]: Updating browse sources language Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::initPlayerControls Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: Express server listening on port 3000 Dec 18 02:00:18 volumio volumio[7737]: [Metrics] WebUI: 3s 968.77ms Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::resetVolumioState Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::getcurrentVolume Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::pushState Dec 18 02:00:18 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:18 volumio volumio-remote-updater[617]: [2025-12-18 02:00:18] [connect] Successful connection Dec 18 02:00:18 volumio volumio[7737]: info: Volumio Network Manager: Network status updated: 1 Dec 18 02:00:18 volumio volumio[7737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 18 02:00:18 volumio volumio[7737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 18 02:00:18 volumio volumio-remote-updater[617]: [2025-12-18 02:00:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766016018 101 Dec 18 02:00:18 volumio volumio[7737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Dec 18 02:00:18 volumio volumio[7737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 18 02:00:18 volumio volumio[7737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::setRepeat false single undefined Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::pushState Dec 18 02:00:18 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::setRandom null Dec 18 02:00:18 volumio volumio[7737]: info: CoreStateMachine::pushState Dec 18 02:00:18 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioPushState Dec 18 02:00:18 volumio volumio[7737]: info: Setting Device type: Raspberry PI Dec 18 02:00:18 volumio volumio[7737]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 18 02:00:18 volumio volumio[7737]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Dec 18 02:00:18 volumio volumio[7737]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 18 02:00:18 volumio volumio[7737]: info: Completed loading Core Plugins Dec 18 02:00:18 volumio volumio[7737]: info: Preparing to generate the ALSA configuration file Dec 18 02:00:18 volumio volumio[7737]: info: Discovery: adding 72701763-7ed2-48fe-b3ec-b91a6a543adc Dec 18 02:00:18 volumio volumio[7737]: info: Discovery: Found device VolumioMaza Dec 18 02:00:18 volumio volumio[7737]: info: Discovery: Connecting to remote: 192.168.88.196 Dec 18 02:00:18 volumio volumio[7737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 18 02:00:18 volumio volumio[7737]: info: Reading ALSA contributions from plugins. Dec 18 02:00:18 volumio volumio[7737]: info: Discovery: adding 52c74d82-78fb-43c7-9c41-2ccf0953c238 Dec 18 02:00:18 volumio volumio[7737]: info: Discovery: Found device Volumio Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioGetState Dec 18 02:00:18 volumio volumio[7737]: info: CorePlayQueue::getTrack 0 Dec 18 02:00:18 volumio volumio[7737]: info: Asound.conf file unchanged, so no further update is needed Dec 18 02:00:18 volumio volumio[7737]: info: Output device has changed, restarting MPD Dec 18 02:00:18 volumio sudo[7855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 02:00:18 volumio sudo[7855]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:18 volumio volumio[7737]: info: Output device has changed, restarting Shairport Sync Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 02:00:18 volumio sudo[7855]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:18 volumio sudo[7858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 02:00:18 volumio sudo[7858]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:18 volumio systemd[1]: Stopping Music Player Daemon... Dec 18 02:00:18 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 02:00:18 volumio volumio[7737]: info: ___________ START PLUGINS ___________ Dec 18 02:00:18 volumio volumio[7737]: info: ControllerMpd::onStart: Initializing MPD Dec 18 02:00:18 volumio systemd[1]: mpd.service: Succeeded. Dec 18 02:00:18 volumio volumio[7737]: info: Creating MPD Configuration file Dec 18 02:00:18 volumio systemd[1]: Stopped Music Player Daemon. Dec 18 02:00:18 volumio sudo[7865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 02:00:18 volumio sudo[7865]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:18 volumio systemd[1]: Starting Music Player Daemon... Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 02:00:18 volumio volumio[7737]: info: [1766016018878] CoreMusicLibrary::Adding element Media Servers Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:18 volumio sudo[7865]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:18 volumio sudo[7868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 02:00:18 volumio sudo[7868]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 02:00:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 18 02:00:18 volumio systemd[1]: mpd.service: Succeeded. Dec 18 02:00:18 volumio systemd[1]: Stopped Music Player Daemon. Dec 18 02:00:18 volumio systemd[1]: Starting Music Player Daemon... Dec 18 02:00:18 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 02:00:18 volumio volumio[7737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 02:00:18 volumio volumio[7737]: info: [1766016018976] CoreMusicLibrary::Adding element Last_100 Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 02:00:18 volumio volumio[7737]: info: [1766016018979] CoreMusicLibrary::Adding element Webradio Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:18 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 02:00:18 volumio volumio[7737]: info: Initializing BBC Radios Dec 18 02:00:18 volumio sudo[7874]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 02:00:18 volumio sudo[7874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:18 volumio sudo[7874]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 02:00:19 volumio volumio[7737]: info: Loading i18n strings for locale en Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 02:00:19 volumio volumio[7737]: info: FusionDsp - mixtype--------------------- None Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 02:00:19 volumio volumio[7737]: info: [1766016019059] CoreMusicLibrary::Adding element LastFM Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:19 volumio volumio[7737]: Cannot find translation for source LastFM Dec 18 02:00:19 volumio volumio[7737]: info: [LastFM] scrobbler initiated! Dec 18 02:00:19 volumio volumio[7737]: info: [LastFM] extended logging: false Dec 18 02:00:19 volumio volumio[7737]: info: [LastFM] try scrobble stream/radio plays: true Dec 18 02:00:19 volumio volumio[7737]: info: [LastFM] Left init routine Dec 18 02:00:19 volumio volumio[7737]: info: [LastFM] Socket already connected: true Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 18 02:00:19 volumio volumio[7737]: info: [1766016019081] CoreMusicLibrary::Adding element Randomizer Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 02:00:19 volumio volumio[7737]: Cannot find translation for source LastFM Dec 18 02:00:19 volumio volumio[7737]: Cannot find translation for source Randomizer Dec 18 02:00:19 volumio volumio[7737]: info: Loading i18n strings for locale en Dec 18 02:00:19 volumio volumio[7737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 02:00:19 volumio volumio[7737]: info: Volumio Calling Home Dec 18 02:00:19 volumio volumio[7737]: info: Preparing to generate the ALSA configuration file Dec 18 02:00:19 volumio sudo[7894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Dec 18 02:00:19 volumio sudo[7894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:19 volumio sudo[7896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Dec 18 02:00:19 volumio sudo[7896]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:19 volumio sudo[7900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 18 02:00:19 volumio sudo[7900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 02:00:19 volumio sudo[7894]: pam_unix(sudo:session): session closed for user root Dec 18 02:00:19 volumio systemd[1]: Reloading. Dec 18 02:00:19 volumio volumio[7737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 02:00:19 volumio volumio[7737]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Dec 18 02:00:19 volumio volumio[7737]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 18 02:00:19 volumio volumio[7737]: errno: -3007, Dec 18 02:00:19 volumio volumio[7737]: code: 'ENOTFOUND', Dec 18 02:00:19 volumio volumio[7737]: syscall: 'getaddrinfo', Dec 18 02:00:19 volumio volumio[7737]: hostname: 'ws.audioscrobbler.com' Dec 18 02:00:19 volumio volumio[7737]: } Dec 18 02:00:19 volumio volumio[7737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 02:00:19 volumio dhcpcd[961]: eth0: dhcp_sendudp: Network is unreachable Dec 18 02:00:19 volumio dhcpcd[961]: eth0: adding route to 192.168.88.0/24 Dec 18 02:00:19 volumio dhcpcd[961]: eth0: adding default route via 192.168.88.3 Dec 18 02:00:19 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 18 02:00:19 volumio sudo[7945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 01:59 Dec 18 02:00:19 volumio sudo[7945]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"