-- Logs begin at Fri 2024-05-03 05:09:39 CEST, end at Tue 2024-05-07 20:05:31 CEST. -- May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 20:04:00 volumio-henk-audio volumio[3994]: info: Received Get System Version May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 20:04:00 volumio-henk-audio volumio[3994]: info: Received Get System Info May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 20:04:00 volumio-henk-audio volumio[3994]: info: Discovery: Getting this device information May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetState May 07 20:04:00 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::servicePause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: [1715105047139] ControllerUPNPBrowser::pause May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand pause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: May 07 20:04:07 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:07 volumio-henk-audio volumio[3994]: info: sendMpdCommand pause took 1 milliseconds May 07 20:04:07 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:07 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 1 milliseconds May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:07 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:07 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9417,"duration":358,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2842 Kbps","isStreaming":false,"title":"Dance On A Volcano","artist":"Genesis","album":"A Trick Of The Tail [2007 EU Virgin Records 0946 388057 2 4 SACD]","uri":"http://192.168.5.31:26125/content/c2/b24/f88200/d-6904853684317029512-co9E28F198AC3C1DDB.flac","trackType":"flac"} May 07 20:04:07 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService pause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus pause May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:07 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:07 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:04:07 volumio-henk-audio volumio[3994]: info: ------------------------------ 17ms May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:04:07 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:04:17 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:04:18 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:24 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1478467994235357357-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3576179786159103487-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-5984779124691154463-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3270155207060561612-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d680189314701456559-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1385951493640814392-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7878393030895524245-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2640444275882426033-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3531700430152085275-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-1604808593072139358-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1655246750043858708-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3582326523128740961-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d497731997236656430-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7599852775783093344-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d5218559793173870711-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6842213312579543364-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d9113983598044883852-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6521376947859903954-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4613053000383453056-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2798234997624945612-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-5456144375464679877-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8512998896723742479-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4005329667713471801-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3526446242782806027-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d320801641685766006-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4982710559247688353-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8870080022233129476-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7160191525533207616-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7832396640752137272-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8323741576732798441-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8309600376516844532-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8721122664365129699-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-870584330795018071-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-314721386776515553-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1408161252795734896-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6219378783292549410-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d729708289296962480-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-1906477467143868598-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d5785419047020805161-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d338656205376201754-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8427241547513255403-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3693426789739091627-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2989703532640753587-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d2736965642875017125-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8422640796520856927-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2163355622660876477-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d737203028928733875-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d6589855439425223470-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d2667535098889782891-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-257831597396551817-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3801772436737496050-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-4906705738251192952-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8705158210168884406-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8205138575002900164-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6270232281583536241-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d511661373070655150-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d524555442096491668-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d6742313592977560748-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d2605832346338587781-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-702522946378960938-coE80D8F09977CEE65 May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1478467994235357357-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3576179786159103487-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-5984779124691154463-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3270155207060561612-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d680189314701456559-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1385951493640814392-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7878393030895524245-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2640444275882426033-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3531700430152085275-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-1604808593072139358-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d1655246750043858708-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d3582326523128740961-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d497731997236656430-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7599852775783093344-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d5218559793173870711-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6842213312579543364-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d9113983598044883852-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-6521376947859903954-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:26 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4613053000383453056-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-2798234997624945612-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-5456144375464679877-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8512998896723742479-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4005329667713471801-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-3526446242782806027-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d320801641685766006-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d4982710559247688353-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8870080022233129476-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7160191525533207616-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-7832396640752137272-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8323741576732798441-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-8309600376516844532-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d8721122664365129699-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@d-870584330795018071-coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioReplaceandPlayItems May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::ClearQueue May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::updateTrackBlock May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrackBlock May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::serviceStop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::serviceStop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: [1715105067707] ControllerUPNPBrowser::stop May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand stop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::clearPlayQueue May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::saveQueue May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushQueue May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::addQueueItems May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::addQueueItems May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Adding Item to queue: upnp/folder/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@coE80D8F09977CEE65 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/folder/http://192.168.5.31:26125/ContentDirectory/2b9087d1-18fb-4eed-0-123456789abc/control.xml@coE80D8F09977CEE65 in service upnp_browser May 07 20:04:27 volumio-henk-audio volumio[3994]: info: May 07 20:04:27 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:27 volumio-henk-audio volumio[3994]: info: sendMpdCommand stop took 15 milliseconds May 07 20:04:27 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:27 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 4 milliseconds May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:27 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:27 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: 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":"Dance On A Volcano","artist":"Genesis","album":"A Trick Of The Tail [2007 EU Virgin Records 0946 388057 2 4 SACD]","uri":"http://192.168.5.31:26125/content/c2/b24/f88200/d-6904853684317029512-co9E28F198AC3C1DDB.flac","trackType":"flac"} May 07 20:04:27 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService stop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus stop May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:27 volumio-henk-audio volumio[3994]: info: No code May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:27 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:27 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:27 volumio-henk-audio volumio[3994]: info: ------------------------------ 39ms May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushQueue May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CorePlayQueue::saveQueue May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::updateTrackBlock May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrackBlock May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPlay May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::play index 0 May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stop May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::play index undefined May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CoreStateMachine::startPlaybackTimer May 07 20:04:30 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:30 volumio-henk-audio volumio[3994]: info: [1715105070992] ControllerUPNPBrowser::clearAddPlayTrack May 07 20:04:30 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand stop May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand stop took 24 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand clear May 07 20:04:31 volumio-henk-audio volumio[3994]: info: May 07 20:04:31 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces system playlist update May 07 20:04:31 volumio-henk-audio volumio[3994]: info: Ignoring MPD Status Update May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand clear took 5 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac" May 07 20:04:31 volumio-henk-audio volumio[3994]: error: updateQueue error: null May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ------------------------------ 229ms May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac" May 07 20:04:31 volumio-henk-audio volumio[3994]: info: May 07 20:04:31 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces system playlist update May 07 20:04:31 volumio-henk-audio volumio[3994]: info: Ignoring MPD Status Update May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand add "http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac" took 1 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService mpd May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand play May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ------------------------------ 2ms May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand play took 2 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: info: May 07 20:04:31 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:31 volumio-henk-audio volumio[3994]: info: May 07 20:04:31 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 2 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 1 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"d1478467994235357357-coE80D8F09977CEE65.flac","artist":null,"album":null,"uri":"http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac","trackType":"flac"} May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService play May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus stop May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ------------------------------ 6ms May 07 20:04:31 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":159,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"d1478467994235357357-coE80D8F09977CEE65.flac","artist":null,"album":null,"uri":"http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac","trackType":"flac"} May 07 20:04:31 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService play May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus play May 07 20:04:31 volumio-henk-audio volumio[3994]: info: Received an update from plugin. extracting info from payload May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:31 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:31 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:31 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:31 volumio-henk-audio volumio[3994]: info: ------------------------------ 17ms May 07 20:04:31 volumio-henk-audio volumiologrotate[492]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 07 20:04:31 volumio-henk-audio volumiologrotate[492]: ls: cannot access 'HENK-AUDIO': No such file or directory May 07 20:04:33 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:04:33 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:04:34 volumio-henk-audio volumio[3994]: info: May 07 20:04:34 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces system playlist update May 07 20:04:34 volumio-henk-audio volumio[3994]: info: Ignoring MPD Status Update May 07 20:04:34 volumio-henk-audio volumio[3994]: info: May 07 20:04:34 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:34 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:34 volumio-henk-audio volumio[3994]: info: ------------------------------ 3ms May 07 20:04:34 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 1 milliseconds May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:34 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:34 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":577,"duration":159,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4743 Kbps","isStreaming":false,"title":"Love Theme From ''The Godfather'' (The Godfather-Soundtrack Version)","artist":"Nino Rota","album":"The Godfather","uri":"http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac","trackType":"flac"} May 07 20:04:34 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService play May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus play May 07 20:04:34 volumio-henk-audio volumio[3994]: info: Received an update from plugin. extracting info from payload May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:34 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:34 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:34 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:34 volumio-henk-audio volumio[3994]: info: ------------------------------ 18ms May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:04:34 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:04:36 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:04:36 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:04:36 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:04:36 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:04:36 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:04:36 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::servicePause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: [1715105078424] ControllerUPNPBrowser::pause May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand pause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: May 07 20:04:38 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:04:38 volumio-henk-audio volumio[3994]: info: sendMpdCommand pause took 1 milliseconds May 07 20:04:38 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:04:38 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 1 milliseconds May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:04:38 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 1 milliseconds May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:04:38 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3260,"duration":159,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4924 Kbps","isStreaming":false,"title":"Love Theme From ''The Godfather'' (The Godfather-Soundtrack Version)","artist":"Nino Rota","album":"The Godfather","uri":"http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac","trackType":"flac"} May 07 20:04:38 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService pause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus pause May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:04:38 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:04:38 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:04:38 volumio-henk-audio volumio[3994]: info: ------------------------------ 14ms May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:04:38 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:04:40 volumio-henk-audio volumio[3994]: error: Failed request for metavolumio API May 07 20:04:43 volumio-henk-audio volumio[3994]: error: Failed request for metavolumio API May 07 20:04:48 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:04:49 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:51 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:04:52 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:04:53 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:04:54 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:05:09 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d3340976046628519034-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d8517710334273790407-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-1812559321753783844-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d7488005460920105223-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d982675207923719781-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-3857439894436252655-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d4153567175928687202-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-4879531830972694329-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d4223301109600884825-coA3FEAF1789CF7DA5 May 07 20:05:09 volumio-henk-audio volumio[3994]: info: Preloading song: upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-2874856352248728560-coA3FEAF1789CF7DA5 May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d3340976046628519034-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d8517710334273790407-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-1812559321753783844-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d7488005460920105223-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d982675207923719781-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-3857439894436252655-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Executing endpoint getSimilarAlbums May 07 20:05:10 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d4153567175928687202-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:05:10 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:05:10 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-4879531830972694329-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d4223301109600884825-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:10 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@d-2874856352248728560-coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:11 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioReplaceandPlayItems May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::ClearQueue May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::updateTrackBlock May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrackBlock May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stPlaybackTimer May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::serviceStop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::serviceStop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: [1715105111727] ControllerUPNPBrowser::stop May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand stop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::clearPlayQueue May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::saveQueue May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushQueue May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::addQueueItems May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::addQueueItems May 07 20:05:11 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:05:11 volumio-henk-audio volumio[3994]: info: Adding Item to queue: upnp/folder/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@coA3FEAF1789CF7DA5 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: Exploding uri upnp/folder/http://192.168.5.31:26127/ContentDirectory/2b9087d1-18fb-4eed-2-123456789abc/control.xml@coA3FEAF1789CF7DA5 in service upnp_browser May 07 20:05:11 volumio-henk-audio volumio[3994]: info: May 07 20:05:11 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:05:11 volumio-henk-audio volumio[3994]: info: sendMpdCommand stop took 18 milliseconds May 07 20:05:11 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:05:11 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 6 milliseconds May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:05:11 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 2 milliseconds May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:05:11 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: 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":"Love Theme From ''The Godfather'' (The Godfather-Soundtrack Version)","artist":"Nino Rota","album":"The Godfather","uri":"http://192.168.5.31:26125/content/c2/b24/f192000/d1478467994235357357-coE80D8F09977CEE65.flac","trackType":"flac"} May 07 20:05:11 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService stop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus stop May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:11 volumio-henk-audio volumio[3994]: info: No code May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:11 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:11 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:11 volumio-henk-audio volumio[3994]: info: ------------------------------ 51ms May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::saveQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::updateTrackBlock May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrackBlock May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPlay May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::play index 0 May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::stop May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::play index undefined May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService undefined May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::startPlaybackTimer May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:12 volumio-henk-audio volumio[3994]: info: [1715105112109] ControllerUPNPBrowser::clearAddPlayTrack May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand stop May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand stop took 1 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand clear May 07 20:05:12 volumio-henk-audio volumio[3994]: info: May 07 20:05:12 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces system playlist update May 07 20:05:12 volumio-henk-audio volumio[3994]: info: Ignoring MPD Status Update May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand clear took 3 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.5.31:26127/content/c2/b24/f44100/d3340976046628519034-coA3FEAF1789CF7DA5.flac" May 07 20:05:12 volumio-henk-audio volumio[3994]: error: updateQueue error: null May 07 20:05:12 volumio-henk-audio volumio[3994]: info: ------------------------------ 3ms May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.5.31:26127/content/c2/b24/f44100/d3340976046628519034-coA3FEAF1789CF7DA5.flac" May 07 20:05:12 volumio-henk-audio volumio[3994]: info: May 07 20:05:12 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces system playlist update May 07 20:05:12 volumio-henk-audio volumio[3994]: info: Ignoring MPD Status Update May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand add "http://192.168.5.31:26127/content/c2/b24/f44100/d3340976046628519034-coA3FEAF1789CF7DA5.flac" took 1 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::setConsumeUpdateService mpd May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand play May 07 20:05:12 volumio-henk-audio volumio[3994]: info: ------------------------------ 3ms May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand play took 2 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: info: May 07 20:05:12 volumio-henk-audio volumio[3994]: ---------------------------- MPD announces state update: player May 07 20:05:12 volumio-henk-audio volumio[3994]: info: ControllerMpd::getState May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand status May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand clearerror May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand status took 5 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseState May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand clearerror took 3 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: info: sendMpdCommand playlistinfo took 2 milliseconds May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: ControllerMpd::parseTrackInfo May 07 20:05:12 volumio-henk-audio volumio[3994]: info: ControllerMpd::pushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::servicePushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getTrack 0 May 07 20:05:12 volumio-henk-audio volumio[3994]: 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":"d3340976046628519034-coA3FEAF1789CF7DA5.flac","artist":null,"album":null,"uri":"http://192.168.5.31:26127/content/c2/b24/f44100/d3340976046628519034-coA3FEAF1789CF7DA5.flac","trackType":"flac"} May 07 20:05:12 volumio-henk-audio volumio[3994]: verbose: CURRENT POSITION 0 May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState stateService stop May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::syncState currentStatus stop May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:12 volumio-henk-audio volumio[3994]: info: No code May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::pushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioPushState May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output update for this device May 07 20:05:12 volumio-henk-audio volumio[3994]: info: MRS: Pushing multiroomSync output May 07 20:05:12 volumio-henk-audio volumio[3994]: info: ------------------------------ 34ms May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::volumioGetQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CoreStateMachine::getQueue May 07 20:05:12 volumio-henk-audio volumio[3994]: info: CorePlayQueue::getQueue May 07 20:05:14 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:05:14 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:05:14 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:05:14 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:05:14 volumio-henk-audio volumio[3994]: info: Executing endpoint metavolumio May 07 20:05:14 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 07 20:05:28 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:05:28 volumio-henk-audio volumio[3994]: info: Preload queue cleared May 07 20:05:30 volumio-henk-audio volumio[3994]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 20:05:30 volumio-henk-audio volumio[3994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 20:05:30 volumio-henk-audio volumio[3994]: TypeError: Cannot read property 'length' of undefined May 07 20:05:30 volumio-henk-audio volumio[3994]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 07 20:05:30 volumio-henk-audio volumio[3994]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 07 20:05:30 volumio-henk-audio volumio[3994]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 07 20:05:30 volumio-henk-audio volumio[3994]: at Parser.emit (events.js:400:28) May 07 20:05:30 volumio-henk-audio volumio[3994]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 07 20:05:30 volumio-henk-audio volumio[3994]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 07 20:05:30 volumio-henk-audio volumio[3994]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 07 20:05:30 volumio-henk-audio volumio[3994]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 07 20:05:30 volumio-henk-audio volumio[3994]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 07 20:05:30 volumio-henk-audio volumio[3994]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 07 20:05:30 volumio-henk-audio volumio[3994]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 07 20:05:30 volumio-henk-audio volumio[3994]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 07 20:05:30 volumio-henk-audio volumio[3994]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 07 20:05:30 volumio-henk-audio volumio[3994]: at IncomingMessage.emit (events.js:412:35) May 07 20:05:30 volumio-henk-audio volumio[3994]: at endReadableNT (internal/streams/readable.js:1333:12) May 07 20:05:30 volumio-henk-audio volumio[3994]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 07 20:05:30 volumio-henk-audio volumio[3994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 20:05:31 volumio-henk-audio sudo[3057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-07 20:04 May 07 20:05:31 volumio-henk-audio sudo[3057]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:28:35 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="be8aa514427e3c503467ece9bc4fd48d"