-- 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"