-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-09-07 16:37:15 UTC. -- Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::volumioPlay Sep 07 16:36:07 volumio volumio[1102]: info: CoreStateMachine::play index undefined Sep 07 16:36:07 volumio volumio[1102]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 16:36:07 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:07 volumio volumio[1102]: info: CoreStateMachine::startPlaybackTimer Sep 07 16:36:07 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 07 16:36:07 volumio volumio[1102]: info: [1725726967401] ControllerWebradio::clearAddPlayTrack Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 16:36:07 volumio volumio[1102]: info: Sep 07 16:36:07 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:07 volumio volumio[1102]: info: sendMpdCommand stop took 7 milliseconds Sep 07 16:36:07 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 16:36:07 volumio volumio[1102]: info: Sep 07 16:36:07 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:07 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:07 volumio volumio[1102]: info: Sep 07 16:36:07 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:07 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:07 volumio volumio[1102]: info: sendMpdCommand status took 3 milliseconds Sep 07 16:36:07 volumio volumio[1102]: info: sendMpdCommand clear took 3 milliseconds Sep 07 16:36:07 volumio volumio[1102]: info: sendMpdCommand status took 1 milliseconds Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand load "https://cervera.eldialdigital.com:21111/stream" Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:07 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:07 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:07 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 16:36:07 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:07 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:07 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 07 16:36:07 volumio volumio[1102]: info: ------------------------------ 11ms Sep 07 16:36:07 volumio volumio[1102]: info: Sep 07 16:36:07 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:07 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:07 volumio volumio[1102]: error: updateQueue error: null Sep 07 16:36:07 volumio volumio[1102]: info: ------------------------------ 17ms Sep 07 16:36:07 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 07 16:36:07 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:07 volumio volumio[1102]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 16:36:07 volumio volumio[1102]: info: ------------------------------ 22ms Sep 07 16:36:07 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:08 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand add "https://cervera.eldialdigital.com:21111/stream" Sep 07 16:36:08 volumio volumio[1102]: error: updateQueue error: null Sep 07 16:36:08 volumio volumio[1102]: info: ------------------------------ 1401ms Sep 07 16:36:08 volumio volumio[1102]: info: Sep 07 16:36:08 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:08 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:08 volumio volumio[1102]: info: sendMpdCommand add "https://cervera.eldialdigital.com:21111/stream" took 2 milliseconds Sep 07 16:36:08 volumio volumio[1102]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 16:36:08 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand play Sep 07 16:36:08 volumio volumio[1102]: info: Sep 07 16:36:08 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:08 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:08 volumio volumio[1102]: info: ------------------------------ 2ms Sep 07 16:36:08 volumio volumio[1102]: info: sendMpdCommand play took 2 milliseconds Sep 07 16:36:08 volumio volumio[1102]: info: ------------------------------ 2ms Sep 07 16:36:09 volumio volumio[1102]: info: Sep 07 16:36:09 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:09 volumio volumio[1102]: info: Sep 07 16:36:09 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:09 volumio volumio[1102]: info: Sep 07 16:36:09 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand status took 5 milliseconds Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:09 volumio volumio[1102]: info: Sep 07 16:36:09 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand status took 13 milliseconds Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand status took 13 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand clearerror took 11 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand status took 9 milliseconds Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:09 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:09 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:09 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:09 volumio volumio[1102]: info: ------------------------------ 35ms Sep 07 16:36:09 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand clearerror took 32 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand clearerror took 31 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 07 16:36:09 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:09 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:09 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:09 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:09 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:09 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:09 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:09 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:09 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:09 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:09 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:09 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:09 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:09 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:09 volumio volumio[1102]: info: ------------------------------ 75ms Sep 07 16:36:09 volumio volumio[1102]: info: ------------------------------ 75ms Sep 07 16:36:09 volumio volumio[1102]: info: ------------------------------ 70ms Sep 07 16:36:09 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:09 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:09 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:09 volumio volumio[1102]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 16:36:09 volumio volumio[1102]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 16:36:18 volumio wpa_supplicant[912]: RRM: Ignoring radio measurement request: Not RRM network Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::volumioPlay Sep 07 16:36:47 volumio volumio[1102]: info: CoreStateMachine::play index undefined Sep 07 16:36:47 volumio volumio[1102]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 16:36:47 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:47 volumio volumio[1102]: info: CoreStateMachine::startPlaybackTimer Sep 07 16:36:47 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 07 16:36:47 volumio volumio[1102]: info: [1725727007493] ControllerWebradio::clearAddPlayTrack Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand stop took 8 milliseconds Sep 07 16:36:47 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clear Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:47 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:47 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand status took 2 milliseconds Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand clear took 3 milliseconds Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand status took 2 milliseconds Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand load "https://cervera.eldialdigital.com:21111/stream" Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:47 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:47 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:47 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 16:36:47 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:47 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:47 volumio volumio[1102]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 11ms Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:47 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:47 volumio volumio[1102]: error: updateQueue error: null Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 11ms Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:47 volumio volumio[1102]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 16ms Sep 07 16:36:47 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:47 volumio wpa_supplicant[912]: RRM: Ignoring radio measurement request: Not RRM network Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand add "https://cervera.eldialdigital.com:21111/stream" Sep 07 16:36:47 volumio volumio[1102]: error: updateQueue error: null Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:47 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 462ms Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand add "https://cervera.eldialdigital.com:21111/stream" took 1 milliseconds Sep 07 16:36:47 volumio volumio[1102]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 16:36:47 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand play Sep 07 16:36:47 volumio volumio[1102]: info: Sep 07 16:36:47 volumio volumio[1102]: ---------------------------- MPD announces system playlist update Sep 07 16:36:47 volumio volumio[1102]: info: Ignoring MPD Status Update Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 3ms Sep 07 16:36:47 volumio volumio[1102]: info: sendMpdCommand play took 2 milliseconds Sep 07 16:36:47 volumio volumio[1102]: info: ------------------------------ 2ms Sep 07 16:36:48 volumio volumio[1102]: info: Sep 07 16:36:48 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:48 volumio volumio[1102]: info: Sep 07 16:36:48 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:48 volumio volumio[1102]: info: Sep 07 16:36:48 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand status took 7 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:48 volumio volumio[1102]: info: Sep 07 16:36:48 volumio volumio[1102]: ---------------------------- MPD announces state update: player Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::getState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand status Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand status took 13 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand clearerror took 10 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand status took 10 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand status took 7 milliseconds Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseState Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:48 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:48 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:48 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:48 volumio volumio[1102]: info: ------------------------------ 31ms Sep 07 16:36:48 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand clearerror took 20 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 07 16:36:48 volumio volumio[1102]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:48 volumio volumio[1102]: verbose: ControllerMpd::parseTrackInfo Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:48 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:48 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:48 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:48 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:48 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:48 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:48 volumio volumio[1102]: info: ControllerMpd::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::servicePushState Sep 07 16:36:48 volumio volumio[1102]: info: CorePlayQueue::getTrack 0 Sep 07 16:36:48 volumio volumio[1102]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://cervera.eldialdigital.com:21111/stream","trackType":""} Sep 07 16:36:48 volumio volumio[1102]: verbose: CURRENT POSITION 0 Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState stateService pause Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::syncState currentStatus stop Sep 07 16:36:48 volumio volumio[1102]: info: CoreStateMachine::pushState Sep 07 16:36:48 volumio volumio[1102]: info: CoreCommandRouter::volumioPushState Sep 07 16:36:48 volumio volumio[1102]: info: ------------------------------ 58ms Sep 07 16:36:48 volumio volumio[1102]: info: ------------------------------ 54ms Sep 07 16:36:48 volumio volumio[1102]: info: ------------------------------ 49ms Sep 07 16:36:48 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:48 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:48 volumio volumio[1102]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Sep 07 16:36:48 volumio volumio[1102]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 16:36:48 volumio volumio[1102]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 16:36:48 volumio volumio[1102]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7xqkcadrJ7XeHXlpxacRWly81rG3.024ede094d6cd43d7ac7aafd756ba8ea.state.status' Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 16:36:59 volumio volumio[1102]: info: Retrieving Cloud Streaming UI Sep 07 16:36:59 volumio volumio[1102]: info: Getting Tidal Cloud Configuration Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 16:36:59 volumio volumio[1102]: info: Getting Qobuz Cloud Configuration Sep 07 16:36:59 volumio volumio[1102]: info: Asking plugin for UI Config Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 16:36:59 volumio volumio[1102]: info: Getting Spotify Cloud Configuration Sep 07 16:36:59 volumio volumio[1102]: info: Asking plugin for UI Config Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 16:36:59 volumio volumio[1102]: info: Saving Spotify Acccount Sep 07 16:36:59 volumio volumio[1102]: info: Got it Sep 07 16:36:59 volumio volumio[1102]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 07 16:36:59 volumio volumio[1102]: info: Got Tidal Cloud Configuration Sep 07 16:36:59 volumio volumio[1102]: info: Got it Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 16:36:59 volumio volumio[1102]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 16:37:01 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 16:37:01 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 07 16:37:05 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 16:37:09 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 16:37:09 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 07 16:37:09 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 07 16:37:12 volumio volumio[1102]: info: Error browsing device nas for shares : Error: Command failed: echo volumio | smbclient -L nas.local. Sep 07 16:37:12 volumio volumio[1102]: Unable to initialize messaging context Sep 07 16:37:13 volumio volumio[1102]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 16:37:14 volumio volumio[1102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 16:37:14 volumio volumio[1102]: Error: dns service error: unknown Sep 07 16:37:14 volumio volumio[1102]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 07 16:37:14 volumio volumio[1102]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 07 16:37:14 volumio volumio[1102]: errorCode: -65537 Sep 07 16:37:14 volumio volumio[1102]: } Sep 07 16:37:14 volumio volumio[1102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 16:37:15 volumio sudo[6605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 16:36 Sep 07 16:37:15 volumio sudo[6605]: 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"