-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-05-04 12:28:18 CEST. -- May 04 12:27:00 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:02 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:03 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:04 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:05 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState May 04 12:27:07 volumio volumio[879]: info: CoreStateMachine::pushState May 04 12:27:07 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 12:27:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState May 04 12:27:08 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:09 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:10 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:11 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:12 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:12 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:14 volumio vtcs[1383]: [2024-05-04 12:27:14] [info] asio async_shutdown error: asio.misc:2 (End of file) May 04 12:27:14 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:15 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:16 volumio volumio[879]: info: Pushing metadata May 04 12:27:16 volumio volumio[879]: info: CoreCommandRouter::servicePushState May 04 12:27:16 volumio volumio[879]: info: CoreStateMachine::pushState May 04 12:27:16 volumio volumio[879]: info: CoreCommandRouter::volumioPushState May 04 12:27:16 volumio volumio[879]: info: CoreCommandRouter::servicePushState May 04 12:27:16 volumio volumio[879]: info: CoreStateMachine::pushState May 04 12:27:16 volumio volumio[879]: info: CoreCommandRouter::volumioPushState May 04 12:27:16 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:17 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:18 volumio vtcs[1383]: [2024-05-04 12:27:18] [info] asio async_shutdown error: asio.misc:2 (End of file) May 04 12:27:18 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:19 volumio volumio[879]: info: Pushing metadata May 04 12:27:19 volumio volumio[879]: info: CoreCommandRouter::servicePushState May 04 12:27:19 volumio volumio[879]: info: CoreStateMachine::pushState May 04 12:27:19 volumio volumio[879]: info: CoreCommandRouter::volumioPushState May 04 12:27:19 volumio volumio[879]: info: CoreCommandRouter::servicePushState May 04 12:27:19 volumio volumio[879]: info: CoreStateMachine::pushState May 04 12:27:19 volumio volumio[879]: info: CoreCommandRouter::volumioPushState May 04 12:27:19 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:20 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:20 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:21 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:22 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:23 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:24 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:25 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:26 volumio volumio[1452]: [Vollibrespot] : couldn't parse packet from 192.168.32.173:5353: type 47 is invalid May 04 12:27:26 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:27 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:27 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:28 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:30 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:31 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:32 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:33 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:35 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:36 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:37 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:38 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:39 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:40 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:41 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:42 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:42 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:43 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:44 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:45 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:46 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:47 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:48 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:49 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:50 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:50 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:51 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:52 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:53 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:54 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:55 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:55 volumio volumio[1452]: [Vollibrespot] : couldn't parse packet from 192.168.32.173:5353: type 47 is invalid May 04 12:27:56 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:57 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:27:58 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:27:59 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:00 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:02 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:03 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:05 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:05 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:28:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:08 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:09 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:10 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:10 volumio vtcs[1383]: [2024-05-04 12:28:10] [info] asio async_shutdown error: asio.misc:2 (End of file) May 04 12:28:11 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:12 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:13 volumio wpa_supplicant[695]: wlan0: Failed to initiate sched scan May 04 12:28:14 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:15 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:16 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:17 volumio volumio[879]: info: CoreCommandRouter::volumioGetState May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714818497816 } May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : SessionActive! May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Fetching autoplay context uri May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 70660927984914882909437816798594167312, audio_type: Track } } May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 70660927984914882909437816798594167312, audio_type: Track } } May 04 12:28:17 volumio volumio[879]: [SpotifyConnect] A connect session has begun May 04 12:28:17 volumio volumio[879]: info: Acquiring new spotify session May 04 12:28:17 volumio volumio[879]: info: CoreCommandRouter::volumioStop May 04 12:28:17 volumio volumio[879]: info: CoreStateMachine::stop May 04 12:28:17 volumio volumio[879]: info: CoreStateMachine::serviceStop May 04 12:28:17 volumio volumio[879]: info: CoreCommandRouter::serviceStop May 04 12:28:17 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 12:28:17 volumio volumio[879]: TypeError: Cannot read property 'then' of undefined May 04 12:28:17 volumio volumio[879]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2111:31) May 04 12:28:17 volumio volumio[879]: at SpotConnEvents.emit (events.js:315:20) May 04 12:28:17 volumio volumio[879]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) May 04 12:28:17 volumio volumio[879]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) May 04 12:28:17 volumio volumio[879]: at Socket.emit (events.js:315:20) May 04 12:28:17 volumio volumio[879]: at UDP.onMessage [as onmessage] (dgram.js:919:8) May 04 12:28:17 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 12:28:17 volumio vtcs[1383]: [2024-05-04 12:28:17.838] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:4Q281GM63zCOemG5de6OKU"> May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Resolving uri "spotify:station:album:4Q281GM63zCOemG5de6OKU" May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Loading with Spotify URI May 04 12:28:17 volumio volumio[1452]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBcdja8PyBJq3fQqHyLPoPxpoeid-R9ITmB0Ee7Fuy4ukn-YsB09qa3cI9iqQdLnxBvQl13jAOgpp_OGxxlyXQS6RxXPiCGLdxK3UvjquLVXfss113PRB10NV-n6cf_6TBCqVDWbgX4L_pqN9AfjM5_ZCjN3kLY3ZPiTnGKyaOwsv-bgisj1_JMFFgbTR85g6kXYeGEYFrKpIKFF0GOYOo", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 04 12:28:18 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 12:27 May 04 12:28:18 volumio sudo[1509]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"