-- Logs begin at Fri 2024-11-29 16:13:34 HKT, end at Fri 2024-11-29 16:22:41 HKT. -- Nov 29 16:21:09 volumio volumio[1084]: info: CoreCommandRouter::volumioPlay Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::play index 57 Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::stop Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::play index undefined Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:21:09 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:09 volumio volumio[1084]: info: CoreStateMachine::startPlaybackTimer Nov 29 16:21:09 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:09 volumio volumio[1084]: info: [1732868469905] ControllerUPNPBrowser::clearAddPlayTrack Nov 29 16:21:09 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 16:21:09 volumio volumio[1084]: info: sendMpdCommand stop took 2 milliseconds Nov 29 16:21:09 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 16:21:09 volumio volumio[1084]: info: Nov 29 16:21:09 volumio volumio[1084]: ---------------------------- MPD announces system playlist update Nov 29 16:21:09 volumio volumio[1084]: info: Ignoring MPD Status Update Nov 29 16:21:09 volumio volumio[1084]: info: sendMpdCommand clear took 2 milliseconds Nov 29 16:21:09 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.9:8200/MediaItems/24613.wav" Nov 29 16:21:09 volumio volumio[1084]: error: updateQueue error: null Nov 29 16:21:09 volumio volumio[1084]: info: ------------------------------ 4ms Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/24613.wav" Nov 29 16:21:14 volumio volumio[1084]: info: Nov 29 16:21:14 volumio volumio[1084]: ---------------------------- MPD announces system playlist update Nov 29 16:21:14 volumio volumio[1084]: info: Ignoring MPD Status Update Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/24613.wav" took 4 milliseconds Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand play Nov 29 16:21:14 volumio volumio[1084]: info: ------------------------------ 10ms Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand play took 8 milliseconds Nov 29 16:21:14 volumio volumio[1084]: info: Nov 29 16:21:14 volumio volumio[1084]: ---------------------------- MPD announces state update: player Nov 29 16:21:14 volumio volumio[1084]: info: ControllerMpd::getState Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Nov 29 16:21:14 volumio volumio[1084]: info: Nov 29 16:21:14 volumio volumio[1084]: ---------------------------- MPD announces state update: player Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand status took 465 milliseconds Nov 29 16:21:14 volumio volumio[1084]: info: ControllerMpd::getState Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::parseState Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand status took 4 milliseconds Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::parseState Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo Nov 29 16:21:14 volumio volumio[1084]: info: ControllerMpd::pushState Nov 29 16:21:14 volumio volumio[1084]: info: CoreCommandRouter::servicePushState Nov 29 16:21:14 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:14 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4126,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24613.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/24613.wav","trackType":"wav"} Nov 29 16:21:14 volumio volumio[1084]: verbose: CURRENT POSITION 57 Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus stop Nov 29 16:21:14 volumio volumio[1084]: info: ------------------------------ 482ms Nov 29 16:21:14 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 29 16:21:14 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo Nov 29 16:21:14 volumio volumio[1084]: info: ControllerMpd::pushState Nov 29 16:21:14 volumio volumio[1084]: info: CoreCommandRouter::servicePushState Nov 29 16:21:14 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:14 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4126,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24613.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/24613.wav","trackType":"wav"} Nov 29 16:21:14 volumio volumio[1084]: verbose: CURRENT POSITION 57 Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play Nov 29 16:21:14 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:14 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:21:14 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:14 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:14 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:14 volumio volumio[1084]: info: ------------------------------ 40ms Nov 29 16:21:20 volumio volumio[1084]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 16:21:20 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 16:21:20 volumio volumio[1084]: info: Listing playlists Nov 29 16:21:21 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 16:21:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 16:21:41 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 16:21:42 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:46 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 16:21:47 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:50 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 29 16:21:53 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F4 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D2 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F5 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F6 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F7 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F8 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F9 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D3 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FA Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D4 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FB Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D5 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FC Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D6 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FD Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D7 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FE Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D8 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FF Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D9 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$500 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DA Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DB Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DC Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DD Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DE Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DF Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4E0 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$14E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$50E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$14F Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$50F Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2D2 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$40E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$150 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$510 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$327 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C3 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$151 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$511 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$152 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$512 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$153 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$513 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$154 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$514 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$155 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$515 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$156 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$516 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$157 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$517 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$158 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$518 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2C6 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$474 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$22D Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$406 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$144 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6D Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6F Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$98 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2B Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2C Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$19B Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$60 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$86 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8B Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3A Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3B Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$21 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9A Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8F Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$23 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$185 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$15A Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$15B Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$177 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$178 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$184 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$33 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$34 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$24 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$55 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$47E Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$25 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$19A Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9C Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$92 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$66 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$67 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$95 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$61 Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$34C Nov 29 16:21:53 volumio volumio[1084]: info: Preloading song: upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$90 Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F4 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D2 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F5 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F6 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F7 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F8 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4F9 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D3 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FA in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D4 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FB in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D5 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FC in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D6 in service upnp_browser Nov 29 16:21:53 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FD in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D7 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FE in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D8 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4FF in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4D9 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$500 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DA in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DB in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DC in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DD in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DE in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4DF in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$4E0 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$14E in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$50E in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$14F in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$50F in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2D2 in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$40E in service upnp_browser Nov 29 16:21:54 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$150 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$510 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$327 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$C3 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$151 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$511 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$152 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$512 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$153 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$513 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$154 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$514 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$155 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$515 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$156 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$516 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$157 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$517 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$158 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$518 in service upnp_browser Nov 29 16:21:55 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2C6 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$474 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$22D in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$406 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$144 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6D in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6E in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$6F in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$98 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2B in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2C in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$19B in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$60 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$86 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8B in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3A in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3B in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8E in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$21 in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9A in service upnp_browser Nov 29 16:21:56 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$8F in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$1 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$23 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$2 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$185 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$15A in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$15B in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$177 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$178 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$184 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$33 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$34 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$24 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$55 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$47E in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$25 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$19A in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$9C in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$92 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$66 in service upnp_browser Nov 29 16:21:57 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$67 in service upnp_browser Nov 29 16:21:58 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$95 in service upnp_browser Nov 29 16:21:58 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$3 in service upnp_browser Nov 29 16:21:58 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$61 in service upnp_browser Nov 29 16:21:58 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$34C in service upnp_browser Nov 29 16:21:58 volumio volumio[1084]: info: Exploding uri upnp/http://192.168.1.9:8200/ctl/ContentDir@1$4$90 in service upnp_browser Nov 29 16:21:59 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::ClearQueue Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::stop Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::updateTrackBlock Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrackBlock Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::serviceStop Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::serviceStop Nov 29 16:21:59 volumio volumio[1084]: info: [1732868519096] ControllerUPNPBrowser::stop Nov 29 16:21:59 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::clearPlayQueue Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::saveQueue Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioPushQueue Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::addQueueItems Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::addQueueItems Nov 29 16:21:59 volumio volumio[1084]: info: Preload queue cleared Nov 29 16:21:59 volumio volumio[1084]: info: Adding Item to queue: upnp/folder/http://192.168.1.9:8200/ctl/ContentDir@1$4 Nov 29 16:21:59 volumio volumio[1084]: info: Exploding uri upnp/folder/http://192.168.1.9:8200/ctl/ContentDir@1$4 in service upnp_browser Nov 29 16:21:59 volumio volumio[1084]: info: Nov 29 16:21:59 volumio volumio[1084]: ---------------------------- MPD announces state update: player Nov 29 16:21:59 volumio volumio[1084]: info: sendMpdCommand stop took 14 milliseconds Nov 29 16:21:59 volumio volumio[1084]: info: ControllerMpd::getState Nov 29 16:21:59 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Nov 29 16:21:59 volumio volumio[1084]: info: sendMpdCommand status took 1 milliseconds Nov 29 16:21:59 volumio volumio[1084]: verbose: ControllerMpd::parseState Nov 29 16:21:59 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 16:21:59 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 16:21:59 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo Nov 29 16:21:59 volumio volumio[1084]: info: ControllerMpd::pushState Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::servicePushState Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: 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":"24613.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/24613.wav","trackType":"wav"} Nov 29 16:21:59 volumio volumio[1084]: verbose: CURRENT POSITION 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::syncState stateService stop Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus stop Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:59 volumio volumio[1084]: info: No code Nov 29 16:21:59 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:21:59 volumio volumio[1084]: info: CorePlayQueue::getTrack 57 Nov 29 16:21:59 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:21:59 volumio volumio[1084]: info: ------------------------------ 23ms Nov 29 16:21:59 volumio nmbd[934]: [2024/11/29 16:21:59.982910, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Nov 29 16:21:59 volumio nmbd[934]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Nov 29 16:22:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPushQueue Nov 29 16:22:00 volumio volumio[1084]: info: CorePlayQueue::saveQueue Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::updateTrackBlock Nov 29 16:22:00 volumio volumio[1084]: info: CorePlayQueue::getTrackBlock Nov 29 16:22:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPlay Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::play index 0 Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::stop Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::play index undefined Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 29 16:22:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0 Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::startPlaybackTimer Nov 29 16:22:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0 Nov 29 16:22:00 volumio volumio[1084]: info: [1732868520728] ControllerUPNPBrowser::clearAddPlayTrack Nov 29 16:22:00 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand stop Nov 29 16:22:00 volumio volumio[1084]: info: sendMpdCommand stop took 37 milliseconds Nov 29 16:22:00 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand clear Nov 29 16:22:00 volumio volumio[1084]: info: Nov 29 16:22:00 volumio volumio[1084]: ---------------------------- MPD announces system playlist update Nov 29 16:22:00 volumio volumio[1084]: info: Ignoring MPD Status Update Nov 29 16:22:00 volumio volumio[1084]: info: sendMpdCommand clear took 4 milliseconds Nov 29 16:22:00 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.9:8200/MediaItems/24555.wav" Nov 29 16:22:00 volumio volumio[1084]: error: updateQueue error: null Nov 29 16:22:00 volumio volumio[1084]: info: ------------------------------ 3ms Nov 29 16:22:00 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.9:8200/MediaItems/24555.wav" Nov 29 16:22:00 volumio volumio[1084]: info: Nov 29 16:22:00 volumio volumio[1084]: ---------------------------- MPD announces system playlist update Nov 29 16:22:00 volumio volumio[1084]: info: Ignoring MPD Status Update Nov 29 16:22:00 volumio volumio[1084]: info: sendMpdCommand add "http://192.168.1.9:8200/MediaItems/24555.wav" took 2 milliseconds Nov 29 16:22:00 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 29 16:22:00 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand play Nov 29 16:22:00 volumio volumio[1084]: info: ------------------------------ 3ms Nov 29 16:22:00 volumio volumio[1084]: info: sendMpdCommand play took 1 milliseconds Nov 29 16:22:01 volumio volumio[1084]: info: Nov 29 16:22:01 volumio volumio[1084]: ---------------------------- MPD announces state update: player Nov 29 16:22:01 volumio volumio[1084]: info: ControllerMpd::getState Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Nov 29 16:22:01 volumio volumio[1084]: info: Nov 29 16:22:01 volumio volumio[1084]: ---------------------------- MPD announces state update: player Nov 29 16:22:01 volumio volumio[1084]: info: sendMpdCommand status took 4 milliseconds Nov 29 16:22:01 volumio volumio[1084]: info: ControllerMpd::getState Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::parseState Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 16:22:01 volumio volumio[1084]: info: sendMpdCommand status took 3 milliseconds Nov 29 16:22:01 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::parseState Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo Nov 29 16:22:01 volumio volumio[1084]: info: ControllerMpd::pushState Nov 29 16:22:01 volumio volumio[1084]: info: CoreCommandRouter::servicePushState Nov 29 16:22:01 volumio volumio[1084]: info: CorePlayQueue::getTrack 0 Nov 29 16:22:01 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24555.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/24555.wav","trackType":"wav"} Nov 29 16:22:01 volumio volumio[1084]: verbose: CURRENT POSITION 0 Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus stop Nov 29 16:22:01 volumio volumio[1084]: info: ------------------------------ 13ms Nov 29 16:22:01 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 29 16:22:01 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo Nov 29 16:22:01 volumio volumio[1084]: info: ControllerMpd::pushState Nov 29 16:22:01 volumio volumio[1084]: info: CoreCommandRouter::servicePushState Nov 29 16:22:01 volumio volumio[1084]: info: CorePlayQueue::getTrack 0 Nov 29 16:22:01 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24555.wav","artist":null,"album":null,"uri":"http://192.168.1.9:8200/MediaItems/24555.wav","trackType":"wav"} Nov 29 16:22:01 volumio volumio[1084]: verbose: CURRENT POSITION 0 Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play Nov 29 16:22:01 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:22:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:22:01 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:22:01 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:22:01 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:22:01 volumio volumio[1084]: info: ------------------------------ 37ms Nov 29 16:22:17 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C2%AEe%C2%AF%C2%AA%C2%A8%C3%A0/22904195-9d8c-40db-b216-9d39c6745b4d.jpg' Nov 29 16:22:23 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/1db37f5b-7aec-4b58-931a-0ff1a9b02b9f.jpg' Nov 29 16:22:23 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C3%90%C3%AD%C3%88%C3%A3%C3%9C%C2%BF/c29908e8-ffea-4504-bd27-775995d4d19e.jpg' Nov 29 16:22:25 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ace%20Of%20Base/The%20Golden%20Ratio/02e94924-4c43-4bd9-97eb-a912891d74d9.png' Nov 29 16:22:26 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ace%20Of%20Base/The%20Golden%20Ratio/02e94924-4c43-4bd9-97eb-a912891d74d9.png' Nov 29 16:22:29 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%AD%E4%B9%A6%E7%91%B6/Honey/3a1b8909-2e52-481c-b1d5-6a7dce6ebcfa.jpg' Nov 29 16:22:30 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/F4/8b9fcb19-12db-401e-a916-7d973648078e.jpg' Nov 29 16:22:31 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lauren%20Alaina/Wildflower/c7c144b8-a769-4129-b8d2-232c589cddab.png' Nov 29 16:22:32 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nelly/08623745-69a7-4bf1-89d1-c2098483983e.jpg' Nov 29 16:22:32 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bruno%20Mars/42b8a940-9064-4eaf-b161-1ddf8574a27c.jpg' Nov 29 16:22:32 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AE%89%E4%B8%83%E7%82%AB/%E9%9D%99%E4%BA%AB%E4%B8%83%E4%B9%90/80a23e00-224d-4694-98b8-a2b7280cb58c.jpg' Nov 29 16:22:33 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C3%95%C3%85%C2%B9%C3%BA%C3%88%C3%99/bb29facc-c298-4e77-9ffd-aa212aabcaf5.jpg' Nov 29 16:22:33 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hayley%20Sales/When%20the%20Bird%20Became%20a%20Book/3de3c93b-c068-461a-aab6-8ec8e3621051.jpg' Nov 29 16:22:33 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chris%20Brown/3e43ddad-a017-497c-b43b-0cdc9be9d89e.jpg' Nov 29 16:22:33 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hayley%20Sales/When%20the%20Bird%20Became%20a%20Book/3de3c93b-c068-461a-aab6-8ec8e3621051.jpg' Nov 29 16:22:35 volumio volumio[1084]: info: CoreCommandRouter::volumioRandom Nov 29 16:22:35 volumio volumio[1084]: info: CoreCommandRouter::writePlayerControls Nov 29 16:22:35 volumio volumio[1084]: info: CoreStateMachine::setRepeat true single false Nov 29 16:22:35 volumio volumio[1084]: info: CoreStateMachine::pushState Nov 29 16:22:35 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 16:22:35 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState Nov 29 16:22:35 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C3%95%C3%85%C3%91%C2%A7%C3%93%C3%91/7bf32517-690e-4486-b0b3-62bf186570b1.jpg' Nov 29 16:22:36 volumio volumio[1084]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%C3%90%C3%AC%C3%90%C2%A1%C2%B7%C3%AF/2c847441-bb6e-4a25-b33f-422b63e1dacf.jpg' Nov 29 16:22:40 volumio volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 16:22:40 volumio volumio[1084]: Error: connect ECONNREFUSED 93.179.102.140:80 Nov 29 16:22:40 volumio volumio[1084]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 29 16:22:40 volumio volumio[1084]: errno: -111, Nov 29 16:22:40 volumio volumio[1084]: code: 'ECONNREFUSED', Nov 29 16:22:40 volumio volumio[1084]: syscall: 'connect', Nov 29 16:22:40 volumio volumio[1084]: address: '93.179.102.140', Nov 29 16:22:40 volumio volumio[1084]: port: 80 Nov 29 16:22:40 volumio volumio[1084]: } Nov 29 16:22:40 volumio volumio[1084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 16:22:41 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 16:21 Nov 29 16:22:41 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"