-- Logs begin at Sat 2024-07-13 14:30:52 CEST, end at Sat 2024-07-13 15:07:35 CEST. -- Jul 13 15:06:44 rivo nmbd[3060]: [2024/07/13 15:06:44.155900, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 13 15:06:44 rivo nmbd[3060]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.155 for name WORKGROUP<1d>. Jul 13 15:06:44 rivo nmbd[3060]: This response was from IP 192.168.178.30, reporting an IP address of 192.168.178.30. Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:01 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:01 rivo volumio[3113]: verbose: New Socket.io Connection to 192.168.178.155 from 192.168.178.205 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 13 15:07:01 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 13 15:07:04 rivo sudo[5444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 13 15:07:04 rivo sudo[5444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 15:07:04 rivo sudo[5444]: pam_unix(sudo:session): session closed for user root Jul 13 15:07:04 rivo sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 13 15:07:04 rivo sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 15:07:04 rivo sudo[5448]: pam_unix(sudo:session): session closed for user root Jul 13 15:07:05 rivo volumio[3113]: verbose: New Socket.io Connection to 192.168.178.155 from 192.168.178.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::volumioGetVisibleSources Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 13 15:07:05 rivo volumio[3113]: info: Received Get System Info Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:05 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:05 rivo volumio[3113]: info: Listing playlists Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 15:07:05 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::volumioGetQueue Jul 13 15:07:06 rivo volumio[3113]: info: CoreStateMachine::getQueue Jul 13 15:07:06 rivo volumio[3113]: info: CorePlayQueue::getQueue Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 15:07:06 rivo volumio[3113]: info: Received Get System Info Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:06 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:06 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:07 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 13 15:07:08 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 15:07:08 rivo volumio[3113]: info: Received Get System Info Jul 13 15:07:08 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:08 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:08 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:08 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:08 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:14 rivo volumio[3113]: Searching plugin music_service/qobuz Jul 13 15:07:14 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 13 15:07:15 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 13 15:07:15 rivo volumio[3113]: info: searchQOBUZUri took 1227 milliseconds Jul 13 15:07:15 rivo volumio[3113]: info: All search sources collected, pushing search results Jul 13 15:07:16 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:16 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:16 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:16 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:16 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:21 rivo volumio[3113]: info: Adding qobuz://album/w4kij9hlff69b to favourites with specific qobuz method Jul 13 15:07:22 rivo volumio[3113]: info: Preload queue cleared Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::ClearQueue Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::stPlaybackTimer Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::updateTrackBlock Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrackBlock Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::serviceStop Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::serviceStop Jul 13 15:07:22 rivo volumio[3113]: info: [1720876042449] ControllerQobuz::stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::stop Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::clearPlayQueue Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::saveQueue Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::addQueueItems Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::addQueueItems Jul 13 15:07:22 rivo volumio[3113]: info: Preload queue cleared Jul 13 15:07:22 rivo volumio[3113]: info: Adding Item to queue: qobuz://album/w4kij9hlff69b Jul 13 15:07:22 rivo volumio[3113]: info: Exploding uri qobuz://album/w4kij9hlff69b in service qobuz Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand stop took 48 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand status took 10 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand status took 9 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand status took 6 milliseconds Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: 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":"68348716","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/68348716","trackType":"qobuz"} Jul 13 15:07:22 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState stateService stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: No code Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: 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":"68348716","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/68348716","trackType":"qobuz"} Jul 13 15:07:22 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState stateService stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: No code Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: 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":"68348716","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/68348716","trackType":"qobuz"} Jul 13 15:07:22 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState stateService stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: No code Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:22 rivo volumio[3113]: info: ------------------------------ 100ms Jul 13 15:07:22 rivo volumio[3113]: info: ------------------------------ 100ms Jul 13 15:07:22 rivo volumio[3113]: info: ------------------------------ 99ms Jul 13 15:07:22 rivo volumio[3113]: info: explodeUri took 358 milliseconds Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPushQueue Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::saveQueue Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::updateTrackBlock Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrackBlock Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::volumioPlay Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::play index 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::stop Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::play index undefined Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: info: CoreStateMachine::startPlaybackTimer Jul 13 15:07:22 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:22 rivo volumio[3113]: info: [1720876042831] ControllerQobuz::clearAddPlayTrack Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand stop took 2 milliseconds Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:22 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:22 rivo volumio[3113]: info: sendMpdCommand clear took 5 milliseconds Jul 13 15:07:22 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/209958891" Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:22 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:22 rivo volumio[3113]: info: Jul 13 15:07:22 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:22 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:22 rivo volumio[3113]: STREAMING PROXY: Handling url /?data=qobuz://song/209958891 Jul 13 15:07:22 rivo volumio[3113]: error: updateQueue error: null Jul 13 15:07:22 rivo volumio[3113]: info: ------------------------------ 7ms Jul 13 15:07:22 rivo volumio[3113]: info: Executing endpoint getStreamUrlqobuz Jul 13 15:07:22 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 15:07:23 rivo volumio[3113]: info: getStreamUrl took 229 milliseconds Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2676332&eid=209958891&fmt=7&profile=raw&app_id=539451548&cid=1859196&etsp=1720879643&hmac=0yl4xEmyAloHxbzSDf9vFULigTs Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Response: 200, length: 54881304 Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Client dropped request, destroying Jul 13 15:07:23 rivo volumio[3113]: error: updateQueue error: null Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/209958891" Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 374ms Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 372ms Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:23 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/209958891" took 3 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand play Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:23 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces system playlist update Jul 13 15:07:23 rivo volumio[3113]: info: Ignoring MPD Status Update Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Handling url /?data=qobuz://song/209958891 Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 7ms Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand play took 5 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 4ms Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 3ms Jul 13 15:07:23 rivo volumio[3113]: info: Executing endpoint getStreamUrlqobuz Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 13 15:07:23 rivo volumio[3113]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 13 15:07:23 rivo volumio[3113]: info: Received Get System Version Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 15:07:23 rivo volumio[3113]: info: Received Get System Info Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 15:07:23 rivo volumio[3113]: info: Discovery: Getting this device information Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioGetState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 15:07:23 rivo volumio[3113]: info: getStreamUrl took 207 milliseconds Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2676332&eid=209958891&fmt=7&profile=raw&app_id=539451548&cid=1859196&etsp=1720879643&hmac=0yl4xEmyAloHxbzSDf9vFULigTs Jul 13 15:07:23 rivo volumio[3113]: STREAMING PROXY: Response: 200, length: 54881304 Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 74 milliseconds Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 77 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 76 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1206 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus stop Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 89ms Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: Jul 13 15:07:23 rivo volumio[3113]: ---------------------------- MPD announces state update: player Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::getState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 16 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 6 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand status took 5 milliseconds Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseState Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1206 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jul 13 15:07:23 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1206 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jul 13 15:07:23 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 152ms Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 153ms Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 58 milliseconds Jul 13 15:07:23 rivo volumio[3113]: info: sendMpdCommand playlistinfo took 58 milliseconds Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1206 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jul 13 15:07:23 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1250 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jul 13 15:07:23 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: ControllerMpd::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::servicePushState Jul 13 15:07:23 rivo volumio[3113]: info: CorePlayQueue::getTrack 0 Jul 13 15:07:23 rivo volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":287,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1250 Kbps","isStreaming":false,"title":"209958891","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209958891","trackType":"qobuz"} Jul 13 15:07:23 rivo volumio[3113]: verbose: CURRENT POSITION 0 Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState stateService play Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jul 13 15:07:23 rivo volumio[3113]: info: Received an update from plugin. extracting info from payload Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreStateMachine::pushState Jul 13 15:07:23 rivo volumio[3113]: info: CoreCommandRouter::volumioPushState Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 237ms Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 227ms Jul 13 15:07:23 rivo volumio[3113]: info: ------------------------------ 227ms Jul 13 15:07:34 rivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 13 15:07:34 rivo volumio[3113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 15:07:34 rivo volumio[3113]: TypeError: Cannot read property 'then' of undefined Jul 13 15:07:34 rivo volumio[3113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:491:20) Jul 13 15:07:34 rivo volumio[3113]: at Socket.emit (events.js:400:28) Jul 13 15:07:34 rivo volumio[3113]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 13 15:07:34 rivo volumio[3113]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 13 15:07:34 rivo volumio[3113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 15:07:35 rivo sudo[5464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 15:06 Jul 13 15:07:35 rivo sudo[5464]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:27:25 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="891b8adfc94c7cc44424972b60e8e09c"