-- Logs begin at Sun 2024-02-04 06:45:03 UTC, end at Fri 2024-11-01 11:40:04 UTC. -- Nov 01 11:39:19 volumio-lwt volumio[1125]: error: error Nov 01 11:39:21 volumio-lwt volumio[1125]: info: Tunnel connection is inactive, restarting it Nov 01 11:39:21 volumio-lwt volumio[1125]: info: Starting Tunnel 1 Nov 01 11:39:21 volumio-lwt volumio[1125]: info: Starting Tunnel Connection Checker Nov 01 11:39:21 volumio-lwt sudo[12879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 01 11:39:21 volumio-lwt sudo[12879]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:39:21 volumio-lwt systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 01 11:39:21 volumio-lwt autossh[12447]: received signal to exit (15) Nov 01 11:39:21 volumio-lwt systemd[1]: Starting MyVolumio SSH Tunnel... Nov 01 11:39:21 volumio-lwt volumio[1125]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: Playing: false Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: STATE:undefined Nov 01 11:39:21 volumio-lwt systemd[1]: Started MyVolumio SSH Tunnel. Nov 01 11:39:21 volumio-lwt sudo[12879]: pam_unix(sudo:session): session closed for user root Nov 01 11:39:21 volumio-lwt autossh[12887]: port set to 0, monitoring disabled Nov 01 11:39:21 volumio-lwt autossh[12887]: starting ssh (count 1) Nov 01 11:39:21 volumio-lwt autossh[12887]: ssh child pid is 12892 Nov 01 11:39:21 volumio-lwt volumio[1125]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: Playing: false Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 01 11:39:21 volumio-lwt volumio[1125]: BT PLUGIN MESSAGE: STATE:undefined Nov 01 11:39:21 volumio-lwt volumio[1125]: info: Remote SSH Started Nov 01 11:39:21 volumio-lwt volumio[1125]: info: CoreCommandRouter::volumioGetState Nov 01 11:39:21 volumio-lwt volumiossh-tunnel[12885]: Warning: Permanently added '[au1.myvolumio.org]:2222,[170.64.163.26]:2222' (RSA) to the list of known hosts. Nov 01 11:40:02 volumio-lwt volumio[8185]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Authenticated as "oops.com" ! Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:PCM index:0 Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12799)[dB]) -- max: 32767 (MilliBel(0)[dB]) HW: true Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Using alsa sink Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Metadata pipe established Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Nov 01 11:40:03 volumio-lwt volumio[8185]: [Vollibrespot] : Country: "AU" Nov 01 11:40:03 volumio-lwt volumio[1125]: [SpotifyConnect] Volume Spotify: 0 Volumio: 0 Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Fetching autoplay context uri Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Event: SessionActive { became_active_at: 1730461204409 } Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : SessionActive! Nov 01 11:40:04 volumio-lwt volumio[1125]: [SpotifyConnect] A connect session has begun Nov 01 11:40:04 volumio-lwt volumio[1125]: info: Acquiring new spotify session Nov 01 11:40:04 volumio-lwt volumio[1125]: info: CoreCommandRouter::volumioStop Nov 01 11:40:04 volumio-lwt volumio[1125]: info: CoreStateMachine::stop Nov 01 11:40:04 volumio-lwt volumio[1125]: info: CoreStateMachine::serviceStop Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 8433316957763052474921014829813211063, audio_type: Track } } Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 8433316957763052474921014829813211063, audio_type: Track } } Nov 01 11:40:04 volumio-lwt volumio[1125]: info: CoreCommandRouter::serviceStop Nov 01 11:40:04 volumio-lwt volumio[1125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 11:40:04 volumio-lwt volumio[1125]: TypeError: Cannot read property 'then' of undefined Nov 01 11:40:04 volumio-lwt volumio[1125]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Nov 01 11:40:04 volumio-lwt volumio[1125]: at emitNone (events.js:106:13) Nov 01 11:40:04 volumio-lwt volumio[1125]: at SpotConnEvents.emit (events.js:208:7) Nov 01 11:40:04 volumio-lwt volumio[1125]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Nov 01 11:40:04 volumio-lwt volumio[1125]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Nov 01 11:40:04 volumio-lwt volumio[1125]: at emitTwo (events.js:126:13) Nov 01 11:40:04 volumio-lwt volumio[1125]: at Socket.emit (events.js:214:7) Nov 01 11:40:04 volumio-lwt volumio[1125]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Nov 01 11:40:04 volumio-lwt volumio[1125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:track:0bYg9bo50gSsH3LtXe2SQn"> Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Resolving uri "spotify:station:track:0bYg9bo50gSsH3LtXe2SQn" Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQDocXkbr7s-jBgZGFVOlqBFXGU-RngbbrSaJr2_NRUh3gk99FoPB3S3gJhgph6WI0hsxqpuzt4aefjSEUCIHG7DuaP9GZVibZG5uLxu6L9LG8nIn5dbWIHemjNMV9pLqeVvJXWWynDC4LE294xDUcmyh9ip3MpPyOb3RIyjWqRaZikFPjTNF5Argc2_GsvlwAQFhBuTZlk", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Nov 01 11:40:04 volumio-lwt volumio[8185]: [Vollibrespot] : Loading with Spotify URI Nov 01 11:40:04 volumio-lwt sudo[13016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 11:39 Nov 01 11:40:04 volumio-lwt sudo[13016]: pam_unix(sudo:session): session opened for user root by (uid=0) 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"