-- Logs begin at Tue 2024-10-08 08:37:57 UTC, end at Wed 2024-10-09 15:28:49 UTC. -- Oct 09 15:27:07 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:14 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:22 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:29 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:35 volumio volumio[1038]: info: PlaybackInactive Oct 09 15:27:35 volumio volumio[1038]: info: Device Session is_active: true Oct 09 15:27:35 volumio volumio[1038]: info: DeviceInactive Oct 09 15:27:35 volumio volumio[1038]: info: Relinquishing Volumio State Oct 09 15:27:35 volumio volumio[1038]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 09 15:27:35 volumio volumio[1038]: info: unSetVolatile called Oct 09 15:27:35 volumio volumio[1038]: info: Relinquishing Volumio State to another service Oct 09 15:27:35 volumio vtcs[1481]: [2024-10-09 15:27:35.969] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 09 15:27:36 volumio vtcs[1481]: [2024-10-09 15:27:36] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 09 15:27:37 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:44 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:52 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 09 15:27:57 volumio volumio[1038]: info: Currently active: webradio Oct 09 15:27:57 volumio volumio[1038]: info: Stopping currently active service Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::stop Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::stPlaybackTimer Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::updateTrackBlock Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrackBlock Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::serviceStop Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::serviceStop Oct 09 15:27:57 volumio volumio[1038]: info: [1728487677601] ControllerWebradio::stop Oct 09 15:27:57 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop Oct 09 15:27:57 volumio volumio[1038]: info: sendMpdCommand stop took 9 milliseconds Oct 09 15:27:57 volumio volumio[1038]: info: TidalConnect Active Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 09 15:27:57 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 09 15:27:57 volumio volumio[1038]: info: Currently active: webradio Oct 09 15:27:57 volumio volumio[1038]: info: Setting Volatile state to tidalconnect Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Oct 09 15:27:57 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 09 15:27:57 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Oct 09 15:27:57 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Oct 09 15:27:59 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:06 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:11 volumio vtcs[1481]: [2024-10-09 15:28:11] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 09 15:28:14 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:21 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:29 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:36 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:44 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Authenticated as "andriusstankevicius" ! Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Using alsa sink Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Metadata pipe established Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Country: "LT" Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] 32.99916075379568 Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Oct 09 15:28:48 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 09 15:28:48 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 09 15:28:48 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 09 15:28:48 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Oct 09 15:28:48 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Fetching autoplay context uri Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Event: SessionActive { became_active_at: 1728487728729 } Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : SessionActive! Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 190659468858421828797699919194783378523, audio_type: Track } } Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 190659468858421828797699919194783378523, audio_type: Track } } Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] A connect session has begun Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] Device palyback is active! Oct 09 15:28:48 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] Currently active: tidalconnect Oct 09 15:28:48 volumio volumio[1038]: [SpotifyConnect] Stopping currently active service Oct 09 15:28:48 volumio volumio[1038]: info: CoreCommandRouter::volumioStop Oct 09 15:28:48 volumio volumio[1038]: info: CoreStateMachine::stop Oct 09 15:28:48 volumio volumio[1038]: info: CoreStateMachine::serviceStop Oct 09 15:28:48 volumio volumio[1038]: info: CoreCommandRouter::serviceStop Oct 09 15:28:48 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 15:28:48 volumio volumio[1038]: TypeError: Cannot read property 'then' of undefined Oct 09 15:28:48 volumio volumio[1038]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) Oct 09 15:28:48 volumio volumio[1038]: at SpotConnEvents.emit (events.js:315:20) Oct 09 15:28:48 volumio volumio[1038]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Oct 09 15:28:48 volumio volumio[1038]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Oct 09 15:28:48 volumio volumio[1038]: at Socket.emit (events.js:315:20) Oct 09 15:28:48 volumio volumio[1038]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Oct 09 15:28:48 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:1PdMoahMiMnqWfzWZs3xSI"> Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Resolving uri "spotify:station:album:1PdMoahMiMnqWfzWZs3xSI" Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC9PC67GKX8NP7QV5yx8tdPjiV6ppv5O0LsfGvYlHtyzyZI6sQ022c_t58XJkXQKFJXpNax-1eyPdJD6JhFfk6nY0OBnUXmpdcqYzTgbn-EzFxXiJ3dw4BufYyxyQXtPdgL0KUWlWwApeUb99fkNf9AraPxIOsYv82gSo9ECFvSiQYspsWwZgFpqYJsmobAeWJC-fY4_FSkl5r2yLotqEvqsQ", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Oct 09 15:28:48 volumio volumio[19830]: [Vollibrespot] : Loading with Spotify URI Oct 09 15:28:49 volumio sudo[10271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-09 15:27 Oct 09 15:28:49 volumio sudo[10271]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"