-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2024-04-21 04:12:14 UTC. -- Apr 21 04:11:46 volumio volumio[837]: verbose: New Socket.io Connection to 192.168.1.251 from 192.168.1.163 UA: unknown Total Clients: 6 Apr 21 04:11:46 volumio volumio[837]: info: CoreCommandRouter::volumioGetState Apr 21 04:11:46 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 21 04:11:46 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 21 04:11:46 volumio volumio[837]: info: Tunnel connection is inactive, restarting it Apr 21 04:11:46 volumio volumio[837]: info: Starting Tunnel 1 Apr 21 04:11:46 volumio volumio[837]: info: Starting Tunnel Connection Checker Apr 21 04:11:46 volumio sudo[2854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 21 04:11:46 volumio sudo[2854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 04:11:46 volumio autossh[2793]: received signal to exit (15) Apr 21 04:11:46 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 21 04:11:46 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 21 04:11:46 volumio systemd[1]: sshtunnel.service: Succeeded. Apr 21 04:11:46 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 21 04:11:46 volumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 21 04:11:46 volumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 21 04:11:46 volumio systemd[1]: Started MyVolumio SSH Tunnel. Apr 21 04:11:46 volumio sudo[2854]: pam_unix(sudo:session): session closed for user root Apr 21 04:11:47 volumio volumio[837]: info: Remote SSH Started Apr 21 04:11:47 volumio autossh[2857]: port set to 0, monitoring disabled Apr 21 04:11:47 volumio autossh[2857]: starting ssh (count 1) Apr 21 04:11:47 volumio autossh[2857]: ssh child pid is 2860 Apr 21 04:11:47 volumio volumio[837]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 21 04:11:47 volumio volumio[837]: info: CoreCommandRouter::volumioGetState Apr 21 04:11:47 volumio volumiossh-tunnel[2856]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Apr 21 04:11:48 volumio volumio[837]: verbose: New Socket.io Connection to 192.168.1.251 from 192.168.1.163 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 04:11:48 volumio volumio[837]: info: Discovery: Getting this device information Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::volumioGetState Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::volumioGetState Apr 21 04:11:48 volumio volumio[837]: info: CoreCommandRouter::volumioGetQueue Apr 21 04:11:48 volumio volumio[837]: info: CoreStateMachine::getQueue Apr 21 04:11:48 volumio volumio[837]: info: CorePlayQueue::getQueue Apr 21 04:11:48 volumio volumio[837]: info: Listing playlists Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713672733840 } Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : SessionActive! Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Fetching autoplay context uri Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 197505341593010383221975613897244072533, audio_type: Track } } Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 197505341593010383221975613897244072533, audio_type: Track } } Apr 21 04:12:13 volumio volumio[837]: [SpotifyConnect] A connect session has begun Apr 21 04:12:13 volumio volumio[837]: info: Acquiring new spotify session Apr 21 04:12:13 volumio volumio[837]: info: CoreCommandRouter::volumioStop Apr 21 04:12:13 volumio volumio[837]: info: CoreStateMachine::stop Apr 21 04:12:13 volumio volumio[837]: info: CoreStateMachine::serviceStop Apr 21 04:12:13 volumio volumio[837]: info: CoreCommandRouter::serviceStop Apr 21 04:12:13 volumio volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 04:12:13 volumio vtcs[1311]: [2024-04-21 04:12:13.855] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Apr 21 04:12:13 volumio volumio[837]: TypeError: Cannot read property 'then' of undefined Apr 21 04:12:13 volumio volumio[837]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) Apr 21 04:12:13 volumio volumio[837]: at SpotConnEvents.emit (events.js:315:20) Apr 21 04:12:13 volumio volumio[837]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Apr 21 04:12:13 volumio volumio[837]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Apr 21 04:12:13 volumio volumio[837]: at Socket.emit (events.js:315:20) Apr 21 04:12:13 volumio volumio[837]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Apr 21 04:12:13 volumio volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:0dyjOAbkTkkzt832sWlIn3"> Apr 21 04:12:13 volumio volumio[1255]: [Vollibrespot] : Resolving uri "spotify:station:album:0dyjOAbkTkkzt832sWlIn3" Apr 21 04:12:14 volumio volumio[1255]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQA88t2e2jHk0o_OxjH8pS3skRh8GV5zV6icn1_rBDu-8Xs-bwbGudUHxfYz4LJ2eEvrnveK59P_o0NXTq6msRv0dsg-uB2fvZhxQMgZOaRbt9_l-M595uxUn8e08D4oKwkBsi4XRZYVGHK0kZXFFYyStBobZG0r30veSyt7BYR2GT-pZ7OeIaOq9NekFwi83g", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 21 04:12:14 volumio volumio[1255]: [Vollibrespot] : Loading with Spotify URI Apr 21 04:12:14 volumio sudo[2873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 04:11 Apr 21 04:12:14 volumio sudo[2873]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"