-- Logs begin at Mon 2024-07-01 18:25:38 UTC, end at Sat 2024-08-24 19:19:11 UTC. -- Aug 24 19:18:03 volumio volumio[973]: info: Airplay started streaming, receiving metadatas Aug 24 19:18:03 volumio volumio[973]: verbose: UNSET VOLATILE: Service: tidalconnect Aug 24 19:18:03 volumio volumio[973]: info: unSetVolatile called Aug 24 19:18:03 volumio volumio[973]: info: Relinquishing Volumio State to another service Aug 24 19:18:03 volumio volumio[973]: info: Airplay started streaming, receiving metadatas Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:03 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:03 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::serviceStop Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::serviceStop Aug 24 19:18:03 volumio volumio[973]: info: [1724527083239] ControllerWebradio::stop Aug 24 19:18:03 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: Airplay started streaming, receiving metadatas Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: Airplay started streaming, receiving metadatas Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: Airplay started streaming, receiving metadatas Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: PlaybackInactive Aug 24 19:18:03 volumio volumio[973]: info: Device Session is_active: true Aug 24 19:18:03 volumio volumio[973]: info: DeviceInactive Aug 24 19:18:03 volumio volumio[973]: info: Relinquishing Volumio State Aug 24 19:18:03 volumio volumio[973]: info: sendMpdCommand stop took 23 milliseconds Aug 24 19:18:03 volumio shairport-sync[1606]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 24 19:18:03 volumio shairport-sync[1606]: Dload Upload Total Spent Left Speed Aug 24 19:18:03 volumio volumio[973]: info: Aug 24 19:18:03 volumio volumio[973]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 24 19:18:03 volumio volumio[973]: info: Airplay playback start Aug 24 19:18:03 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Aug 24 19:18:03 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:03 volumio shairport-sync[1606]: [158B blob data] Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:04 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:04 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:04 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio shairport-sync[1606]: {"time":1724527083273,"response":"startAirplayPlayback Success"}Aug 24 19:18:05.169 ERRO Failed to start websocket server: Address already in use (os error 98), module: camillalib::socketserver Aug 24 19:18:05 volumio shairport-sync[1606]: Aug 24 19:18:05.184 ERRO Playback error: ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy', module: camilladsp Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.203 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:05 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:05 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.309 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.412 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.516 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.588 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.688 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.795 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.890 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:05 volumio vtcs[1633]: Aug 24 19:18:05.969 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.063 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.202 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.295 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.432 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.526 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.621 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.720 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.817 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.865 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:06 volumio vtcs[1633]: Aug 24 19:18:06.967 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.063 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.200 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.255 WARN sample rate change detected, last rate was 31084.10825435518 Hz, module: camillalib::filedevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.321 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.373 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.478 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.572 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:07 volumio vtcs[1633]: Aug 24 19:18:07.678 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Aug 24 19:18:17 volumio volumio[973]: info: Currently active: airplay_emulation Aug 24 19:18:17 volumio volumio[973]: info: Stopping currently active service Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:18:17 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:18:17 volumio volumio[973]: info: CoreStateMachine::serviceStop Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::serviceStop Aug 24 19:18:17 volumio volumio[973]: info: Airplay Stop Aug 24 19:18:17 volumio volumio[973]: info: Stopping Airplay Playback and sending pause command to client via USR2 Aug 24 19:18:17 volumio sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1606 Aug 24 19:18:17 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 19:18:17 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root Aug 24 19:18:17 volumio volumio[973]: info: Shairport-Sync paused with USR2 Aug 24 19:18:17 volumio volumio[973]: info: TidalConnect Active Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Aug 24 19:18:17 volumio volumio[973]: info: Currently active: airplay_emulation Aug 24 19:18:17 volumio volumio[973]: info: Setting Volatile state to tidalconnect Aug 24 19:18:17 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:17 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:17 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:17 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::servicePushState Aug 24 19:18:17 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:18:17 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:18:17 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:18:17 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:18:18 volumio volumio[973]: info: Restarting Shairport-Sync after stop Aug 24 19:18:18 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 19:18:18 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 19:18:18 volumio shairport-sync[1606]: thread 'ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:104:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook got a non zero return 15 from command tail --pid `pidof camilladsp` -f /dev/null Aug 24 19:18:18 volumio shairport-sync[1606]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:144:(snd_pcm_volumiohook_close) PCM close called for fusiondsphook Aug 24 19:18:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 24 19:18:18 volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 24 19:18:18 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 24 19:18:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 24 19:18:18 volumio volumio[973]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 24 19:18:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 19:18:18 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Aug 24 19:18:18 volumio volumio[973]: info: Shairport-Sync retarted Aug 24 19:18:32 volumio vtcs[1633]: [2024-08-24 19:18:32] [info] asio async_shutdown error: asio.misc:2 (End of file) Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Authenticated as "andriusstankevicius" ! Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Using alsa sink Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Metadata pipe established Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Country: "LT" Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] 32.99916075379568 Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Aug 24 19:19:11 volumio volumio[973]: info: CoreStateMachine::pushState Aug 24 19:19:11 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 19:19:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Aug 24 19:19:11 volumio volumio[973]: info: MRS: Pushing multiroomSync output update for this device Aug 24 19:19:11 volumio volumio[973]: info: MRS: Pushing multiroomSync output Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Fetching autoplay context uri Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Event: SessionActive { became_active_at: 1724527151539 } Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : SessionActive! Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 212757860193552649493567578119410342468, audio_type: Track } } Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 212757860193552649493567578119410342468, audio_type: Track } } Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] A connect session has begun Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] Device palyback is active! Aug 24 19:19:11 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] Currently active: tidalconnect Aug 24 19:19:11 volumio volumio[973]: [SpotifyConnect] Stopping currently active service Aug 24 19:19:11 volumio volumio[973]: info: CoreCommandRouter::volumioStop Aug 24 19:19:11 volumio volumio[973]: info: CoreStateMachine::stop Aug 24 19:19:11 volumio volumio[973]: info: CoreStateMachine::serviceStop Aug 24 19:19:11 volumio volumio[973]: info: CoreCommandRouter::serviceStop Aug 24 19:19:11 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 19:19:11 volumio volumio[973]: TypeError: Cannot read property 'then' of undefined Aug 24 19:19:11 volumio volumio[973]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Aug 24 19:19:11 volumio volumio[973]: at SpotConnEvents.emit (events.js:315:20) Aug 24 19:19:11 volumio volumio[973]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Aug 24 19:19:11 volumio volumio[973]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Aug 24 19:19:11 volumio volumio[973]: at Socket.emit (events.js:315:20) Aug 24 19:19:11 volumio volumio[973]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Aug 24 19:19:11 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:2256qKBSQdt53T5dz4Kdcs"> Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Resolving uri "spotify:station:album:2256qKBSQdt53T5dz4Kdcs" Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBSPmZYQPFa_SH3j9DgeUKwHR4E48xlOFPJl_uFYxGNniHubg4LzjwMhMlYmNH4ROGXdMKdOblGKGcpOLH4JEKQULJ8SkMbx_S2JsuKTvBlJLc5p9pbrPqDPiXDrwwb6ZZkyJOgpWxT0kFAvSQ4K7cDXQt2pcWQatW_QjGWWh_A9f4VNopwN24PAlVNp39yix46lOoOtf7I_Z1Fo5GAdUSHwQ", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Aug 24 19:19:11 volumio volumio[1608]: [Vollibrespot] : Loading with Spotify URI Aug 24 19:19:11 volumio sudo[2201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 19:18 Aug 24 19:19:11 volumio sudo[2201]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"