-- Logs begin at Thu 2022-05-26 16:47:55 UTC, end at Sat 2025-05-03 07:06:49 UTC. -- May 03 07:05:07 volumio volumio[1076]: info: Tunnel connection is inactive, restarting it May 03 07:05:07 volumio volumio[1076]: info: Starting Tunnel 1 May 03 07:05:07 volumio volumio[1076]: info: Starting Tunnel Connection Checker May 03 07:05:08 volumio sudo[19011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 03 07:05:08 volumio sudo[19011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 07:05:08 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... May 03 07:05:08 volumio autossh[18544]: received signal to exit (15) May 03 07:05:08 volumio systemd[1]: Starting MyVolumio SSH Tunnel... May 03 07:05:08 volumio volumio[1076]: ------------------------------------ BT MESSAGE: BT STATUS: running May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: Playing: false May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: STATE:undefined May 03 07:05:08 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 03 07:05:08 volumio sudo[19011]: pam_unix(sudo:session): session closed for user root May 03 07:05:08 volumio volumio[1076]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: Playing: false May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect May 03 07:05:08 volumio volumio[1076]: BT PLUGIN MESSAGE: STATE:undefined May 03 07:05:08 volumio autossh[19019]: port set to 0, monitoring disabled May 03 07:05:08 volumio autossh[19019]: starting ssh (count 1) May 03 07:05:08 volumio autossh[19019]: ssh child pid is 19024 May 03 07:05:08 volumio volumio[1076]: info: Remote SSH Started May 03 07:05:08 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 03 07:05:08 volumio volumiossh-tunnel[19017]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Authenticated as "12177287486" ! May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Using alsa sink May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Metadata pipe established May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Country: "HK" May 03 07:06:48 volumio volumio[1176]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } May 03 07:06:48 volumio volumio[1076]: [SpotifyConnect] 100 May 03 07:06:48 volumio volumio[1076]: [SpotifyConnect] Volume: Spotify:100 Volumio: 100 May 03 07:06:48 volumio volumio[1076]: info: CoreStateMachine::pushState May 03 07:06:48 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState May 03 07:06:48 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Fetching autoplay context uri May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Event: SessionActive { became_active_at: 1746256009104 } May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : SessionActive! May 03 07:06:49 volumio volumio[1076]: [SpotifyConnect] A connect session has begun May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 186796667937695242518120704690915366495, audio_type: Track } } May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 186796667937695242518120704690915366495, audio_type: Track } } May 03 07:06:49 volumio volumio[1076]: [SpotifyConnect] Device palyback is active! May 03 07:06:49 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState May 03 07:06:49 volumio volumio[1076]: [SpotifyConnect] Currently active: tidalconnect May 03 07:06:49 volumio volumio[1076]: [SpotifyConnect] Stopping currently active service May 03 07:06:49 volumio volumio[1076]: info: CoreCommandRouter::volumioStop May 03 07:06:49 volumio volumio[1076]: info: CoreStateMachine::stop May 03 07:06:49 volumio volumio[1076]: info: CoreStateMachine::serviceStop May 03 07:06:49 volumio volumio[1076]: info: CoreCommandRouter::serviceStop May 03 07:06:49 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 07:06:49 volumio volumio[1076]: TypeError: Cannot read property 'then' of undefined May 03 07:06:49 volumio volumio[1076]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 03 07:06:49 volumio volumio[1076]: at emitNone (events.js:106:13) May 03 07:06:49 volumio volumio[1076]: at SpotConnEvents.emit (events.js:208:7) May 03 07:06:49 volumio volumio[1076]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 03 07:06:49 volumio volumio[1076]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 03 07:06:49 volumio volumio[1076]: at emitTwo (events.js:126:13) May 03 07:06:49 volumio volumio[1076]: at Socket.emit (events.js:214:7) May 03 07:06:49 volumio volumio[1076]: at UDP.onMessage [as onmessage] (dgram.js:659:8) May 03 07:06:49 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : No autoplay_uri found May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : AutoplayError: MercuryError May 03 07:06:49 volumio vtcs[1430]: [2025-05-03 07:06:49] [info] asio async_shutdown error: asio.misc:2 (End of file) May 03 07:06:49 volumio vtcs[1430]: [2025-05-03 07:06:49.186] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQB8Zjv3TuxuexRLQfZCZp6iW6MDgq7LOj3APykxL8A6Bve4R7KjECSQ_zN6QTg69Iv_uC5e8-9y0Z6yokC9M3VpuL1PX9Lb2H1AbORieogp5L2tWFMMFeWh6067mQUdOvofwsiqGkSrsIi3wrGV2vb4f2ihO9pmU7qdQ-Hk3K-Fm-gFOXLZgnEcjwFdd86YjDbtECsEtZOXjYWuQGhl35aZm6knJ0OL49vBfc_g8Hz5zQs", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 03 07:06:49 volumio volumio[1176]: [Vollibrespot] : Loading <迷失藝術> with Spotify URI May 03 07:06:49 volumio sudo[19297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-03 07:05 May 03 07:06:49 volumio sudo[19297]: 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"