-- Logs begin at Sat 2024-08-31 07:43:46 UTC, end at Sat 2024-08-31 09:41:11 UTC. -- Aug 31 09:40:02 volumio-pulawy volumio[657]: info: Aug 31 09:40:02 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:02 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:02 volumio-pulawy volumio[657]: info: Aug 31 09:40:02 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:02 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:02 volumio-pulawy volumio[657]: info: ------------------------------ 45ms Aug 31 09:40:02 volumio-pulawy volumio[657]: info: sendMpdCommand status took 32 milliseconds Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:02 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:02 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76236067,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:02 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:02 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:02 volumio-pulawy volumio[657]: info: ------------------------------ 212ms Aug 31 09:40:04 volumio-pulawy volumio[657]: info: Aug 31 09:40:04 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:04 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:04 volumio-pulawy volumio[657]: info: Aug 31 09:40:04 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:04 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:04 volumio-pulawy volumio[657]: info: ------------------------------ 25ms Aug 31 09:40:04 volumio-pulawy volumio[657]: info: sendMpdCommand status took 13 milliseconds Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:04 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:04 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76238312,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:04 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:04 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:04 volumio-pulawy volumio[657]: info: ------------------------------ 153ms Aug 31 09:40:10 volumio-pulawy volumio[657]: info: Aug 31 09:40:10 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:10 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:10 volumio-pulawy volumio[657]: info: Aug 31 09:40:10 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:10 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:10 volumio-pulawy volumio[657]: info: ------------------------------ 39ms Aug 31 09:40:10 volumio-pulawy volumio[657]: info: sendMpdCommand status took 33 milliseconds Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:10 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:10 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76244194,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:10 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:10 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:10 volumio-pulawy volumio[657]: info: ------------------------------ 200ms Aug 31 09:40:15 volumio-pulawy volumio[657]: info: Aug 31 09:40:15 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:15 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:15 volumio-pulawy volumio[657]: info: Aug 31 09:40:15 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:15 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:15 volumio-pulawy volumio[657]: info: ------------------------------ 33ms Aug 31 09:40:15 volumio-pulawy volumio[657]: info: sendMpdCommand status took 38 milliseconds Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:15 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:15 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76249444,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:15 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:15 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:15 volumio-pulawy volumio[657]: info: ------------------------------ 158ms Aug 31 09:40:17 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 09:40:17 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 09:40:17 volumio-pulawy volumio[657]: info: Discovery: Getting this device information Aug 31 09:40:17 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioGetState Aug 31 09:40:17 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 09:40:21 volumio-pulawy volumio[657]: info: Aug 31 09:40:21 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:21 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:21 volumio-pulawy volumio[657]: info: Aug 31 09:40:21 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:21 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:21 volumio-pulawy volumio[657]: info: ------------------------------ 32ms Aug 31 09:40:21 volumio-pulawy volumio[657]: info: sendMpdCommand status took 37 milliseconds Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:21 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:21 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76254695,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:21 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:21 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:21 volumio-pulawy volumio[657]: info: ------------------------------ 224ms Aug 31 09:40:24 volumio-pulawy volumio[657]: info: Aug 31 09:40:24 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:24 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:24 volumio-pulawy volumio[657]: info: Aug 31 09:40:24 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:24 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:24 volumio-pulawy volumio[657]: info: ------------------------------ 53ms Aug 31 09:40:24 volumio-pulawy volumio[657]: info: sendMpdCommand status took 23 milliseconds Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:24 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:24 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76257694,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:24 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:24 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:24 volumio-pulawy volumio[657]: info: ------------------------------ 214ms Aug 31 09:40:29 volumio-pulawy volumio[657]: info: Aug 31 09:40:29 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:29 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:29 volumio-pulawy volumio[657]: info: Aug 31 09:40:29 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:29 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:29 volumio-pulawy volumio[657]: info: ------------------------------ 26ms Aug 31 09:40:29 volumio-pulawy volumio[657]: info: sendMpdCommand status took 17 milliseconds Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:29 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:29 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76262938,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:29 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:29 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:29 volumio-pulawy volumio[657]: info: ------------------------------ 102ms Aug 31 09:40:34 volumio-pulawy volumio[657]: info: Aug 31 09:40:34 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:34 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:34 volumio-pulawy volumio[657]: info: Aug 31 09:40:34 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:34 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:34 volumio-pulawy volumio[657]: info: ------------------------------ 29ms Aug 31 09:40:34 volumio-pulawy volumio[657]: info: sendMpdCommand status took 16 milliseconds Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:34 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:34 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76268188,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:34 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:34 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:34 volumio-pulawy volumio[657]: info: ------------------------------ 215ms Aug 31 09:40:40 volumio-pulawy volumio[657]: info: Aug 31 09:40:40 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:40 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:40 volumio-pulawy volumio[657]: info: Aug 31 09:40:40 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:40 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:40 volumio-pulawy volumio[657]: info: ------------------------------ 41ms Aug 31 09:40:40 volumio-pulawy volumio[657]: info: sendMpdCommand status took 24 milliseconds Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:40 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:40 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76274192,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:40 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:40 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:40 volumio-pulawy volumio[657]: info: ------------------------------ 214ms Aug 31 09:40:42 volumio-pulawy volumio[657]: info: Aug 31 09:40:42 volumio-pulawy volumio[657]: ---------------------------- MPD announces system playlist update Aug 31 09:40:42 volumio-pulawy volumio[657]: info: Ignoring MPD Status Update Aug 31 09:40:42 volumio-pulawy volumio[657]: info: Aug 31 09:40:42 volumio-pulawy volumio[657]: ---------------------------- MPD announces state update: player Aug 31 09:40:42 volumio-pulawy volumio[657]: info: ControllerMpd::getState Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand status Aug 31 09:40:42 volumio-pulawy volumio[657]: info: ------------------------------ 27ms Aug 31 09:40:42 volumio-pulawy volumio[657]: info: sendMpdCommand status took 17 milliseconds Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseState Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 09:40:42 volumio-pulawy volumio[657]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: ControllerMpd::parseTrackInfo Aug 31 09:40:42 volumio-pulawy volumio[657]: info: ControllerMpd::pushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreCommandRouter::servicePushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CorePlayQueue::getTrack 0 Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76276438,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"John Lennon - Jealous Guy w Ballady Wszech Czasów","artist":"20","album":null,"uri":"https://stream.open.fm/20","trackType":"fm/20"} Aug 31 09:40:42 volumio-pulawy volumio[657]: verbose: CURRENT POSITION 0 Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState stateService play Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreStateMachine::syncState currentStatus play Aug 31 09:40:42 volumio-pulawy volumio[657]: info: Received an update from plugin. extracting info from payload Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreStateMachine::pushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: CoreCommandRouter::volumioPushState Aug 31 09:40:42 volumio-pulawy volumio[657]: info: ------------------------------ 104ms Aug 31 09:40:47 volumio-pulawy dhcpcd[579]: wlan0: carrier lost Aug 31 09:40:47 volumio-pulawy kernel: rtlwifi: AP off, try to reconnect now Aug 31 09:40:47 volumio-pulawy kernel: wlan0: Connection to AP 74:ac:b9:b1:bf:be lost Aug 31 09:40:47 volumio-pulawy wpa_supplicant[563]: wlan0: CTRL-EVENT-DISCONNECTED bssid=74:ac:b9:b1:bf:be reason=4 locally_generated=1 Aug 31 09:40:47 volumio-pulawy wpa_supplicant[563]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 31 09:40:47 volumio-pulawy avahi-daemon[324]: Withdrawing address record for 192.168.2.10 on wlan0. Aug 31 09:40:47 volumio-pulawy avahi-daemon[324]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.10. Aug 31 09:40:47 volumio-pulawy avahi-daemon[324]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 31 09:40:47 volumio-pulawy dhcpcd[579]: wlan0: deleting route to 192.168.2.0/24 Aug 31 09:40:47 volumio-pulawy dhcpcd[579]: wlan0: deleting default route via 192.168.2.1 Aug 31 09:40:47 volumio-pulawy volumio[657]: info: mDNS: A device disapperared from network Aug 31 09:40:48 volumio-pulawy wpa_supplicant[563]: wlan0: SME: Trying to authenticate with 74:ac:b9:b1:bf:be (SSID='Burek2' freq=2412 MHz) Aug 31 09:40:48 volumio-pulawy kernel: wlan0: authenticate with 74:ac:b9:b1:bf:be Aug 31 09:40:48 volumio-pulawy kernel: wlan0: send auth to 74:ac:b9:b1:bf:be (try 1/3) Aug 31 09:40:48 volumio-pulawy kernel: wlan0: send auth to 74:ac:b9:b1:bf:be (try 2/3) Aug 31 09:40:49 volumio-pulawy kernel: wlan0: send auth to 74:ac:b9:b1:bf:be (try 3/3) Aug 31 09:40:49 volumio-pulawy kernel: wlan0: authentication with 74:ac:b9:b1:bf:be timed out Aug 31 09:40:50 volumio-pulawy ntpd[470]: Deleting interface #426 wlan0, 192.168.2.10#123, interface stats: received=1837, sent=1893, dropped=0, active_time=323694 secs Aug 31 09:40:50 volumio-pulawy ntpd[470]: 80.50.102.114 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy ntpd[470]: 213.222.217.11 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy ntpd[470]: 178.215.228.24 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy ntpd[470]: 162.159.200.123 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy ntpd[470]: 91.212.242.21 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy ntpd[470]: 150.254.65.62 local addr 192.168.2.10 -> Aug 31 09:40:50 volumio-pulawy wpa_supplicant[563]: wlan0: SME: Trying to authenticate with 74:ac:b9:b1:bf:be (SSID='Burek2' freq=2412 MHz) Aug 31 09:40:50 volumio-pulawy kernel: wlan0: authenticate with 74:ac:b9:b1:bf:be Aug 31 09:40:50 volumio-pulawy kernel: wlan0: send auth to 74:ac:b9:b1:bf:be (try 1/3) Aug 31 09:40:50 volumio-pulawy kernel: wlan0: authenticated Aug 31 09:40:50 volumio-pulawy wpa_supplicant[563]: wlan0: Trying to associate with 74:ac:b9:b1:bf:be (SSID='Burek2' freq=2412 MHz) Aug 31 09:40:50 volumio-pulawy kernel: wlan0: associate with 74:ac:b9:b1:bf:be (try 1/3) Aug 31 09:40:51 volumio-pulawy kernel: wlan0: RX AssocResp from 74:ac:b9:b1:bf:be (capab=0x1431 status=0 aid=1) Aug 31 09:40:51 volumio-pulawy wpa_supplicant[563]: wlan0: Associated with 74:ac:b9:b1:bf:be Aug 31 09:40:51 volumio-pulawy kernel: wlan0: associated Aug 31 09:40:51 volumio-pulawy wpa_supplicant[563]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 31 09:40:51 volumio-pulawy wpa_supplicant[563]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=PL Aug 31 09:40:51 volumio-pulawy wpa_supplicant[563]: wlan0: WPA: Key negotiation completed with 74:ac:b9:b1:bf:be [PTK=CCMP GTK=CCMP] Aug 31 09:40:51 volumio-pulawy wpa_supplicant[563]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:ac:b9:b1:bf:be completed [id=0 id_str=] Aug 31 09:40:51 volumio-pulawy dhcpcd[579]: wlan0: carrier acquired Aug 31 09:40:51 volumio-pulawy dhcpcd[579]: wlan0: IAID 02:da:2c:21 Aug 31 09:40:51 volumio-pulawy kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by 74:ac:b9:b1:bf:be Aug 31 09:40:51 volumio-pulawy dhcpcd[579]: wlan0: soliciting an IPv6 router Aug 31 09:40:52 volumio-pulawy dhcpcd[579]: wlan0: rebinding lease of 192.168.2.10 Aug 31 09:40:57 volumio-pulawy dhcpcd[579]: wlan0: probing for an IPv4LL address Aug 31 09:40:57 volumio-pulawy dhcpcd[579]: wlan0: DHCP lease expired Aug 31 09:40:57 volumio-pulawy dhcpcd[579]: wlan0: soliciting a DHCP lease Aug 31 09:40:58 volumio-pulawy dhcpcd[579]: wlan0: offered 192.168.2.10 from 192.168.2.1 Aug 31 09:40:58 volumio-pulawy dhcpcd[579]: wlan0: probing address 192.168.2.10/24 Aug 31 09:41:01 volumio-pulawy dhcpcd[579]: wlan0: using IPv4LL address 169.254.195.119 Aug 31 09:41:01 volumio-pulawy dhcpcd[579]: wlan0: adding route to 169.254.0.0/16 Aug 31 09:41:01 volumio-pulawy dhcpcd[579]: wlan0: adding default route Aug 31 09:41:01 volumio-pulawy avahi-daemon[324]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.195.119. Aug 31 09:41:01 volumio-pulawy avahi-daemon[324]: New relevant interface wlan0.IPv4 for mDNS. Aug 31 09:41:01 volumio-pulawy avahi-daemon[324]: Registering new address record for 169.254.195.119 on wlan0.IPv4. Aug 31 09:41:03 volumio-pulawy ntpd[470]: Listen normally on 427 wlan0 169.254.195.119:123 Aug 31 09:41:03 volumio-pulawy ntpd[470]: new interface(s) found: waking up resolver Aug 31 09:41:03 volumio-pulawy dhcpcd[579]: wlan0: leased 192.168.2.10 for infinity Aug 31 09:41:03 volumio-pulawy avahi-daemon[324]: Registering new address record for 192.168.2.10 on wlan0.IPv4. Aug 31 09:41:03 volumio-pulawy dhcpcd[579]: wlan0: adding route to 192.168.2.0/24 Aug 31 09:41:03 volumio-pulawy dhcpcd[579]: wlan0: changing default route via 192.168.2.1 Aug 31 09:41:03 volumio-pulawy avahi-daemon[324]: Withdrawing address record for 169.254.195.119 on wlan0. Aug 31 09:41:03 volumio-pulawy avahi-daemon[324]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.195.119. Aug 31 09:41:03 volumio-pulawy avahi-daemon[324]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.10. Aug 31 09:41:03 volumio-pulawy dhcpcd[579]: wlan0: deleting route to 169.254.0.0/16 Aug 31 09:41:03 volumio-pulawy volumio[657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 09:41:04 volumio-pulawy volumio[657]: Error: bind EADDRNOTAVAIL 169.254.195.119:50765 Aug 31 09:41:04 volumio-pulawy volumio[657]: at dgram.js:337:20 Aug 31 09:41:04 volumio-pulawy volumio[657]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Aug 31 09:41:04 volumio-pulawy volumio[657]: errno: -99, Aug 31 09:41:04 volumio-pulawy volumio[657]: code: 'EADDRNOTAVAIL', Aug 31 09:41:04 volumio-pulawy volumio[657]: syscall: 'bind', Aug 31 09:41:04 volumio-pulawy volumio[657]: address: '169.254.195.119', Aug 31 09:41:04 volumio-pulawy volumio[657]: port: 50765 Aug 31 09:41:04 volumio-pulawy volumio[657]: } Aug 31 09:41:04 volumio-pulawy volumio[657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 09:41:04 volumio-pulawy dhcpcd[579]: wlan0: pid 579 deleted default route via 192.168.2.1 Aug 31 09:41:05 volumio-pulawy ntpd[470]: Listen normally on 428 wlan0 192.168.2.10:123 Aug 31 09:41:05 volumio-pulawy ntpd[470]: Deleting interface #427 wlan0, 169.254.195.119#123, interface stats: received=0, sent=0, dropped=1, active_time=2 secs Aug 31 09:41:05 volumio-pulawy ntpd[470]: 213.222.217.11 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: 178.215.228.24 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: 162.159.200.123 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: 91.212.242.21 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: 150.254.65.62 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: 80.50.102.114 local addr 169.254.195.119 -> Aug 31 09:41:05 volumio-pulawy ntpd[470]: new interface(s) found: waking up resolver Aug 31 09:41:10 volumio-pulawy ntpd[470]: Soliciting pool server 2a01:1102:0:b::2 Aug 31 09:41:11 volumio-pulawy sudo[15078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 09:40 Aug 31 09:41:11 volumio-pulawy sudo[15078]: 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="682e0b17433cee9f9da31457f545345105e06e63" VOLUMIO_FE_VERSION="fba46add594d630e1b506bc42f1beddaad53fb64" VOLUMIO_FE3_VERSION="c23fef6dba8e02db978bd39bd8d26872e60ada6e" VOLUMIO_BE_VERSION="f7885966dee9f148ddf4f5050103a7d6734e1a10" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Dec 2021 04:57:50 PM CET" VOLUMIO_VERSION="3.179" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d5d4c489f0b1e608d9cefb791a9102d"