-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-12-23 00:18:53 CET. -- Dec 23 00:17:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:17:05+01:00" level=trace msg="sent dealer ping" Dec 23 00:17:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:17:05+01:00" level=trace msg="received dealer pong" Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioSeek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: TRACKBLOCK {"album":"On Every Street","albumUri":"tidal://album/2570506","albumart":"https://resources.tidal.com/images/0d5db54c/f5be/45c2/9188/affb43aeeb15/640x640.jpg","artist":"Dire Straits","artistUri":"tidal://artist/29893","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":357,"explicit":false,"name":"You And Your Friend","samplerate":"44.1 KHz","service":"tidal","title":"You And Your Friend","trackType":"tidal","tracknumber":6,"type":"track","uri":"tidal://song/2570512","volumeNumber":1} Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: [1766445428017] ControllerTidal::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: error: null Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: Dec 23 00:17:08 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 2 milliseconds Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3212,"duration":357,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"534 Kbps","isStreaming":false,"title":"2570512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/2570512&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ------------------------------ 29ms Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Connected to cast device: 4K-SMART-TV-fed4500adf8e0a706d2d916f52d94f22 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: Refreshing Cached IP Addresses Dec 23 00:17:08 volumio-dac2pro sudo[23403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 00:17:08 volumio-dac2pro sudo[23403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 00:17:08 volumio-dac2pro sudo[23403]: pam_unix(sudo:session): session closed for user root Dec 23 00:17:08 volumio-dac2pro sudo[23405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 00:17:08 volumio-dac2pro sudo[23405]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 00:17:08 volumio-dac2pro sudo[23405]: pam_unix(sudo:session): session closed for user root Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: IDLE Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: IDLE Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: cast media loaded Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioSeek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: TRACKBLOCK {"album":"On Every Street","albumUri":"tidal://album/2570506","albumart":"https://resources.tidal.com/images/0d5db54c/f5be/45c2/9188/affb43aeeb15/640x640.jpg","artist":"Dire Straits","artistUri":"tidal://artist/29893","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":357,"explicit":false,"name":"You And Your Friend","samplerate":"44.1 KHz","service":"tidal","title":"You And Your Friend","trackType":"tidal","tracknumber":6,"type":"track","uri":"tidal://song/2570512","volumeNumber":1} Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: [1766445428741] ControllerTidal::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::seek Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: error: null Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: Dec 23 00:17:08 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 10 milliseconds Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8960,"duration":357,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"534 Kbps","isStreaming":false,"title":"2570512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/2570512&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:17:08 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 7 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: ------------------------------ 25ms Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:08 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:09 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:09 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:10 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:10 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:17:11 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:17:11 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPrevious Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::previous Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: [1766445434218] ControllerTidal::seek Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: ControllerMpd::seek Dec 23 00:17:14 volumio-dac2pro volumio[869]: error: null Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: Dec 23 00:17:14 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 2 milliseconds Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":357,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"538 Kbps","isStreaming":false,"title":"2570512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/2570512&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:17:14 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 7 Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:17:14 volumio-dac2pro volumio[869]: info: ------------------------------ 32ms Dec 23 00:17:14 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:14 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:17:32 volumio-dac2pro ntpd[840]: Soliciting pool server 2001:ac8:7d:42:0:2:0:10 Dec 23 00:17:35 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:17:35+01:00" level=trace msg="sent dealer ping" Dec 23 00:17:35 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:17:35+01:00" level=trace msg="received dealer pong" Dec 23 00:18:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:05+01:00" level=trace msg="sent dealer ping" Dec 23 00:18:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:05+01:00" level=trace msg="received dealer pong" Dec 23 00:18:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:05+01:00" level=trace msg="received accesspoint ping" Dec 23 00:18:05 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:05+01:00" level=trace msg="received accesspoint pong ack" Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Preload queue cleared Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::ClearQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::updateTrackBlock Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrackBlock Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::serviceStop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::serviceStop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: [1766445491237] ControllerTidal::stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: ControllerMpd::stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::clearPlayQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::saveQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::addQueueItems Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::addQueueItems Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Preload queue cleared Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/74944825 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Exploding uri tidal://song/74944825 in service tidal Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/205362155 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/205362155 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/268019983 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/268019983 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/332836942 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/332836942 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/94302672 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/94302672 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/453982152 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/453982152 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/339590479 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/339590479 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/109636786 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/109636786 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/2570512 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/2570512 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/72697169 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/72697169 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/6244864 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/6244864 Dec 23 00:18:11 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: Dec 23 00:18:11 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: sendMpdCommand stop took 19 milliseconds Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 0 milliseconds Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 7 Dec 23 00:18:11 volumio-dac2pro volumio[869]: 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":"2570512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/2570512&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:11 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 7 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: No code Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: ------------------------------ 24ms Dec 23 00:18:11 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:11 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:11 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: explodeTIDALUri took 298 milliseconds Dec 23 00:18:11 volumio-dac2pro volumio[869]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [74944825] not found"} Dec 23 00:18:11 volumio-dac2pro volumio[869]: error: Commandrouter: Cannot explode uri tidal://song/74944825 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [74944825] not found"} Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::saveQueue Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::updateTrackBlock Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrackBlock Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPlay Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::play index 10 Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::stop Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::play index undefined Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:11 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 10 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: MRS: Cast player state: PLAYING Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Preload queue cleared Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreStateMachine::ClearQueue Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreStateMachine::stop Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CorePlayQueue::clearPlayQueue Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CorePlayQueue::saveQueue Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CoreStateMachine::addQueueItems Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: CorePlayQueue::addQueueItems Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Preload queue cleared Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/74944825 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Exploding uri tidal://song/74944825 in service tidal Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/205362155 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/205362155 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/268019983 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/268019983 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/332836942 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/332836942 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/94302672 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/94302672 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/453982152 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/453982152 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/339590479 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/339590479 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/109636786 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/109636786 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/2570512 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/2570512 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/72697169 Dec 23 00:18:15 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/72697169 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: explodeTIDALUri took 276 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [74944825] not found"} Dec 23 00:18:16 volumio-dac2pro volumio[869]: error: Commandrouter: Cannot explode uri tidal://song/74944825 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [74944825] not found"} Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::saveQueue Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::updateTrackBlock Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrackBlock Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPlay Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::play index 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::addQueueItems Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::addQueueItems Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Preload queue cleared Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Adding Item to queue: tidal://song/6244864 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Using cached record of: tidal://song/6244864 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::stop Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushQueue Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::saveQueue Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::play index undefined Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::updateTrackBlock Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrackBlock Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: [1766445496252] ControllerTidal::clearAddPlayTrack Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Getting stream with soundQuality LOSSLESS Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand stop took 2 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Dec 23 00:18:16 volumio-dac2pro volumio[869]: ---------------------------- MPD announces system playlist update Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Ignoring MPD Status Update Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand clear took 1 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS" Dec 23 00:18:16 volumio-dac2pro volumio[869]: error: updateQueue error: null Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Dec 23 00:18:16 volumio-dac2pro volumio[869]: ---------------------------- MPD announces system playlist update Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Ignoring MPD Status Update Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ------------------------------ 2ms Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS" took 1 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand play Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ------------------------------ 1ms Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand play took 2 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: STREAMING PROXY: Handling url /?data=tidal://song/6244864&soundQuality=LOSSLESS Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Executing endpoint getStreamUrltidal Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: getStreamUrl took 295 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkYmQ0ZWU4YzA2Y2MyMmNhZTc2ODdiODg4ODg0NWUzOV82MS5tcDQ/0.flac?token=1766449096~NDE0YzlmMjFlZmU5MGVmMjI2YjM1YTRmM2E1ZmQ3NTc4YWVhNjI1NA== Dec 23 00:18:16 volumio-dac2pro volumio[869]: STREAMING PROXY: Response: 200, length: 45619306 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Dec 23 00:18:16 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 2 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Dec 23 00:18:16 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus stop Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ------------------------------ 4ms Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 2 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:16 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:16 volumio-dac2pro volumio[869]: info: ------------------------------ 32ms Dec 23 00:18:16 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:16 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:17 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:18:17 volumio-dac2pro volumio[869]: info: MRS: Cast player state: BUFFERING Dec 23 00:18:18 volumio-dac2pro dhcpcd[3450]: eth0: carrier lost Dec 23 00:18:18 volumio-dac2pro kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 23 00:18:18 volumio-dac2pro dhcpcd[3450]: eth0: deleting route to 192.168.1.0/24 Dec 23 00:18:18 volumio-dac2pro avahi-daemon[590]: Withdrawing address record for 192.168.1.66 on eth0. Dec 23 00:18:18 volumio-dac2pro dhcpcd[3450]: eth0: deleting default route via 192.168.1.1 Dec 23 00:18:18 volumio-dac2pro avahi-daemon[590]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.66. Dec 23 00:18:18 volumio-dac2pro avahi-daemon[590]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 23 00:18:18 volumio-dac2pro volumio[869]: info: Discovery: A device disappeared from network Dec 23 00:18:18 volumio-dac2pro volumio[869]: info: Discovery: Device volumiorpi4-amp2 disappeared from network Dec 23 00:18:18 volumio-dac2pro volumio[869]: info: Discovery: Disconnected from remote: 192.168.1.75 Dec 23 00:18:18 volumio-dac2pro volumio[869]: info: Discovery: A device disappeared from network Dec 23 00:18:18 volumio-dac2pro volumio[869]: info: Discovery: Device volumio-dac2pro disappeared from network Dec 23 00:18:18 volumio-dac2pro ifplugd(eth0)[844]: Link beat lost. Dec 23 00:18:18 volumio-dac2pro nmbd[796]: [2025/12/23 00:18:18.715169, 0] ../source3/libsmb/nmblib.c:917(send_udp) Dec 23 00:18:18 volumio-dac2pro nmbd[796]: Packet send failed to 192.168.1.255(137) ERRNO=Network is unreachable Dec 23 00:18:18 volumio-dac2pro nmbd[796]: [2025/12/23 00:18:18.715316, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Dec 23 00:18:18 volumio-dac2pro nmbd[796]: send_netbios_packet: send_packet() to IP 192.168.1.255 port 137 failed Dec 23 00:18:18 volumio-dac2pro nmbd[796]: [2025/12/23 00:18:18.715375, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Dec 23 00:18:18 volumio-dac2pro nmbd[796]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: Deleting interface #17 eth0, 192.168.1.66#123, interface stats: received=6, sent=6, dropped=0, active_time=100 secs Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 195.252.108.16 local addr 192.168.1.66 -> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 195.178.58.245 local addr 192.168.1.66 -> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 217.24.20.5 local addr 192.168.1.66 -> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 147.91.26.20 local addr 192.168.1.66 -> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 195.250.114.105 local addr 192.168.1.66 -> Dec 23 00:18:19 volumio-dac2pro ntpd[840]: 147.91.8.1 local addr 192.168.1.66 -> Dec 23 00:18:21 volumio-dac2pro dhcpcd[3450]: eth0: carrier acquired Dec 23 00:18:21 volumio-dac2pro dhcpcd[3450]: eth0: IAID 32:21:a1:d7 Dec 23 00:18:21 volumio-dac2pro kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Dec 23 00:18:21 volumio-dac2pro ifplugd(eth0)[844]: Link beat detected. Dec 23 00:18:21 volumio-dac2pro dhcpcd[3450]: eth0: soliciting an IPv6 router Dec 23 00:18:21 volumio-dac2pro dhcpcd[3450]: eth0: rebinding lease of 192.168.1.66 Dec 23 00:18:21 volumio-dac2pro dhcpcd[3450]: eth0: probing address 192.168.1.66/24 Dec 23 00:18:26 volumio-dac2pro dhcpcd[3450]: eth0: leased 192.168.1.66 for 86400 seconds Dec 23 00:18:26 volumio-dac2pro dhcpcd[3450]: eth0: adding route to 192.168.1.0/24 Dec 23 00:18:26 volumio-dac2pro dhcpcd[3450]: eth0: adding default route via 192.168.1.1 Dec 23 00:18:26 volumio-dac2pro avahi-daemon[590]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.66. Dec 23 00:18:26 volumio-dac2pro avahi-daemon[590]: New relevant interface eth0.IPv4 for mDNS. Dec 23 00:18:26 volumio-dac2pro avahi-daemon[590]: Registering new address record for 192.168.1.66 on eth0.IPv4. Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: MRS: Found cast device: 4K-SMART-TV-fed4500adf8e0a706d2d916f52d94f22 Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: MRS: Found cast device: Raspberry-Pi-5-b02887faf46525244873d61907b990f4 Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: MRS: Found cast device: SDOTT0202-96caedfdffad48d1ea4d3f29244dee50 Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: Discovery: adding e853999d-fce1-4cd0-b5db-6dc88c1e809e Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: Discovery: Found device VolumioRPi4-Amp2 Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: Discovery: Connecting to remote: 192.168.1.75 Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: MRS: Pushing audio output on request Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:26 volumio-dac2pro volumio[869]: info: Discovery: Connected to remote: 192.168.1.75 Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Discovery: adding ee47b1c2-9250-42b3-80be-a8b78a2d1fd3 Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Discovery: Found device Volumio-Dac2Pro Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:27 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.244 UA: Mozilla/5.0 (Linux; Android 16; SM-F946B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 23 00:18:27 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.244 UA: Mozilla/5.0 (Linux; Android 16; SM-F946B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Received Get System Info Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Discovery: Getting this device information Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: Listing playlists Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetQueue Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreStateMachine::getQueue Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CorePlayQueue::getQueue Dec 23 00:18:27 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 00:18:27 volumio-dac2pro ntpd[840]: Listen normally on 18 eth0 192.168.1.66:123 Dec 23 00:18:27 volumio-dac2pro ntpd[840]: new interface(s) found: waking up resolver Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: MRS: Cast error: read ECONNRESET Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: MRS: Cast error: read ECONNRESET Dec 23 00:18:32 volumio-dac2pro dhcpcd[3450]: eth0: carrier lost Dec 23 00:18:32 volumio-dac2pro kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 23 00:18:32 volumio-dac2pro ifplugd(eth0)[844]: Link beat lost. Dec 23 00:18:32 volumio-dac2pro avahi-daemon[590]: Withdrawing address record for 192.168.1.66 on eth0. Dec 23 00:18:32 volumio-dac2pro dhcpcd[3450]: eth0: deleting route to 192.168.1.0/24 Dec 23 00:18:32 volumio-dac2pro avahi-daemon[590]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.66. Dec 23 00:18:32 volumio-dac2pro dhcpcd[3450]: eth0: deleting default route via 192.168.1.1 Dec 23 00:18:32 volumio-dac2pro avahi-daemon[590]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: Discovery: A device disappeared from network Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: Discovery: Device volumiorpi4-amp2 disappeared from network Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: Discovery: Disconnected from remote: 192.168.1.75 Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: Discovery: A device disappeared from network Dec 23 00:18:32 volumio-dac2pro volumio[869]: info: Discovery: Device volumio-dac2pro disappeared from network Dec 23 00:18:34 volumio-dac2pro ntpd[840]: Deleting interface #18 eth0, 192.168.1.66#123, interface stats: received=5, sent=6, dropped=0, active_time=7 secs Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 195.252.108.16 local addr 192.168.1.66 -> Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 195.178.58.245 local addr 192.168.1.66 -> Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 217.24.20.5 local addr 192.168.1.66 -> Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 147.91.26.20 local addr 192.168.1.66 -> Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 195.250.114.105 local addr 192.168.1.66 -> Dec 23 00:18:34 volumio-dac2pro ntpd[840]: 147.91.8.1 local addr 192.168.1.66 -> Dec 23 00:18:35 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:35+01:00" level=trace msg="sent dealer ping" Dec 23 00:18:36 volumio-dac2pro volumio[869]: info: Volumio Network Manager: Network status updated: 0 Dec 23 00:18:36 volumio-dac2pro dhcpcd[3450]: eth0: carrier acquired Dec 23 00:18:36 volumio-dac2pro dhcpcd[3450]: eth0: IAID 32:21:a1:d7 Dec 23 00:18:36 volumio-dac2pro ifplugd(eth0)[844]: Link beat detected. Dec 23 00:18:36 volumio-dac2pro kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Dec 23 00:18:37 volumio-dac2pro dhcpcd[3450]: eth0: soliciting an IPv6 router Dec 23 00:18:37 volumio-dac2pro dhcpcd[3450]: eth0: rebinding lease of 192.168.1.66 Dec 23 00:18:37 volumio-dac2pro dhcpcd[3450]: eth0: probing address 192.168.1.66/24 Dec 23 00:18:39 volumio-dac2pro ntpd[840]: Soliciting pool server 2001:ac8:7d:42:0:2:0:20 Dec 23 00:18:41 volumio-dac2pro ntpd[840]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 23 00:18:42 volumio-dac2pro dhcpcd[3450]: eth0: leased 192.168.1.66 for 86400 seconds Dec 23 00:18:42 volumio-dac2pro avahi-daemon[590]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.66. Dec 23 00:18:42 volumio-dac2pro dhcpcd[3450]: eth0: adding route to 192.168.1.0/24 Dec 23 00:18:42 volumio-dac2pro avahi-daemon[590]: New relevant interface eth0.IPv4 for mDNS. Dec 23 00:18:42 volumio-dac2pro dhcpcd[3450]: eth0: adding default route via 192.168.1.1 Dec 23 00:18:42 volumio-dac2pro avahi-daemon[590]: Registering new address record for 192.168.1.66 on eth0.IPv4. Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Found cast device: 4K-SMART-TV-fed4500adf8e0a706d2d916f52d94f22 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Found cast device: SDOTT0202-96caedfdffad48d1ea4d3f29244dee50 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: Discovery: adding e853999d-fce1-4cd0-b5db-6dc88c1e809e Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: Discovery: Found device VolumioRPi4-Amp2 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: Discovery: Connecting to remote: 192.168.1.75 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Found cast device: Raspberry-Pi-5-b02887faf46525244873d61907b990f4 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Pushing audio output on request Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: Discovery: Connected to remote: 192.168.1.75 Dec 23 00:18:42 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66:3000 from 192.168.1.75 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Adding multiroomSync output Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: Adding audio output: Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 23 00:18:42 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:43 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66:3000 from 192.168.1.244 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: Discovery: Getting this device information Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: Discovery: adding ee47b1c2-9250-42b3-80be-a8b78a2d1fd3 Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: Discovery: Found device Volumio-Dac2Pro Dec 23 00:18:43 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:43 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66:3000 from 192.168.1.75 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 23 00:18:43 volumio-dac2pro ntpd[840]: Listen normally on 19 eth0 192.168.1.66:123 Dec 23 00:18:43 volumio-dac2pro ntpd[840]: new interface(s) found: waking up resolver Dec 23 00:18:44 volumio-dac2pro volumio[869]: verbose: New Socket.io Connection to 192.168.1.66:3000 from 192.168.1.244 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPrevious Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::previous Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: [1766445526340] ControllerTidal::seek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::seek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPrevious Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::previous Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::previous Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand previous Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioSeek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::seek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: TRACKBLOCK {"album":"Champagne","albumUri":"tidal://album/6244862","albumart":"https://resources.tidal.com/images/df5b8634/4215/4981/8713/946701977409/640x640.jpg","artist":"Philippe Chrétien","artistUri":"tidal://artist/3560021","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":449,"explicit":false,"name":"Champagne","samplerate":"44.1 KHz","service":"tidal","title":"Champagne","trackType":"tidal","tracknumber":2,"type":"track","uri":"tidal://song/6244864","volumeNumber":1} Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::startPlaybackTimer Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: [1766445526343] ControllerTidal::seek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::seek Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: error: null Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Dec 23 00:18:46 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:46 volumio-dac2pro volumio[869]: error: null Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Dec 23 00:18:46 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Dec 23 00:18:46 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand previous took 20 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 14 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 15 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 14 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"730 Kbps","isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"647 Kbps","isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:46 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus play Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Received an update from plugin. extracting info from payload Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ------------------------------ 73ms Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ------------------------------ 74ms Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: ------------------------------ 74ms Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:46 volumio-dac2pro volumio[869]: info: Volumio Network Manager: Network status updated: 1 Dec 23 00:18:48 volumio-dac2pro volumio[869]: STREAMING PROXY: Client dropped request, destroying Dec 23 00:18:49 volumio-dac2pro go-librespot[1226]: time="2025-12-23T00:18:49+01:00" level=trace msg="received dealer pong" Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::servicePause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: [1766445530431] ControllerTidal::pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: ControllerMpd::pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: Dec 23 00:18:50 volumio-dac2pro volumio[869]: ---------------------------- MPD announces state update: player Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: sendMpdCommand pause took 1 milliseconds Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: ControllerMpd::getState Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand status Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: sendMpdCommand status took 1 milliseconds Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseState Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: ControllerMpd::parseTrackInfo Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: ControllerMpd::pushState Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::servicePushState Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CorePlayQueue::getTrack 9 Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5468,"duration":449,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"466 Kbps","isStreaming":false,"title":"6244864&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/6244864&soundQuality=LOSSLESS","trackType":"tidal"} Dec 23 00:18:50 volumio-dac2pro volumio[869]: verbose: CURRENT POSITION 9 Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState stateService pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::syncState currentStatus pause Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::pushState Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioPushState Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output update for this device Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: MRS: Pushing multiroomSync output Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreCommandRouter::volumioGetState Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: CoreStateMachine::stPlaybackTimer Dec 23 00:18:50 volumio-dac2pro volumio[869]: info: ------------------------------ 12ms Dec 23 00:18:50 volumio-dac2pro volumio[869]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 23 00:18:53 volumio-dac2pro volumio[869]: info: MRS: STARTING volumioStreaming Dec 23 00:18:53 volumio-dac2pro sudo[23760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream118.ts /tmp/hls/stream119.ts /tmp/hls/stream120.ts /tmp/hls/stream121.ts /tmp/hls/stream122.ts Dec 23 00:18:53 volumio-dac2pro sudo[23760]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 00:18:53 volumio-dac2pro sudo[23760]: pam_unix(sudo:session): session closed for user root Dec 23 00:18:53 volumio-dac2pro volumio[869]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 00:18:53 volumio-dac2pro volumio[869]: TypeError: Cannot read property 'send' of null Dec 23 00:18:53 volumio-dac2pro volumio[869]: at Client.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:128:11) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at Channel.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:34:12) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at fn.Controller.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:28:16) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at fn.RequestResponseController.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:34:8) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at fn.MediaController.sessionRequest (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:91:8) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at fn.MediaController.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:107:8) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at DefaultMediaReceiver.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/default-media-receiver.js:42:19) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:5038) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at listOnTimeout (internal/timers.js:554:17) Dec 23 00:18:53 volumio-dac2pro volumio[869]: at processTimers (internal/timers.js:497:7) Dec 23 00:18:53 volumio-dac2pro volumio[869]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 00:18:53 volumio-dac2pro sudo[23763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Dec 23 00:18:53 volumio-dac2pro sudo[23763]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 00:18:53 volumio-dac2pro systemd[1]: Stopping VolumioStreamingService... Dec 23 00:18:53 volumio-dac2pro systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Dec 23 00:18:53 volumio-dac2pro systemd[1]: volumioStreaming.service: Succeeded. Dec 23 00:18:53 volumio-dac2pro systemd[1]: Stopped VolumioStreamingService. Dec 23 00:18:53 volumio-dac2pro systemd[1]: Started VolumioStreamingService. Dec 23 00:18:53 volumio-dac2pro sudo[23763]: pam_unix(sudo:session): session closed for user root Dec 23 00:18:53 volumio-dac2pro sudo[23774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 23 00:18:53 volumio-dac2pro sudo[23774]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 00:18:53 volumio-dac2pro sudo[23774]: pam_unix(sudo:session): session closed for user root Dec 23 00:18:53 volumio-dac2pro sudo[23778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 00:17 Dec 23 00:18:53 volumio-dac2pro sudo[23778]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"