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