-- Logs begin at Wed 2024-07-24 14:52:06 UTC, end at Wed 2024-07-24 14:58:20 UTC. -- Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::servicePushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreStateMachine::pushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::volumioPushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::servicePushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreStateMachine::pushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::volumioPushState Jul 24 14:57:00 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 14:57:02 cola-volumio volumio[753]: info: Volume: {"mute":false} Jul 24 14:57:02 cola-volumio volumio[753]: info: VolumeController::SetAlsaVolume20 Jul 24 14:57:02 cola-volumio volumio[753]: info: CoreStateMachine::pushState Jul 24 14:57:02 cola-volumio volumio[753]: info: CoreCommandRouter::volumioPushState Jul 24 14:57:02 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 24 14:57:10 cola-volumio volumio[753]: info: Discovery: Getting this device information Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetState Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 24 14:57:10 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 24 14:57:11 cola-volumio vtcs[1181]: [2024-07-24 14:57:11] [info] asio async_shutdown error: asio.misc:2 (End of file) Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetState Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 14:57:12 cola-volumio volumio[753]: info: Listing playlists Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreStateMachine::getQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CorePlayQueue::getQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetState Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 14:57:12 cola-volumio volumio[753]: info: Listing playlists Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreStateMachine::getQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CorePlayQueue::getQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetState Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 24 14:57:12 cola-volumio volumio[753]: info: Listing playlists Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetVisibleSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CoreStateMachine::getQueue Jul 24 14:57:12 cola-volumio volumio[753]: info: CorePlayQueue::getQueue Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: info: Retrieving Cloud Streaming UI Jul 24 14:57:27 cola-volumio volumio[753]: info: Getting Tidal Cloud Configuration Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: info: Getting Qobuz Cloud Configuration Jul 24 14:57:27 cola-volumio volumio[753]: info: Asking plugin for UI Config Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: info: Getting Spotify Cloud Configuration Jul 24 14:57:27 cola-volumio volumio[753]: info: Asking plugin for UI Config Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: info: Saving Spotify Acccount Jul 24 14:57:27 cola-volumio volumio[753]: info: Got it Jul 24 14:57:27 cola-volumio volumio[753]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 24 14:57:27 cola-volumio volumio[753]: info: Got Tidal Cloud Configuration Jul 24 14:57:27 cola-volumio volumio[753]: info: Got it Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 14:57:27 cola-volumio volumio[753]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:27 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 14:57:31 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:35 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:39 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:43 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:47 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:49 cola-volumio volumio[753]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jul 24 14:57:49 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jul 24 14:57:49 cola-volumio volumio[753]: info: Saving configuration Jul 24 14:57:49 cola-volumio volumio[753]: info: Done Jul 24 14:57:51 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:52 cola-volumio volumio[753]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jul 24 14:57:52 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jul 24 14:57:52 cola-volumio volumio[753]: info: Saving configuration Jul 24 14:57:52 cola-volumio volumio[753]: info: Done Jul 24 14:57:55 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:57:59 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:58:00 cola-volumio volumio[753]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Jul 24 14:58:00 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Jul 24 14:58:00 cola-volumio volumio[753]: info: CoreCommandRouter::volumioUpdateToBrowseSources Jul 24 14:58:00 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 24 14:58:03 cola-volumio volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Authenticated as "6jroekhnut5gi4yuei8thi6yg" ! Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Using alsa sink Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Metadata pipe established Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Country: "HK" Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Event: Volume { volume_to_mixer: 39321 } Jul 24 14:58:19 cola-volumio volumio[753]: [SpotifyConnect] Volume Spotify: 60 Volumio: 60 Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Fetching autoplay context uri Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Event: SessionActive { became_active_at: 1721833099674 } Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : SessionActive! Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 335412441183802724086712261791976946680, audio_type: Track } } Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 335412441183802724086712261791976946680, audio_type: Track } } Jul 24 14:58:19 cola-volumio volumio[753]: [SpotifyConnect] A connect session has begun Jul 24 14:58:19 cola-volumio volumio[753]: info: Acquiring new spotify session Jul 24 14:58:19 cola-volumio volumio[753]: info: CoreCommandRouter::volumioStop Jul 24 14:58:19 cola-volumio volumio[753]: info: CoreStateMachine::stop Jul 24 14:58:19 cola-volumio volumio[753]: info: CoreStateMachine::serviceStop Jul 24 14:58:19 cola-volumio volumio[753]: info: CoreCommandRouter::serviceStop Jul 24 14:58:19 cola-volumio volumio[753]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 14:58:19 cola-volumio volumio[753]: TypeError: Cannot read property 'then' of undefined Jul 24 14:58:19 cola-volumio volumio[753]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Jul 24 14:58:19 cola-volumio volumio[753]: at emitNone (events.js:106:13) Jul 24 14:58:19 cola-volumio volumio[753]: at SpotConnEvents.emit (events.js:208:7) Jul 24 14:58:19 cola-volumio volumio[753]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Jul 24 14:58:19 cola-volumio volumio[753]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Jul 24 14:58:19 cola-volumio volumio[753]: at emitTwo (events.js:126:13) Jul 24 14:58:19 cola-volumio volumio[753]: at Socket.emit (events.js:214:7) Jul 24 14:58:19 cola-volumio volumio[753]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jul 24 14:58:19 cola-volumio volumio[753]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAQtMXRZfG0fhZ4BEi3SQFFh-wGGcR4ceboS3CqqGw2DMz3-99HKOEqso-qCjXHkQZ8yYTwiecQQBN2V3Pzpcpx5mLDgE0wcDCssrKkgycJCerooAymd1JB09KWbNmcoVoFvl2kjWiDwHi6r_3-iA5nhw003Al0EtudM2NXpRhC0hwT0rCllT8RwKIeXAWM7D2S0-ey3Gg_tiVb2agC-ed4sA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jul 24 14:58:19 cola-volumio vtcs[1181]: [2024-07-24 14:58:19.717] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E35cE92Q1N1MT"> Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E35cE92Q1N1MT" Jul 24 14:58:19 cola-volumio volumio[1061]: [Vollibrespot] : Loading <命書> with Spotify URI Jul 24 14:58:20 cola-volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 14:57 Jul 24 14:58:20 cola-volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 20:14:38 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="odroidc2" VOLUMIO_HASH="62be85deec26efb2f51ffd48ff92058c"