-- Logs begin at Wed 2025-01-08 17:36:33 UTC, end at Sun 2025-01-12 05:00:54 UTC. -- Jan 12 04:59:27 digiplayer volumio[1614]: info: Tunnel connection is inactive, restarting it Jan 12 04:59:27 digiplayer volumio[1614]: info: Starting Tunnel 1 Jan 12 04:59:27 digiplayer volumio[1614]: info: Starting Tunnel Connection Checker Jan 12 04:59:27 digiplayer sudo[12539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 04:59:28 digiplayer sudo[12539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 04:59:28 digiplayer systemd[1]: Stopping MyVolumio SSH Tunnel... Jan 12 04:59:28 digiplayer autossh[12105]: received signal to exit (15) Jan 12 04:59:28 digiplayer systemd[1]: Starting MyVolumio SSH Tunnel... Jan 12 04:59:28 digiplayer systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 04:59:28 digiplayer volumio[1614]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: Playing: false Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: STATE:undefined Jan 12 04:59:28 digiplayer sudo[12539]: pam_unix(sudo:session): session closed for user root Jan 12 04:59:28 digiplayer volumio[1614]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: Playing: false Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Jan 12 04:59:28 digiplayer volumio[1614]: BT PLUGIN MESSAGE: STATE:undefined Jan 12 04:59:28 digiplayer autossh[12546]: port set to 0, monitoring disabled Jan 12 04:59:28 digiplayer autossh[12546]: starting ssh (count 1) Jan 12 04:59:28 digiplayer autossh[12546]: ssh child pid is 12551 Jan 12 04:59:28 digiplayer volumio[1614]: info: Remote SSH Started Jan 12 04:59:28 digiplayer volumio[1614]: info: CoreCommandRouter::volumioGetState Jan 12 04:59:28 digiplayer volumiossh-tunnel[12545]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:443" Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Authenticated as "batten.brenan" ! Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Using alsa sink Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Country: "US" Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Metadata pipe established Jan 12 05:00:53 digiplayer volumio[14342]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Jan 12 05:00:53 digiplayer volumio[1614]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Fetching autoplay context uri Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Event: SessionActive { became_active_at: 1736658054170 } Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : SessionActive! Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 312302868756933776809939120960640862644, audio_type: Track } } Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 312302868756933776809939120960640862644, audio_type: Track } } Jan 12 05:00:54 digiplayer volumio[1614]: [SpotifyConnect] A connect session has begun Jan 12 05:00:54 digiplayer volumio[1614]: info: Acquiring new spotify session Jan 12 05:00:54 digiplayer volumio[1614]: info: CoreCommandRouter::volumioStop Jan 12 05:00:54 digiplayer volumio[1614]: info: CoreStateMachine::stop Jan 12 05:00:54 digiplayer volumio[1614]: info: CoreStateMachine::serviceStop Jan 12 05:00:54 digiplayer volumio[1614]: info: CoreCommandRouter::serviceStop Jan 12 05:00:54 digiplayer volumio[1614]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 05:00:54 digiplayer volumio[1614]: TypeError: Cannot read property 'then' of undefined Jan 12 05:00:54 digiplayer volumio[1614]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Jan 12 05:00:54 digiplayer volumio[1614]: at emitNone (events.js:106:13) Jan 12 05:00:54 digiplayer volumio[1614]: at SpotConnEvents.emit (events.js:208:7) Jan 12 05:00:54 digiplayer volumio[1614]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Jan 12 05:00:54 digiplayer volumio[1614]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Jan 12 05:00:54 digiplayer volumio[1614]: at emitTwo (events.js:126:13) Jan 12 05:00:54 digiplayer volumio[1614]: at Socket.emit (events.js:214:7) Jan 12 05:00:54 digiplayer volumio[1614]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jan 12 05:00:54 digiplayer volumio[1614]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 05:00:54 digiplayer vtcs[1958]: [2025-01-12 05:00:54.194] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:1zPULsN5SgzfAiBkFwDeOs"> Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Resolving uri "spotify:station:playlist:1zPULsN5SgzfAiBkFwDeOs" Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCdPd8LmnBo7u0RENwp2L7Ozzw1LopfF5V9Q93vNkVBKrX9iUbzsjBIguEd2cFHcKKYflDXGnJsLTKDZnqF2bvTWTlA1ozK0Spx7m6xyRBUHA8xBtRPUPI1nO0T-V-hl8BxlqujuoFZVWqZWl-GworvtfUagXKeFypL5VtUEoHaX0-L08Tk4e0rHz0ZpBavD1DSYtqduXn1zv0PZA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Loading with Spotify URI Jan 12 05:00:54 digiplayer sudo[12781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 04:59 Jan 12 05:00:54 digiplayer sudo[12781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 05:00:54 digiplayer volumio[14342]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:1zPULsN5SgzfAiBkFwDeOs"> PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"