-- Logs begin at Mon 2024-07-22 19:22:29 CEST, end at Thu 2024-09-05 13:27:18 CEST. -- Sep 05 13:26:09 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:26:10 sala volumio[807]: info: Preload queue cleared Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i40 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i259 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i140 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i26 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i164 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i148 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i20 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i30 Sep 05 13:26:10 sala volumio[807]: info: Preloading song: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i114 Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i40 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i259 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i140 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i26 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i164 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i148 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i20 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i30 in service upnp_browser Sep 05 13:26:10 sala volumio[807]: info: Exploding uri upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i114 in service upnp_browser Sep 05 13:26:17 sala volumio[807]: info: Preload queue cleared Sep 05 13:26:17 sala volumio[807]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::ClearQueue Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::stop Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::clearPlayQueue Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::saveQueue Sep 05 13:26:17 sala volumio[807]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::addQueueItems Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::addQueueItems Sep 05 13:26:17 sala volumio[807]: info: Preload queue cleared Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i40 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i40 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i259 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i259 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i140 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i140 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i26 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i26 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i164 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i164 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i148 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i148 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i20 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i20 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i30 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i30 Sep 05 13:26:17 sala volumio[807]: info: Adding Item to queue: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i114 Sep 05 13:26:17 sala volumio[807]: info: Using cached record of: upnp/http://192.168.1.198:9791/dbcbe9d5-d719-47a6-b6f9-e1ff899d896c/upnp.org-ContentDirectory-1/control@0$=All Artists$8$*i114 Sep 05 13:26:17 sala volumio[807]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::saveQueue Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::updateTrackBlock Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::getTrackBlock Sep 05 13:26:17 sala volumio[807]: info: CoreCommandRouter::volumioPlay Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::play index 2 Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::stop Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::play index undefined Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::startPlaybackTimer Sep 05 13:26:17 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:17 sala volumio[807]: info: [1725535577528] ControllerUPNPBrowser::clearAddPlayTrack Sep 05 13:26:17 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 13:26:17 sala volumio[807]: info: sendMpdCommand stop took 67 milliseconds Sep 05 13:26:17 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 13:26:17 sala volumio[807]: info: Sep 05 13:26:17 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:17 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:17 sala volumio[807]: info: sendMpdCommand clear took 60 milliseconds Sep 05 13:26:17 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac" Sep 05 13:26:17 sala volumio[807]: info: Sep 05 13:26:17 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:17 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:17 sala volumio[807]: info: Sep 05 13:26:17 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:17 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:17 sala volumio[807]: error: updateQueue error: null Sep 05 13:26:17 sala volumio[807]: info: ------------------------------ 104ms Sep 05 13:26:17 sala volumio[807]: error: updateQueue error: null Sep 05 13:26:17 sala volumio[807]: error: updateQueue error: null Sep 05 13:26:17 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac" Sep 05 13:26:17 sala volumio[807]: info: ------------------------------ 107ms Sep 05 13:26:17 sala volumio[807]: info: ------------------------------ 102ms Sep 05 13:26:17 sala volumio[807]: info: Sep 05 13:26:17 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:17 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:17 sala volumio[807]: info: sendMpdCommand add "http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac" took 87 milliseconds Sep 05 13:26:17 sala volumio[807]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 13:26:17 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand play Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:18 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:18 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:18 sala volumio[807]: info: ------------------------------ 303ms Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand play took 275 milliseconds Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:18 sala volumio[807]: info: ------------------------------ 175ms Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:18 sala volumio[807]: info: ------------------------------ 172ms Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:18 sala volumio[807]: info: Sep 05 13:26:18 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand status took 488 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand status took 486 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand status took 161 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand status took 136 milliseconds Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand playlistinfo took 138 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand playlistinfo took 127 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand playlistinfo took 134 milliseconds Sep 05 13:26:18 sala volumio[807]: info: sendMpdCommand playlistinfo took 137 milliseconds Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:18 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:18 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:18 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:18 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","artist":null,"album":null,"uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:18 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:18 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:18 sala volumio[807]: info: CoreStateMachine::syncState currentStatus stop Sep 05 13:26:18 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:19 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:19 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","artist":null,"album":null,"uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:19 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:19 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:19 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:19 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:19 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","artist":null,"album":null,"uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:19 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:19 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:19 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:19 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:19 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","artist":null,"album":null,"uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:19 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:19 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:19 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:19 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:20 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:20 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:20 sala volumio[807]: info: ------------------------------ 2015ms Sep 05 13:26:21 sala volumio[807]: info: ------------------------------ 3295ms Sep 05 13:26:21 sala volumio[807]: info: ------------------------------ 3012ms Sep 05 13:26:21 sala volumio[807]: info: ------------------------------ 2895ms Sep 05 13:26:21 sala volumio[807]: info: Sep 05 13:26:21 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:21 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:21 sala volumio[807]: info: Sep 05 13:26:21 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:21 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:21 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:21 sala volumio[807]: info: Sep 05 13:26:21 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:21 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:21 sala volumio[807]: info: Sep 05 13:26:21 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:22 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:22 sala volumio[807]: info: Sep 05 13:26:22 sala volumio[807]: ---------------------------- MPD announces system playlist update Sep 05 13:26:22 sala volumio[807]: info: Ignoring MPD Status Update Sep 05 13:26:22 sala volumio[807]: info: Sep 05 13:26:22 sala volumio[807]: ---------------------------- MPD announces state update: player Sep 05 13:26:22 sala volumio[807]: info: ControllerMpd::getState Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:26:22 sala volumio[807]: info: ------------------------------ 849ms Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand status took 847 milliseconds Sep 05 13:26:22 sala volumio[807]: info: ------------------------------ 550ms Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand status took 476 milliseconds Sep 05 13:26:22 sala volumio[807]: info: ------------------------------ 471ms Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand status took 436 milliseconds Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseState Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand playlistinfo took 145 milliseconds Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand playlistinfo took 133 milliseconds Sep 05 13:26:22 sala volumio[807]: info: sendMpdCommand playlistinfo took 129 milliseconds Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:22 sala volumio[807]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:26:22 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:22 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:22 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3491,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1810 Kbps","isStreaming":false,"title":"Koko","artist":"Ryuichi Sakamoto","album":"Utau","uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:22 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:22 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:22 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:22 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:22 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3869,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1763 Kbps","isStreaming":false,"title":"Koko","artist":"Ryuichi Sakamoto","album":"Utau","uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:22 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:22 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:22 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:22 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:26:23 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:23 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:23 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:23 sala volumio[807]: info: ControllerMpd::pushState Sep 05 13:26:23 sala volumio[807]: info: CoreCommandRouter::servicePushState Sep 05 13:26:23 sala volumio[807]: info: CorePlayQueue::getTrack 2 Sep 05 13:26:23 sala volumio[807]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3869,"duration":258,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1763 Kbps","isStreaming":false,"title":"Koko","artist":"Ryuichi Sakamoto","album":"Utau","uri":"http://192.168.1.198:9790/minimserver/*/Audio/HD/Ryuichi*20Sakamoto/Utau*20(2010,*202024,*20Commmons)*20*5b24-96*5d/14*20-*20Ryuichi*20Sakamoto*20-*20Koko.flac","trackType":"flac"} Sep 05 13:26:23 sala volumio[807]: verbose: CURRENT POSITION 2 Sep 05 13:26:23 sala volumio[807]: info: CoreStateMachine::syncState stateService play Sep 05 13:26:23 sala volumio[807]: info: CoreStateMachine::syncState currentStatus play Sep 05 13:26:23 sala volumio[807]: info: Received an update from plugin. extracting info from payload Sep 05 13:26:23 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:23 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:23 sala volumio[807]: info: CoreStateMachine::pushState Sep 05 13:26:23 sala volumio[807]: info: CoreCommandRouter::volumioPushState Sep 05 13:26:24 sala volumio[807]: info: ------------------------------ 2415ms Sep 05 13:26:24 sala volumio[807]: info: ------------------------------ 2071ms Sep 05 13:26:24 sala volumio[807]: info: ------------------------------ 2028ms Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:24 sala volumio[807]: info: CoreCommandRouter::volumioGetQueue Sep 05 13:26:24 sala volumio[807]: info: CoreStateMachine::getQueue Sep 05 13:26:24 sala volumio[807]: info: CorePlayQueue::getQueue Sep 05 13:26:51 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:26:51 sala volumio[807]: info: Preload queue cleared Sep 05 13:27:07 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:27:08 sala volumio[807]: info: Preload queue cleared Sep 05 13:27:13 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:27:13 sala volumio[807]: info: Preload queue cleared Sep 05 13:27:14 sala volumio[807]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:27:15 sala volumio[807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 13:27:15 sala volumio[807]: TypeError: Cannot read property 'length' of undefined Sep 05 13:27:15 sala volumio[807]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 05 13:27:15 sala volumio[807]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 05 13:27:15 sala volumio[807]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 05 13:27:15 sala volumio[807]: at Parser.emit (events.js:315:20) Sep 05 13:27:15 sala volumio[807]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 05 13:27:15 sala volumio[807]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 05 13:27:15 sala volumio[807]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 05 13:27:15 sala volumio[807]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 05 13:27:15 sala volumio[807]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 05 13:27:15 sala volumio[807]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 05 13:27:15 sala volumio[807]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 05 13:27:15 sala volumio[807]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 05 13:27:15 sala volumio[807]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 05 13:27:15 sala volumio[807]: at IncomingMessage.emit (events.js:327:22) Sep 05 13:27:15 sala volumio[807]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 05 13:27:15 sala volumio[807]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 13:27:15 sala volumio[807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 13:27:18 sala sudo[18605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 13:26 Sep 05 13:27:18 sala sudo[18605]: 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"