-- 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"