-- Logs begin at Wed 2024-10-16 21:02:03 UTC, end at Fri 2024-11-22 15:28:18 UTC. -- Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::servicePushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreStateMachine::pushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::volumioPushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 15:27:17 digiplayer volumio[1023]: info: Pushing metadata Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::servicePushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreStateMachine::pushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::volumioPushState Nov 22 15:27:17 digiplayer volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 15:27:18 digiplayer vtcs[1394]: [2024-11-22 15:27:18.611] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5515 milliseconds Nov 22 15:27:18 digiplayer vtcs[1394]: [2024-11-22 15:27:18.611] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 22 15:27:24 digiplayer vtcs[1394]: [2024-11-22 15:27:24.138] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5514 milliseconds Nov 22 15:27:24 digiplayer vtcs[1394]: [2024-11-22 15:27:24.139] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 22 15:27:29 digiplayer vtcs[1394]: [2024-11-22 15:27:29.664] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5515 milliseconds Nov 22 15:27:29 digiplayer vtcs[1394]: [2024-11-22 15:27:29.664] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 22 15:27:29 digiplayer vtcs[1394]: [2024-11-22 15:27:29.668] [tisoc] [warning] [logger.cpp:22] [audio_worker.cpp:293] Max connection attempts reached! Nov 22 15:27:29 digiplayer volumio[1023]: info: Unknown command: notifyPlaybackError Nov 22 15:27:29 digiplayer volumio[1023]: info: CoreCommandRouter::servicePushState Nov 22 15:27:29 digiplayer volumio[1023]: info: CoreStateMachine::pushState Nov 22 15:27:29 digiplayer volumio[1023]: info: CoreCommandRouter::volumioPushState Nov 22 15:27:29 digiplayer volumio[1023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 15:28:02 digiplayer vtcs[1394]: [2024-11-22 15:28:02] [info] asio async_shutdown error: asio.misc:2 (End of file) Nov 22 15:28:02 digiplayer volumio[1023]: info: Tunnel connection is inactive, restarting it Nov 22 15:28:02 digiplayer volumio[1023]: info: Starting Tunnel 1 Nov 22 15:28:02 digiplayer volumio[1023]: info: Starting Tunnel Connection Checker Nov 22 15:28:03 digiplayer sudo[12450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 22 15:28:03 digiplayer sudo[12450]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 15:28:03 digiplayer systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 22 15:28:03 digiplayer autossh[11987]: received signal to exit (15) Nov 22 15:28:03 digiplayer volumio[1023]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: Playing: false Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: STATE:undefined Nov 22 15:28:03 digiplayer systemd[1]: Starting MyVolumio SSH Tunnel... Nov 22 15:28:03 digiplayer volumio[1023]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: Playing: false Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 22 15:28:03 digiplayer volumio[1023]: BT PLUGIN MESSAGE: STATE:undefined Nov 22 15:28:03 digiplayer systemd[1]: Started MyVolumio SSH Tunnel. Nov 22 15:28:03 digiplayer sudo[12450]: pam_unix(sudo:session): session closed for user root Nov 22 15:28:03 digiplayer volumio[1023]: info: Remote SSH Started Nov 22 15:28:03 digiplayer autossh[12458]: port set to 0, monitoring disabled Nov 22 15:28:03 digiplayer autossh[12458]: starting ssh (count 1) Nov 22 15:28:03 digiplayer autossh[12458]: ssh child pid is 12463 Nov 22 15:28:03 digiplayer volumio[1023]: info: CoreCommandRouter::volumioGetState Nov 22 15:28:03 digiplayer volumiossh-tunnel[12456]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 22 15:28:03 digiplayer vtcs[1394]: [2024-11-22 15:28:03.210] [tisoc] [warning] [PlaybackControllerImpl.cpp:859] Not send NotifyMediaChanged(media_info is null) Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:443" Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Authenticated as "nighttimelightbug" ! Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Using alsa sink Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Metadata pipe established Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Nov 22 15:28:11 digiplayer volumio[1155]: [Vollibrespot] : Country: "US" Nov 22 15:28:11 digiplayer volumio[1023]: [SpotifyConnect] Volume Spotify: 32.99916075379568 Volumio: 33 Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Fetching autoplay context uri Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Event: SessionActive { became_active_at: 1732289298117 } Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : SessionActive! Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 168157205836364183419752711240530040254, audio_type: Track } } Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 168157205836364183419752711240530040254, audio_type: Track } } Nov 22 15:28:18 digiplayer volumio[1023]: [SpotifyConnect] A connect session has begun Nov 22 15:28:18 digiplayer volumio[1023]: info: Acquiring new spotify session Nov 22 15:28:18 digiplayer volumio[1023]: info: CoreCommandRouter::volumioStop Nov 22 15:28:18 digiplayer volumio[1023]: info: CoreStateMachine::stop Nov 22 15:28:18 digiplayer volumio[1023]: info: CoreStateMachine::serviceStop Nov 22 15:28:18 digiplayer volumio[1023]: info: CoreCommandRouter::serviceStop Nov 22 15:28:18 digiplayer volumio[1023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 15:28:18 digiplayer volumio[1023]: TypeError: Cannot read property 'then' of undefined Nov 22 15:28:18 digiplayer volumio[1023]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Nov 22 15:28:18 digiplayer volumio[1023]: at emitNone (events.js:106:13) Nov 22 15:28:18 digiplayer volumio[1023]: at SpotConnEvents.emit (events.js:208:7) Nov 22 15:28:18 digiplayer volumio[1023]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Nov 22 15:28:18 digiplayer volumio[1023]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Nov 22 15:28:18 digiplayer volumio[1023]: at emitTwo (events.js:126:13) Nov 22 15:28:18 digiplayer volumio[1023]: at Socket.emit (events.js:214:7) Nov 22 15:28:18 digiplayer volumio[1023]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Nov 22 15:28:18 digiplayer volumio[1023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:0r6h2rRyYKzI8TLpxl8xQ5"> Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Resolving uri "spotify:station:playlist:0r6h2rRyYKzI8TLpxl8xQ5" Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Loading with Spotify URI Nov 22 15:28:18 digiplayer vtcs[1394]: [2024-11-22 15:28:18] [info] asio async_shutdown error: asio.misc:2 (End of file) Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBqU_lFxbEhFoQer5MSygyiKJmL9mNF04UyTL_y9nMs4H1RWTJRPxazQQeay8f9KULdvBE_-IBqBmg752D3CaJWpT6hmSYaH0ye1T90aOMiSAC9Qb0D1sfKx2bcYwaPUr1FxGb9pbmaVEwUD6CALtzeXiKfcpDpXtoWJ4hHY654Ni2A8ob7mDZqpkmZtyzpGE3ijb-Q6_6ZFw1GiodLfGY", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Nov 22 15:28:18 digiplayer volumio[1155]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:0r6h2rRyYKzI8TLpxl8xQ5"> Nov 22 15:28:18 digiplayer sudo[12527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 15:27 Nov 22 15:28:18 digiplayer sudo[12527]: 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"