-- Logs begin at Fri 2024-04-05 08:57:17 UTC, end at Fri 2024-04-05 10:10:33 UTC. -- Apr 05 10:09:20 volumio-pc vtcs[1534]: [2024-04-05 10:09:20.428] [tisoc] [warning] [PlaybackEngineTidal.cpp:142] url fetch error: 11, 401, {"status":401,"subStatus":4006,"userMessage":"Session does not have streaming privileges"} Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:23 volumio-pc volumio[882]: info: Pushing metadata Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:23 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:09:24 volumio-pc vtcs[1534]: [2024-04-05 10:09:24.762] [tisoc] [warning] [PlaybackEngineTidal.cpp:142] url fetch error: 12, 401, {"status":401,"subStatus":4006,"userMessage":"Session does not have streaming privileges"} Apr 05 10:09:24 volumio-pc volumio[882]: info: CoreCommandRouter::servicePushState Apr 05 10:09:24 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:09:24 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:09:24 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Authenticated as "1283195039" ! Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Speaker index:0 Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-10000)[dB]) -- max: 100 (MilliBel(0)[dB]) HW: true Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Using alsa sink Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Country: "MY" Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Metadata pipe established Apr 05 10:10:32 volumio-pc volumio[1410]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Apr 05 10:10:32 volumio-pc volumio[882]: [SpotifyConnect] 100 Apr 05 10:10:32 volumio-pc volumio[882]: [SpotifyConnect] Volume: Spotify:100 Volumio: 100 Apr 05 10:10:32 volumio-pc volumio[882]: info: CoreStateMachine::pushState Apr 05 10:10:32 volumio-pc volumio[882]: info: CoreCommandRouter::volumioPushState Apr 05 10:10:32 volumio-pc volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Fetching autoplay context uri Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Event: SessionActive { became_active_at: 1712311833194 } Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : SessionActive! Apr 05 10:10:33 volumio-pc volumio[882]: [SpotifyConnect] A connect session has begun Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 227801932063865304521792032107441345147, audio_type: Track } } Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 227801932063865304521792032107441345147, audio_type: Track } } Apr 05 10:10:33 volumio-pc volumio[882]: [SpotifyConnect] Device palyback is active! Apr 05 10:10:33 volumio-pc volumio[882]: info: CoreCommandRouter::volumioGetState Apr 05 10:10:33 volumio-pc volumio[882]: [SpotifyConnect] Currently active: tidalconnect Apr 05 10:10:33 volumio-pc volumio[882]: [SpotifyConnect] Stopping currently active service Apr 05 10:10:33 volumio-pc volumio[882]: info: CoreCommandRouter::volumioStop Apr 05 10:10:33 volumio-pc volumio[882]: info: CoreStateMachine::stop Apr 05 10:10:33 volumio-pc volumio[882]: info: CoreStateMachine::serviceStop Apr 05 10:10:33 volumio-pc volumio[882]: info: CoreCommandRouter::serviceStop Apr 05 10:10:33 volumio-pc volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 10:10:33 volumio-pc vtcs[1534]: [2024-04-05 10:10:33.204] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Apr 05 10:10:33 volumio-pc volumio[882]: TypeError: Cannot read property 'then' of undefined Apr 05 10:10:33 volumio-pc volumio[882]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Apr 05 10:10:33 volumio-pc volumio[882]: at emitNone (events.js:106:13) Apr 05 10:10:33 volumio-pc volumio[882]: at SpotConnEvents.emit (events.js:208:7) Apr 05 10:10:33 volumio-pc volumio[882]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Apr 05 10:10:33 volumio-pc volumio[882]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Apr 05 10:10:33 volumio-pc volumio[882]: at emitTwo (events.js:126:13) Apr 05 10:10:33 volumio-pc volumio[882]: at Socket.emit (events.js:214:7) Apr 05 10:10:33 volumio-pc volumio[882]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Apr 05 10:10:33 volumio-pc volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:64P9kEXQH3v2IR8MI8vLnK"> Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Resolving uri "spotify:station:artist:64P9kEXQH3v2IR8MI8vLnK" Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQDiDOLZWFeaawWi3Q-GRod7lD8nT7WAwj4OV7w4hxai9rRhcJ4vdapLvbTVNbSrPaVGYOHRFlbZ2imWmIM8TrIziUklNBK_x0lCk0mIxJMZXqj3d4c-fLcCr_NZyqXee_4IJjXTzqFMEHKE4uG7MGSW3Nf0rQHflWbpQNN_Crqjs_dq1e6wheMH02B1xp-HZC2gpQ", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 05 10:10:33 volumio-pc volumio[1410]: [Vollibrespot] : Loading with Spotify URI Apr 05 10:10:33 volumio-pc sudo[12673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 10:09 Apr 05 10:10:33 volumio-pc sudo[12673]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"