-- Logs begin at Mon 2024-07-22 20:55:52 MSK, end at Wed 2024-07-24 23:06:37 MSK. -- Jul 24 23:05:00 volumio volumio[845]: info: CALLMETHOD: music_service qobuz qobuzAccountLogin [object Object] Jul 24 23:05:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , qobuzAccountLogin Jul 24 23:05:00 volumio volumio[845]: info: [1721851500841] ControllerQobuz::login params: {"app_id":"","password":"23cc85e2e06620ed39d3d7606022139f","username":"Am.ost@yandex.ru"} Jul 24 23:05:01 volumio volumio[845]: info: [1721851501009] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15e6c-7f5ac42964f8e95167315ff3)","status":"error"} Jul 24 23:05:07 volumio volumio[845]: info: CALLMETHOD: music_service qobuz qobuzAccountLogin [object Object] Jul 24 23:05:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , qobuzAccountLogin Jul 24 23:05:07 volumio volumio[845]: info: [1721851507159] ControllerQobuz::login params: {"app_id":"","password":"23cc85e2e06620ed39d3d7606022139f","username":"Am.ost@yandex.ru"} Jul 24 23:05:07 volumio volumio[845]: info: [1721851507330] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15e73-77675ac2055fb37a1802b524)","status":"error"} Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioNext Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::next Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::stop Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::serviceStop Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::serviceStop Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::stop Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 24 23:05:11 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 3600 seconds. Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand stop took 146 milliseconds Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::play index undefined Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf Jul 24 23:05:11 volumio volumio[845]: info: Setting Volume to 100 automatically for DSD Jul 24 23:05:11 volumio volumio[845]: info: VolumeController::SetAlsaVolume100 Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Jul 24 23:05:11 volumio volumio[845]: info: Jul 24 23:05:11 volumio volumio[845]: ---------------------------- MPD announces state update: player Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::getState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand stop took 26 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Jul 24 23:05:11 volumio volumio[845]: info: Jul 24 23:05:11 volumio volumio[845]: ---------------------------- MPD announces system playlist update Jul 24 23:05:11 volumio volumio[845]: info: Ignoring MPD Status Update Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand status took 18 milliseconds Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand clear took 17 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf" Jul 24 23:05:11 volumio volumio[845]: error: updateQueue error: null Jul 24 23:05:11 volumio volumio[845]: info: Jul 24 23:05:11 volumio volumio[845]: ---------------------------- MPD announces system playlist update Jul 24 23:05:11 volumio volumio[845]: info: Ignoring MPD Status Update Jul 24 23:05:11 volumio volumio[845]: info: ------------------------------ 10ms Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand add "USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf" took 5 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play Jul 24 23:05:11 volumio volumio[845]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 24 23:05:11 volumio volumio[845]: info: ------------------------------ 32ms Jul 24 23:05:11 volumio volumio[845]: info: ------------------------------ 8ms Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand play took 5 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Jul 24 23:05:11 volumio volumio[845]: info: Jul 24 23:05:11 volumio volumio[845]: ---------------------------- MPD announces state update: player Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::getState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 24 23:05:11 volumio volumio[845]: info: Jul 24 23:05:11 volumio volumio[845]: ---------------------------- MPD announces state update: player Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand status took 13 milliseconds Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::getState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand status took 9 milliseconds Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseState Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::pushState Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::servicePushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"In Visible Light","artist":"Lacuna Coil","album":"Karmacode (Century Media - 77660-1, Germany)","uri":"USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf","trackType":"dsf"} Jul 24 23:05:11 volumio volumio[845]: verbose: CURRENT POSITION 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jul 24 23:05:11 volumio volumio[845]: info: ------------------------------ 31ms Jul 24 23:05:11 volumio volumio[845]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 24 23:05:11 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 24 23:05:11 volumio volumio[845]: info: ControllerMpd::pushState Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::servicePushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97,"duration":240,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"In Visible Light","artist":"Lacuna Coil","album":"Karmacode (Century Media - 77660-1, Germany)","uri":"USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf","trackType":"dsf"} Jul 24 23:05:11 volumio volumio[845]: verbose: CURRENT POSITION 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Jul 24 23:05:11 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:11 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:11 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:11 volumio volumio[845]: info: ------------------------------ 63ms Jul 24 23:05:11 volumio volumio[845]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player Jul 24 23:05:11 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 Jul 24 23:05:11 volumio volumio[845]: info: touch_display: Setting screensaver timeout to 0 seconds. Jul 24 23:05:12 volumio volumio[845]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Jul 24 23:05:12 volumio volumio[845]: info: CoreCommandRouter::volumioGetState Jul 24 23:05:12 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:13 volumio volumio[845]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Jul 24 23:05:13 volumio volumio[845]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=13&CI=0&RID=rpc&SID=6A919B79DEDE2DCD&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=jEKlZ-nrihIwYQDbvh9EAcaB2wnHcK_M&id=2290e133-566b-4d2a-8532-b3d3ee689679&loungeIdToken=AGdO5p9BQ-7PHd1aSCfmV3A1gRhXu-ZlxbiyjdhrU3cvl0TDinbB5EJ1XknjEVivnCm3Eg5Gf_GvF8AgBCjcw3vGmkaE4C1l_FzZzGrfb_NFWtAfkztkp60&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=7361683a242f Jul 24 23:05:13 volumio volumio[845]: verbose: [yt-cast-receiver] RPC connection established. Jul 24 23:05:13 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:05:13 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 15) (YouTube Music) Incoming message: 'noop' Jul 24 23:05:13 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 15) (YouTube Music) Not handled: 'noop' Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 24 23:05:14 volumio volumio[845]: info: Jul 24 23:05:14 volumio volumio[845]: ---------------------------- MPD announces state update: update Jul 24 23:05:14 volumio volumio[845]: info: ControllerMpd::getState Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jul 24 23:05:14 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds Jul 24 23:05:14 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::parseState Jul 24 23:05:14 volumio volumio[845]: info: Command Router : Notfying DB Updatefalse Jul 24 23:05:14 volumio volumio[845]: info: CoreCommandRouter::Close All Modals sent Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::parseState Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 24 23:05:14 volumio volumio[845]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 24 23:05:14 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo Jul 24 23:05:14 volumio volumio[845]: info: ControllerMpd::pushState Jul 24 23:05:14 volumio volumio[845]: info: CoreCommandRouter::servicePushState Jul 24 23:05:14 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:14 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3068,"duration":240,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"In Visible Light","artist":"Lacuna Coil","album":"Karmacode (Century Media - 77660-1, Germany)","uri":"USB/Seagate_Expansion_Drive/МУЗЫКА/David Coverdale - 1977 - Whitesnake (Japan Edition)/Дорожка 10.dsf","trackType":"dsf"} Jul 24 23:05:14 volumio volumio[845]: verbose: CURRENT POSITION 9 Jul 24 23:05:14 volumio volumio[845]: info: CoreStateMachine::syncState stateService play Jul 24 23:05:14 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play Jul 24 23:05:14 volumio volumio[845]: info: Received an update from plugin. extracting info from payload Jul 24 23:05:14 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:14 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:14 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 23:05:14 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:14 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:05:14 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:05:14 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:05:14 volumio volumio[845]: info: ------------------------------ 632ms Jul 24 23:05:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 24 23:05:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 24 23:05:28 volumio volumio[845]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Jul 24 23:05:28 volumio volumio[845]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=13&CI=0&RID=rpc&SID=5F602717A298717&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=lrRIYZLySM54-14vStaeHwTotZSoWpvF&id=279ec512-ac89-43ae-95f4-9d4fac0114e3&loungeIdToken=AGdO5p8-Kd6YV2kV7S4LkkxfEy80_xyCo2TZm8P-mwxTLgD9-ul2BlVTOY6y0ukfR3LYijpmJII5nZ2ukDw3z7de9SqB8K6C4ndo5ROQbiZUlpgko2QsSDM&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=fe5b96ae6e2b Jul 24 23:05:28 volumio volumio[845]: verbose: [yt-cast-receiver] RPC connection established. Jul 24 23:05:28 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:05:28 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 15) (YouTube) Incoming message: 'noop' Jul 24 23:05:28 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 15) (YouTube) Not handled: 'noop' Jul 24 23:05:41 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:05:41 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 16) (YouTube Music) Incoming message: 'noop' Jul 24 23:05:41 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 16) (YouTube Music) Not handled: 'noop' Jul 24 23:05:41 volumio volumio[845]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Jul 24 23:05:49 volumio volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 23:05:58 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:05:58 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 16) (YouTube) Incoming message: 'noop' Jul 24 23:05:58 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 16) (YouTube) Not handled: 'noop' Jul 24 23:06:05 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:06:05 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 17) (YouTube Music) Incoming message: 'noop' Jul 24 23:06:05 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 17) (YouTube Music) Not handled: 'noop' Jul 24 23:06:05 volumio volumio[845]: info: CALLMETHOD: music_service qobuz saveQobuzCacheSettings [object Object] Jul 24 23:06:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , saveQobuzCacheSettings Jul 24 23:06:05 volumio volumio[845]: info: [1721851565807] ControllerQobuz::saveQobuzCacheSettings json:{"cache":{"enabled":true,"favourites":360,"items":720,"editorial":720,"pruneInterval":60},"sort":{"albums":"artistTitle","playlists":"","tracks":"artistTitle"},"display":{"showQobuzListsInRoot":true}} Jul 24 23:06:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:20 volumio volumio[845]: info: [1721851580275] ControllerQobuz::handleBrowseUri Jul 24 23:06:20 volumio volumio[845]: info: [1721851580275] ControllerQobuz::handleBrowseUri: "qobuz" Jul 24 23:06:20 volumio volumio[845]: info: Preload queue cleared Jul 24 23:06:21 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:21 volumio volumio[845]: info: [1721851581670] ControllerQobuz::handleBrowseUri Jul 24 23:06:21 volumio volumio[845]: info: [1721851581670] ControllerQobuz::handleBrowseUri: "qobuz/favourites/albums" Jul 24 23:06:21 volumio volumio[845]: info: [1721851581671] ControllerQobuz::cache check Jul 24 23:06:21 volumio volumio[845]: info: [1721851581674] ControllerQobuz::qobuz/favourites/albums: not in cache Jul 24 23:06:21 volumio volumio[845]: info: [1721851581675] ControllerQobuz::QobuzApi call method: favorite/getUserFavorites; params: {"type":"albums"} Jul 24 23:06:21 volumio volumio[845]: info: [1721851581938] makeQobuzRequest failed response: {"status":"error","message":"An unexpected error occurred (Root=1-66a15ebd-55f10cad6146499e6d882d21)","code":500} Jul 24 23:06:21 volumio volumio[845]: info: qobuz/favourites/albums: fail cache error Jul 24 23:06:21 volumio volumio[845]: info: [1721851581941] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:23 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:23 volumio volumio[845]: info: [1721851583396] ControllerQobuz::handleBrowseUri Jul 24 23:06:23 volumio volumio[845]: info: [1721851583396] ControllerQobuz::handleBrowseUri: "qobuz/favourites/tracks" Jul 24 23:06:23 volumio volumio[845]: info: [1721851583397] ControllerQobuz::cache check Jul 24 23:06:23 volumio volumio[845]: info: [1721851583398] ControllerQobuz::qobuz/favourites/tracks: not in cache Jul 24 23:06:23 volumio volumio[845]: info: [1721851583398] ControllerQobuz::QobuzApi call method: favorite/getUserFavorites; params: {"type":"tracks"} Jul 24 23:06:23 volumio volumio[845]: info: [1721851583563] makeQobuzRequest failed response: {"code":500,"status":"error","message":"An unexpected error occurred (Root=1-66a15ebf-3f90bb1b7e540a6358062303)"} Jul 24 23:06:23 volumio volumio[845]: info: qobuz/favourites/tracks: fail cache error Jul 24 23:06:23 volumio volumio[845]: info: [1721851583564] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:24 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:24 volumio volumio[845]: info: [1721851584359] ControllerQobuz::handleBrowseUri Jul 24 23:06:24 volumio volumio[845]: info: [1721851584359] ControllerQobuz::handleBrowseUri: "qobuz/favourites/playlists" Jul 24 23:06:24 volumio volumio[845]: info: [1721851584360] ControllerQobuz::cache check Jul 24 23:06:24 volumio volumio[845]: info: [1721851584360] ControllerQobuz::qobuz/favourites/playlists: not in cache Jul 24 23:06:24 volumio volumio[845]: info: [1721851584361] ControllerQobuz::QobuzApi call method: playlist/getUserPlaylists; params: {} Jul 24 23:06:24 volumio volumio[845]: info: [1721851584541] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15ec0-0a0f82d8386015412c96c5bd)","status":"error"} Jul 24 23:06:24 volumio volumio[845]: info: qobuz/favourites/playlists: fail cache error Jul 24 23:06:24 volumio volumio[845]: info: [1721851584543] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:24 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:06:24 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 17) (YouTube) Incoming message: 'noop' Jul 24 23:06:24 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 17) (YouTube) Not handled: 'noop' Jul 24 23:06:26 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:26 volumio volumio[845]: info: [1721851586726] ControllerQobuz::handleBrowseUri Jul 24 23:06:26 volumio volumio[845]: info: [1721851586726] ControllerQobuz::handleBrowseUri: "qobuz/genres" Jul 24 23:06:26 volumio volumio[845]: info: [1721851586726] ControllerQobuz::cache check Jul 24 23:06:26 volumio volumio[845]: info: [1721851586727] ControllerQobuz::qobuz/genre: not in cache Jul 24 23:06:26 volumio volumio[845]: info: [1721851586727] ControllerQobuz::QobuzApi call method: genre/list; params: {} Jul 24 23:06:26 volumio volumio[845]: info: [1721851586903] makeQobuzRequest failed response: {"status":"error","message":"An unexpected error occurred (Root=1-66a15ec2-7a26651b32c849b8537cbcd7)","code":500} Jul 24 23:06:26 volumio volumio[845]: info: qobuz/genre: fail cache error Jul 24 23:06:26 volumio volumio[845]: info: [1721851586903] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:27 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:27 volumio volumio[845]: info: [1721851587719] ControllerQobuz::handleBrowseUri Jul 24 23:06:27 volumio volumio[845]: info: [1721851587719] ControllerQobuz::handleBrowseUri: "qobuz/editor/playlists" Jul 24 23:06:27 volumio volumio[845]: info: [1721851587719] ControllerQobuz::cache check Jul 24 23:06:27 volumio volumio[845]: info: [1721851587720] ControllerQobuz::qobuz/editor/playlists/: not in cache Jul 24 23:06:27 volumio volumio[845]: info: [1721851587720] ControllerQobuz::QobuzApi call method: playlist/getFeatured; params: {"type":"editor-picks"} Jul 24 23:06:27 volumio volumio[845]: info: [1721851587888] makeQobuzRequest failed response: {"code":500,"status":"error","message":"An unexpected error occurred (Root=1-66a15ec3-0c1440ce3095be03228f7bfc)"} Jul 24 23:06:27 volumio volumio[845]: info: qobuz/editor/playlists/: fail cache error Jul 24 23:06:27 volumio volumio[845]: info: [1721851587889] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:29 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:29 volumio volumio[845]: info: [1721851589495] ControllerQobuz::handleBrowseUri Jul 24 23:06:29 volumio volumio[845]: info: [1721851589495] ControllerQobuz::handleBrowseUri: "qobuz/bestsellers/albums" Jul 24 23:06:29 volumio volumio[845]: info: [1721851589495] ControllerQobuz::cache check Jul 24 23:06:29 volumio volumio[845]: info: [1721851589496] ControllerQobuz::qobuz/bestsellers/albums/: not in cache Jul 24 23:06:29 volumio volumio[845]: info: [1721851589496] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"best-sellers"} Jul 24 23:06:29 volumio volumio[845]: info: [1721851589662] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15ec5-01d112194669499c1be0d639)","status":"error"} Jul 24 23:06:29 volumio volumio[845]: info: qobuz/bestsellers/albums/: fail cache error Jul 24 23:06:29 volumio volumio[845]: info: [1721851589663] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:30 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:30 volumio volumio[845]: info: [1721851590281] ControllerQobuz::handleBrowseUri Jul 24 23:06:30 volumio volumio[845]: info: [1721851590281] ControllerQobuz::handleBrowseUri: "qobuz/moststreamed/albums" Jul 24 23:06:30 volumio volumio[845]: info: [1721851590281] ControllerQobuz::cache check Jul 24 23:06:30 volumio volumio[845]: info: [1721851590282] ControllerQobuz::qobuz/moststreamed/albums/: not in cache Jul 24 23:06:30 volumio volumio[845]: info: [1721851590282] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"most-streamed"} Jul 24 23:06:30 volumio volumio[845]: info: [1721851590450] makeQobuzRequest failed response: {"status":"error","message":"An unexpected error occurred (Root=1-66a15ec6-7b97032c6c7f140d1885c29f)","code":500} Jul 24 23:06:30 volumio volumio[845]: info: qobuz/moststreamed/albums/: fail cache error Jul 24 23:06:30 volumio volumio[845]: info: [1721851590451] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:31 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:31 volumio volumio[845]: info: [1721851591015] ControllerQobuz::handleBrowseUri Jul 24 23:06:31 volumio volumio[845]: info: [1721851591015] ControllerQobuz::handleBrowseUri: "qobuz/press/albums" Jul 24 23:06:31 volumio volumio[845]: info: [1721851591015] ControllerQobuz::cache check Jul 24 23:06:31 volumio volumio[845]: info: [1721851591016] ControllerQobuz::qobuz/press/albums/: not in cache Jul 24 23:06:31 volumio volumio[845]: info: [1721851591016] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"press-awards"} Jul 24 23:06:31 volumio volumio[845]: info: [1721851591193] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15ec7-48fba3d5257f525b66e469ac)","status":"error"} Jul 24 23:06:31 volumio volumio[845]: info: qobuz/press/albums/: fail cache error Jul 24 23:06:31 volumio volumio[845]: info: [1721851591194] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:32 volumio volumio[845]: verbose: ----------------------------------- Jul 24 23:06:32 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 18) (YouTube Music) Incoming message: 'noop' Jul 24 23:06:32 volumio volumio[845]: verbose: [yt-cast-receiver] (AID: 18) (YouTube Music) Not handled: 'noop' Jul 24 23:06:32 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:32 volumio volumio[845]: info: [1721851592432] ControllerQobuz::handleBrowseUri Jul 24 23:06:32 volumio volumio[845]: info: [1721851592432] ControllerQobuz::handleBrowseUri: "qobuz/editor/albums" Jul 24 23:06:32 volumio volumio[845]: info: [1721851592432] ControllerQobuz::cache check Jul 24 23:06:32 volumio volumio[845]: info: [1721851592432] ControllerQobuz::qobuz/editor/albums/: not in cache Jul 24 23:06:32 volumio volumio[845]: info: [1721851592433] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"editor-picks"} Jul 24 23:06:32 volumio volumio[845]: info: [1721851592607] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15ec8-7f0c3e8d079116471e595c32)","status":"error"} Jul 24 23:06:32 volumio volumio[845]: info: qobuz/editor/albums/: fail cache error Jul 24 23:06:32 volumio volumio[845]: info: [1721851592608] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:33 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:33 volumio volumio[845]: info: [1721851593276] ControllerQobuz::handleBrowseUri Jul 24 23:06:33 volumio volumio[845]: info: [1721851593276] ControllerQobuz::handleBrowseUri: "qobuz/editor/albums" Jul 24 23:06:33 volumio volumio[845]: info: [1721851593276] ControllerQobuz::cache check Jul 24 23:06:33 volumio volumio[845]: info: [1721851593277] ControllerQobuz::qobuz/editor/albums/: not in cache Jul 24 23:06:33 volumio volumio[845]: info: [1721851593277] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"editor-picks"} Jul 24 23:06:33 volumio volumio[845]: info: [1721851593456] makeQobuzRequest failed response: {"status":"error","message":"An unexpected error occurred (Root=1-66a15ec9-713b62330ee1bac6540d1472)","code":500} Jul 24 23:06:33 volumio volumio[845]: info: qobuz/editor/albums/: fail cache error Jul 24 23:06:33 volumio volumio[845]: info: [1721851593457] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:33 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 24 23:06:33 volumio volumio[845]: info: [1721851593711] ControllerQobuz::handleBrowseUri Jul 24 23:06:33 volumio volumio[845]: info: [1721851593711] ControllerQobuz::handleBrowseUri: "qobuz/mostfeatured/albums" Jul 24 23:06:33 volumio volumio[845]: info: [1721851593711] ControllerQobuz::cache check Jul 24 23:06:33 volumio volumio[845]: info: [1721851593712] ControllerQobuz::qobuz/mostfeatured/albums/: not in cache Jul 24 23:06:33 volumio volumio[845]: info: [1721851593712] ControllerQobuz::QobuzApi call method: album/getFeatured; params: {"type":"most-featured"} Jul 24 23:06:33 volumio volumio[845]: info: [1721851593872] makeQobuzRequest failed response: {"code":500,"message":"An unexpected error occurred (Root=1-66a15ec9-5de6ec2356525991066e47ca)","status":"error"} Jul 24 23:06:33 volumio volumio[845]: info: qobuz/mostfeatured/albums/: fail cache error Jul 24 23:06:33 volumio volumio[845]: info: [1721851593873] ControllerQobuz::handleBrowseUri failed Jul 24 23:06:37 volumio volumio[845]: info: Preload queue cleared Jul 24 23:06:37 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::ClearQueue Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::stop Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::getTrackBlock Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::pushState Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:06:37 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 23:06:37 volumio volumio[845]: info: CoreCommandRouter::volumioPushState Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::serviceStop Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::getTrack 9 Jul 24 23:06:37 volumio volumio[845]: info: CoreCommandRouter::serviceStop Jul 24 23:06:37 volumio volumio[845]: info: ControllerMpd::stop Jul 24 23:06:37 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::saveQueue Jul 24 23:06:37 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue Jul 24 23:06:37 volumio volumio[845]: info: CoreStateMachine::addQueueItems Jul 24 23:06:37 volumio volumio[845]: info: CorePlayQueue::addQueueItems Jul 24 23:06:37 volumio volumio[845]: info: Preload queue cleared Jul 24 23:06:37 volumio volumio[845]: info: Adding Item to queue: qobuz/purchases Jul 24 23:06:37 volumio volumio[845]: info: Exploding uri qobuz/purchases in service qobuz Jul 24 23:06:37 volumio volumio[845]: info: [1721851597059] ControllerQobuz::explodeUri start uri: qobuz/purchases Jul 24 23:06:37 volumio volumio[845]: info: [1721851597059] ControllerQobuz::explodeUri no uri pattern matched Jul 24 23:06:37 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 23:06:37 volumio volumio[845]: TypeError: exploder is not a function Jul 24 23:06:37 volumio volumio[845]: at ControllerQobuz.explodeUri (/data/plugins/music_service/qobuz/index.js:492:12) Jul 24 23:06:37 volumio volumio[845]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1358:16) Jul 24 23:06:37 volumio volumio[845]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jul 24 23:06:37 volumio volumio[845]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jul 24 23:06:37 volumio volumio[845]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Jul 24 23:06:37 volumio volumio[845]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Jul 24 23:06:37 volumio volumio[845]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:506:23) Jul 24 23:06:37 volumio volumio[845]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:476:17) Jul 24 23:06:37 volumio volumio[845]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Jul 24 23:06:37 volumio volumio[845]: at Socket.emit (events.js:315:20) Jul 24 23:06:37 volumio volumio[845]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 24 23:06:37 volumio volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 24 23:06:37 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 23:06:37 volumio sudo[2546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 23:05 Jul 24 23:06:37 volumio sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"