-- Logs begin at Mon 2024-07-22 13:22:29 -04, end at Wed 2024-09-04 22:40:49 -04. -- Sep 04 22:39:06 volumio volumio[1486]: 62 Sep 04 22:39:06 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:06 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:06 volumio volumio[654]: info: Sep 04 22:39:06 volumio volumio[654]: ---------------------------- Client requests Volume 62 Sep 04 22:39:06 volumio volumio[654]: info: VolumeController::SetAlsaVolume62 Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:06 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:06 volumio volumio[1486]: [158B blob data] Sep 04 22:39:06 volumio volumio[1486]: {"time":1725503946296,"response":"volume Success"}62 Sep 04 22:39:06 volumio volumio[654]: info: Sep 04 22:39:06 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:06 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:06 volumio volumio[654]: info: Sep 04 22:39:06 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:06 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:06 volumio volumio[654]: info: sendMpdCommand status took 22 milliseconds Sep 04 22:39:06 volumio volumio[654]: info: sendMpdCommand status took 9 milliseconds Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:06 volumio volumio[654]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 04 22:39:06 volumio volumio[654]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:06 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:06 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:06 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:06 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:39:06 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:06 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:06 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:06 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:06 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:06 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:06 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:06 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:39:06 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:06 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:06 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:06 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:39:06 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:06 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:06 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:39:06 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:39:06 volumio volumio[654]: info: ------------------------------ 144ms Sep 04 22:39:06 volumio volumio[654]: info: ------------------------------ 126ms Sep 04 22:39:11 volumio nmbd[578]: [2024/09/04 22:39:11.943700, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 04 22:39:11 volumio nmbd[578]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.172 for name WORKGROUP<1d>. Sep 04 22:39:11 volumio nmbd[578]: This response was from IP 192.168.1.191, reporting an IP address of 192.168.1.191. Sep 04 22:39:17 volumio volumio[1486]: 56 Sep 04 22:39:18 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:18 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:18 volumio volumio[654]: info: Sep 04 22:39:18 volumio volumio[654]: ---------------------------- Client requests Volume 56 Sep 04 22:39:18 volumio volumio[654]: info: VolumeController::SetAlsaVolume56 Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:18 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:18 volumio volumio[1486]: [158B blob data] Sep 04 22:39:18 volumio volumio[1486]: {"time":1725503958129,"response":"volume Success"}56 Sep 04 22:39:18 volumio volumio[654]: info: Sep 04 22:39:18 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:18 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:18 volumio volumio[654]: info: Sep 04 22:39:18 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:18 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:18 volumio volumio[654]: info: sendMpdCommand status took 31 milliseconds Sep 04 22:39:18 volumio volumio[654]: info: sendMpdCommand status took 15 milliseconds Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:18 volumio volumio[654]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 04 22:39:18 volumio volumio[654]: info: sendMpdCommand playlistinfo took 29 milliseconds Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:18 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:18 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:18 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:18 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:39:18 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25099,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"901 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:18 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:18 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:18 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:18 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:18 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:18 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:18 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:39:18 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25099,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"901 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:18 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:18 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:18 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:39:18 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:18 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:18 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:39:18 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:39:18 volumio volumio[654]: info: ------------------------------ 381ms Sep 04 22:39:18 volumio volumio[654]: info: ------------------------------ 347ms Sep 04 22:39:39 volumio volumio[654]: info: CoreCommandRouter::volumioPlay Sep 04 22:39:39 volumio volumio[654]: info: CoreStateMachine::play index undefined Sep 04 22:39:39 volumio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 22:39:39 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:44 volumio volumio[1486]: 91 Sep 04 22:39:45 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:45 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:45 volumio volumio[654]: info: Sep 04 22:39:45 volumio volumio[654]: ---------------------------- Client requests Volume 91 Sep 04 22:39:45 volumio volumio[654]: info: VolumeController::SetAlsaVolume91 Sep 04 22:39:45 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:45 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:45 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:39:45 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:45 volumio volumio[1486]: [158B blob data] Sep 04 22:39:45 volumio volumio[1486]: {"time":1725503985089,"response":"volume Success"}91 Sep 04 22:39:45 volumio volumio[654]: info: Sep 04 22:39:45 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:45 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:45 volumio volumio[654]: info: Sep 04 22:39:45 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:45 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:45 volumio volumio[654]: info: sendMpdCommand status took 51 milliseconds Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:45 volumio volumio[654]: info: sendMpdCommand status took 101 milliseconds Sep 04 22:39:45 volumio volumio[654]: info: sendMpdCommand playlistinfo took 130 milliseconds Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:45 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:45 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:45 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:45 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:45 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51845,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"869 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:45 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:45 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:45 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:45 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:45 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:45 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:45 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:45 volumio volumio[1486]: 96 Sep 04 22:39:45 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:45 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:45 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:46 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:46 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:46 volumio volumio[654]: info: ------------------------------ 1065ms Sep 04 22:39:46 volumio volumio[654]: info: sendMpdCommand playlistinfo took 914 milliseconds Sep 04 22:39:46 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:46 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:46 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:46 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:46 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"814 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:46 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:46 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:46 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:46 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:46 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:46 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:46 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:46 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:46 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:46 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:47 volumio volumio[654]: info: ------------------------------ 1719ms Sep 04 22:39:47 volumio volumio[654]: info: Sep 04 22:39:47 volumio volumio[654]: ---------------------------- Client requests Volume 96 Sep 04 22:39:47 volumio volumio[654]: info: VolumeController::SetAlsaVolume96 Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:47 volumio volumio[1486]: [237B blob data] Sep 04 22:39:47 volumio volumio[1486]: {"time":1725503987210,"response":"volume Success"}96 Sep 04 22:39:47 volumio volumio[1486]: 100 Sep 04 22:39:47 volumio volumio[654]: info: Sep 04 22:39:47 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:47 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:47 volumio volumio[654]: info: Sep 04 22:39:47 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:47 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:47 volumio volumio[654]: info: sendMpdCommand status took 37 milliseconds Sep 04 22:39:47 volumio volumio[654]: info: sendMpdCommand status took 9 milliseconds Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:47 volumio volumio[654]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 04 22:39:47 volumio volumio[654]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:47 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:47 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"812 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:47 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:47 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:47 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:47 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:47 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"812 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:47 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:47 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:47 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:47 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:47 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:48 volumio volumio[654]: info: ------------------------------ 552ms Sep 04 22:39:48 volumio volumio[654]: info: ------------------------------ 527ms Sep 04 22:39:48 volumio volumio[654]: info: Sep 04 22:39:48 volumio volumio[654]: ---------------------------- Client requests Volume 100 Sep 04 22:39:48 volumio volumio[654]: info: VolumeController::SetAlsaVolume100 Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:48 volumio volumio[1486]: [237B blob data] Sep 04 22:39:48 volumio volumio[1486]: {"time":1725503988195,"response":"volume Success"}100 Sep 04 22:39:48 volumio volumio[654]: info: Sep 04 22:39:48 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:48 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:48 volumio volumio[654]: info: Sep 04 22:39:48 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:48 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:48 volumio volumio[654]: info: sendMpdCommand status took 18 milliseconds Sep 04 22:39:48 volumio volumio[654]: info: sendMpdCommand status took 12 milliseconds Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:48 volumio volumio[654]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 04 22:39:48 volumio volumio[654]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 22:39:48 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:48 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55094,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"810 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:48 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:48 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:48 volumio volumio[1486]: 50 Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:48 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55094,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"810 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:48 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:48 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:48 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:48 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:48 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[654]: info: ------------------------------ 477ms Sep 04 22:39:49 volumio volumio[654]: info: ------------------------------ 470ms Sep 04 22:39:49 volumio volumio[1486]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 04 22:39:49 volumio volumio[1486]: Dload Upload Total Spent Left Speed Sep 04 22:39:49 volumio volumio[654]: info: Sep 04 22:39:49 volumio volumio[654]: ---------------------------- Client requests Volume 50 Sep 04 22:39:49 volumio volumio[654]: info: VolumeController::SetAlsaVolume50 Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[1486]: [158B blob data] Sep 04 22:39:49 volumio volumio[1486]: {"time":1725503989099,"response":"volume Success"}50 Sep 04 22:39:49 volumio volumio[654]: info: Sep 04 22:39:49 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:49 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:49 volumio volumio[654]: info: Sep 04 22:39:49 volumio volumio[654]: ---------------------------- MPD announces state update: mixer Sep 04 22:39:49 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:39:49 volumio volumio[654]: info: sendMpdCommand status took 39 milliseconds Sep 04 22:39:49 volumio volumio[654]: info: sendMpdCommand status took 29 milliseconds Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:39:49 volumio volumio[654]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 04 22:39:49 volumio volumio[654]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:49 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:39:49 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:49 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:49 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55964,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:39:49 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:39:49 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:39:49 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:39:49 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:39:49 volumio volumio[654]: info: ------------------------------ 360ms Sep 04 22:39:49 volumio volumio[654]: info: ------------------------------ 342ms Sep 04 22:40:01 volumio volumio[654]: info: Clearing queue after UPNP request Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::ClearQueue Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::stop Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::stPlaybackTimer Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::updateTrackBlock Sep 04 22:40:01 volumio volumio[654]: info: CorePlayQueue::getTrackBlock Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:01 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:01 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:01 volumio volumio[654]: info: CoreStateMachine::serviceStop Sep 04 22:40:01 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:01 volumio volumio[654]: info: ControllerMpd::stop Sep 04 22:40:01 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 22:40:01 volumio volumio[654]: info: CorePlayQueue::clearPlayQueue Sep 04 22:40:01 volumio volumio[654]: info: CorePlayQueue::saveQueue Sep 04 22:40:01 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:01 volumio volumio[654]: info: CoreCommandRouter::volumioPushQueue Sep 04 22:40:01 volumio volumio[654]: error: Upnp client error: Error: This socket has been ended by the other party Sep 04 22:40:01 volumio volumio[654]: info: Sep 04 22:40:01 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:01 volumio volumio[654]: info: sendMpdCommand stop took 155 milliseconds Sep 04 22:40:01 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:01 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:02 volumio volumio[654]: info: Sep 04 22:40:02 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:02 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:02 volumio volumio[654]: info: sendMpdCommand status took 20 milliseconds Sep 04 22:40:02 volumio volumio[654]: info: sendMpdCommand status took 11 milliseconds Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:02 volumio volumio[654]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 04 22:40:02 volumio volumio[654]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:02 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:02 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:40:02 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::syncState stateService stop Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus stop Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: No code Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/b2a4c883bb654170a1204a713ccf9d68.flac?ts=1725503931","trackType":""} Sep 04 22:40:02 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::syncState stateService stop Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus stop Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: No code Sep 04 22:40:02 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:02 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:02 volumio volumio[654]: info: CoreCommandRouter::volumioPushState Sep 04 22:40:02 volumio volumio[654]: info: ------------------------------ 509ms Sep 04 22:40:02 volumio volumio[654]: info: ------------------------------ 504ms Sep 04 22:40:03 volumio volumio[654]: info: Starting UPNP Playback Sep 04 22:40:03 volumio volumio[654]: info: Preparing playback through UPNP Sep 04 22:40:03 volumio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 04 22:40:03 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:03 volumio volumio[654]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 22:40:03 volumio volumio[654]: info: Sep 04 22:40:03 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 33ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 23ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 23ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 14ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 22ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 15ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 23ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 14ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 23ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 14ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:04 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 22ms Sep 04 22:40:04 volumio volumio[654]: info: ------------------------------ 14ms Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:04 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:04 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:04 volumio volumio[654]: info: Sep 04 22:40:04 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:04 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:04 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:05 volumio volumio[654]: info: Sep 04 22:40:05 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand status took 120 milliseconds Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:05 volumio volumio[654]: info: Sep 04 22:40:05 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:05 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:05 volumio volumio[654]: info: Sep 04 22:40:05 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand status took 154 milliseconds Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand status took 106 milliseconds Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:05 volumio volumio[654]: info: Sep 04 22:40:05 volumio volumio[654]: ---------------------------- MPD announces system playlist update Sep 04 22:40:05 volumio volumio[654]: info: Ignoring MPD Status Update Sep 04 22:40:05 volumio volumio[654]: info: Sep 04 22:40:05 volumio volumio[654]: ---------------------------- MPD announces state update: player Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::getState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand status Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand playlistinfo took 139 milliseconds Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 140ms Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand status took 103 milliseconds Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand playlistinfo took 96 milliseconds Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand playlistinfo took 89 milliseconds Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:05 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:40:05 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","trackType":""} Sep 04 22:40:05 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus stop Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:05 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:40:05 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","trackType":""} Sep 04 22:40:05 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:40:05 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:05 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:05 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:40:05 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","trackType":""} Sep 04 22:40:05 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:40:05 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:05 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 569ms Sep 04 22:40:05 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:05 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 515ms Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 473ms Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 278ms Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand status took 280 milliseconds Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand playlistinfo took 226 milliseconds Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseState Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:05 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:40:05 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","trackType":""} Sep 04 22:40:05 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:40:05 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:05 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:05 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 469ms Sep 04 22:40:05 volumio volumio[654]: info: sendMpdCommand playlistinfo took 91 milliseconds Sep 04 22:40:05 volumio volumio[654]: verbose: ControllerMpd::parseTrackInfo Sep 04 22:40:05 volumio volumio[654]: info: ControllerMpd::pushState Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::servicePushState Sep 04 22:40:05 volumio volumio[654]: verbose: In UPNP mode Sep 04 22:40:05 volumio volumio[654]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"521 Kbps","isStreaming":false,"title":"805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","artist":"Music Assistant","album":null,"uri":"http://192.168.1.191:8097/single/uuid:548a2cb5-2925-37ea-1818-00e089103f55/805b16ac523f405ca782ea978b01bb33.flac?ts=1725504001","trackType":""} Sep 04 22:40:05 volumio volumio[654]: verbose: CURRENT POSITION 0 Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState stateService play Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::syncState currentStatus play Sep 04 22:40:05 volumio volumio[654]: info: Received an update from plugin. extracting info from payload Sep 04 22:40:05 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 04 22:40:05 volumio volumio[654]: info: CoreStateMachine::pushState Sep 04 22:40:05 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:05 volumio volumio[654]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:05 volumio volumio[654]: info: ------------------------------ 468ms Sep 04 22:40:27 volumio volumio[654]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 04 22:40:28 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 04 22:40:37 volumio volumio[654]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 04 22:40:37 volumio volumio[654]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 04 22:40:37 volumio volumio[654]: info: Preparing to save Alsa Options, stopping services first Sep 04 22:40:37 volumio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 04 22:40:37 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:37 volumio volumio[654]: error: Failed callmethod call: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:46 volumio volumio[654]: info: CoreCommandRouter::volumioGetState Sep 04 22:40:46 volumio volumio[654]: info: CorePlayQueue::getTrack 0 Sep 04 22:40:46 volumio volumio[654]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 22:40:46 volumio volumio[654]: TypeError: Cannot read property 'name' of undefined Sep 04 22:40:46 volumio volumio[654]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 04 22:40:46 volumio volumio[654]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Sep 04 22:40:46 volumio volumio[654]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Sep 04 22:40:46 volumio volumio[654]: at Socket.emit (events.js:315:20) Sep 04 22:40:46 volumio volumio[654]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 22:40:46 volumio volumio[654]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 04 22:40:46 volumio volumio[654]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 22:40:49 volumio sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 22:39 Sep 04 22:40:49 volumio sudo[2230]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"