-- Logs begin at Sat 2024-07-13 22:19:20 HKT, end at Sat 2024-07-13 23:04:51 HKT. -- Jul 13 23:03:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 23:03:00 volumio volumio[932]: info: Received Get System Info Jul 13 23:03:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 23:03:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 23:03:00 volumio volumio[932]: info: Discovery: Getting this device information Jul 13 23:03:00 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:03:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 23:03:02 volumio volumio[932]: info: CoreCommandRouter::volumioGetQueue Jul 13 23:03:02 volumio volumio[932]: info: CoreStateMachine::getQueue Jul 13 23:03:02 volumio volumio[932]: info: CorePlayQueue::getQueue Jul 13 23:03:07 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 13 23:03:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 23:03:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 13 23:03:17 volumio volumio[932]: info: [yt-cast-receiver] Player.reset() Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 23:03:17 volumio volumio[932]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 13 23:03:17 volumio volumio[932]: info: Received Get System Version Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 23:03:17 volumio volumio[932]: info: Received Get System Info Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 23:03:17 volumio volumio[932]: info: Discovery: Getting this device information Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:03:17 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 23:03:17 volumio volumio[932]: verbose: UNSET VOLATILE: Service: spop Jul 13 23:03:17 volumio volumio[932]: [SpotifyConnect] unSetVolatile called Jul 13 23:03:17 volumio volumio[932]: info: Spotify Unset Volatile called Jul 13 23:03:17 volumio volumio[932]: [SpotifyConnect] Relinquishing Volumio State to another service Jul 13 23:03:17 volumio volumio[932]: [SpotifyConnect] Spotify Received stop Jul 13 23:03:17 volumio volumio[932]: info: Disabling plugin ytcr Jul 13 23:03:17 volumio volumio[932]: info: Done. Jul 13 23:03:17 volumio volumio[3689]: [Vollibrespot] : Pause Jul 13 23:03:17 volumio volumio[3689]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track }, position_ms: 114361 } Jul 13 23:03:17 volumio volumio[932]: [SpotifyConnect] Pause Jul 13 23:03:17 volumio volumio[932]: [SpotifyConnect] pause Jul 13 23:03:18 volumio volumio[932]: [SpotifyConnect] Out of the blue Jul 13 23:03:18 volumio volumio[3689]: [Vollibrespot] : Event: SinkInactive Jul 13 23:03:18 volumio volumio[3689]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:03:18 volumio volumio[932]: [SpotifyConnect] PlaybackInactive Jul 13 23:03:18 volumio volumio[932]: info: [1720882998349] ControllerSpotify::pushState Jul 13 23:03:18 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:03:18 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:03:18 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:03:18 volumio volumio[932]: info: Is Not Connect Playback Jul 13 23:03:18 volumio volumio[932]: info: [1720882998894] ControllerSpotify::pushState Jul 13 23:03:18 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:03:18 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:03:18 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:03:28 volumio ntpd[1407]: Soliciting pool server 2606:4700:f1::1 Jul 13 23:03:49 volumio volumio[3689]: [Vollibrespot] : Event: SessionInactive { became_inactive_at: 1720883029627 } Jul 13 23:03:49 volumio volumio[3689]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:03:49 volumio volumio[932]: [SpotifyConnect] Connect Session has ended Jul 13 23:03:49 volumio volumio[932]: [SpotifyConnect] PlaybackInactive Jul 13 23:03:49 volumio volumio[932]: info: [1720883029630] ControllerSpotify::pushState Jul 13 23:03:49 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:03:49 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:03:49 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:04:07 volumio volumio[932]: verbose: New Socket.io Connection to 192.168.1.190 from 192.168.1.123 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Jul 13 23:04:07 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:04:07 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:07 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 13 23:04:07 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 13 23:04:08 volumio volumio[932]: verbose: New Socket.io Connection to 192.168.1.190 from 192.168.1.123 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetVisibleSources Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:04:08 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 13 23:04:08 volumio volumio[932]: info: Received Get System Info Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 23:04:08 volumio volumio[932]: info: Discovery: Getting this device information Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:04:08 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:04:08 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:08 volumio volumio[932]: info: Listing playlists Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::volumioGetQueue Jul 13 23:04:08 volumio volumio[932]: info: CoreStateMachine::getQueue Jul 13 23:04:08 volumio volumio[932]: info: CorePlayQueue::getQueue Jul 13 23:04:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 13 23:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 23:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 23:04:12 volumio volumio[932]: info: Discovery: Getting this device information Jul 13 23:04:12 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 13 23:04:12 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 23:04:16 volumio volumio[932]: info: Starting Uninstall of plugin music_service - ytcr Jul 13 23:04:16 volumio volumio[932]: info: Uninstalling plugin ytcr Jul 13 23:04:16 volumio volumio[932]: info: Disabling plugin ytcr Jul 13 23:04:16 volumio volumio[932]: info: Checking if uninstall.sh is present Jul 13 23:04:16 volumio volumio[932]: info: Executing uninstall.sh Jul 13 23:04:16 volumio sudo[3845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/ytcr/uninstall.sh Jul 13 23:04:16 volumio sudo[3845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:04:16 volumio sudo[3845]: pam_unix(sudo:session): session closed for user root Jul 13 23:04:16 volumio volumio[932]: info: Uninstall script completed Jul 13 23:04:16 volumio volumio[932]: info: Removing plugin ytcr from configuration Jul 13 23:04:17 volumio volumio[932]: info: Successfully removed ytcr configuration files Jul 13 23:04:17 volumio volumio[932]: info: Plugin folders cleanup Jul 13 23:04:17 volumio volumio[932]: info: Scanning into folder /volumio/app/plugins/ Jul 13 23:04:17 volumio volumio[932]: info: Scanning category audio_interface Jul 13 23:04:17 volumio volumio[932]: info: Scanning category miscellanea Jul 13 23:04:17 volumio volumio[932]: info: Scanning category music_service Jul 13 23:04:17 volumio volumio[932]: info: Scanning category plugins.json Jul 13 23:04:17 volumio volumio[932]: info: Scanning category system_controller Jul 13 23:04:17 volumio volumio[932]: info: Scanning category user_interface Jul 13 23:04:17 volumio volumio[932]: info: Scanning into folder /data/plugins/ Jul 13 23:04:17 volumio volumio[932]: info: Scanning category music_service Jul 13 23:04:17 volumio volumio[932]: info: Cleaning folder for ytcr Jul 13 23:04:20 volumio volumio[932]: info: Plugin folders cleanup completed Jul 13 23:04:20 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 23:04:20 volumio volumio[932]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"3.0.9","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] Jul 13 23:04:20 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 13 23:04:28 volumio volumio[932]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 13 23:04:28 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 23:04:28 volumio volumio[932]: [SpotifyConnect] Closing metadata listener Jul 13 23:04:28 volumio volumio[932]: info: Disabling plugin spop Jul 13 23:04:28 volumio volumio[932]: info: Done. Jul 13 23:04:28 volumio sudo[3853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Jul 13 23:04:28 volumio sudo[3853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:04:28 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jul 13 23:04:28 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jul 13 23:04:28 volumio systemd[1]: volspotconnect.service: Succeeded. Jul 13 23:04:28 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jul 13 23:04:28 volumio sudo[3853]: pam_unix(sudo:session): session closed for user root Jul 13 23:04:28 volumio volumio[932]: [SpotifyConnect] Vollibrespot Daemon service stoped! Jul 13 23:04:29 volumio volumio[932]: info: Enabling plugin spop Jul 13 23:04:29 volumio volumio[932]: info: Loading plugin "spop"... Jul 13 23:04:29 volumio volumio[932]: info: PLUGIN START: spop Jul 13 23:04:29 volumio volumio[932]: info: Checking for old spotify connect plugin installed Jul 13 23:04:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 13 23:04:29 volumio volumio[932]: [SpotifyConnect] Creating VLS config file Jul 13 23:04:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:04:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:04:29 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:04:29 volumio volumio[932]: [SpotifyConnect] Starting metadata listener Jul 13 23:04:30 volumio sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 13 23:04:30 volumio sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:04:30 volumio volumio[932]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 13 23:04:30 volumio systemd[1]: Started Volspotconnect2 Daemon. Jul 13 23:04:30 volumio sudo[3857]: pam_unix(sudo:session): session closed for user root Jul 13 23:04:30 volumio volumio[932]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 13 23:04:30 volumio volumio[932]: [Metrics] SpotifyConnect: 0s 158.03ms Jul 13 23:04:30 volumio volumio[3859]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jul 13 23:04:30 volumio volumio[3859]: Reading Config from "/tmp/volspotify.toml" Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Using Alsa backend with device: volumio Jul 13 23:04:30 volumio volumio[932]: info: Done. Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Authenticated as "22aajkmp2g5nsgulkxrl6g5bi" ! Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Using alsa sink Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Metadata pipe established Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Country: "HK" Jul 13 23:04:30 volumio volumio[3859]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Jul 13 23:04:30 volumio volumio[932]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Jul 13 23:04:33 volumio ntpd[1407]: Soliciting pool server 2603:1040:200:3::b6 Jul 13 23:04:34 volumio volumio[932]: info: Checking Spotify Web API Jul 13 23:04:34 volumio volumio[3859]: [Vollibrespot] : ReqToken Jul 13 23:04:34 volumio volumio[932]: info: New Spotify Access Token Received Jul 13 23:04:34 volumio volumio[932]: info: Initializing Spotify Web API Jul 13 23:04:34 volumio volumio[932]: info: Initliazing Spotify Browsing Facility Jul 13 23:04:34 volumio volumio[932]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 23:04:34 volumio volumio[932]: info: [1720883074315] CoreMusicLibrary::Adding element Spotify Jul 13 23:04:34 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 23:04:34 volumio volumio[932]: Cannot find translation for source Spotify Jul 13 23:04:34 volumio volumio[932]: info: Idenfitied this device as Connect device: c6e56cf6b68c07041768b06e2f29a439e7724b0e Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Fetching autoplay context uri Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Event: SessionActive { became_active_at: 1720883083109 } Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : SessionActive! Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:04:43 volumio volumio[932]: [SpotifyConnect] A connect session has begun Jul 13 23:04:43 volumio volumio[932]: [SpotifyConnect] Device palyback is active! Jul 13 23:04:43 volumio volumio[932]: [SpotifyConnect] Device palyback is loading Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : error 400 for uri hm://autoplay-enabled/query?uri=spotify:search:out+of+the+blue Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : AutoplayError: MercuryError Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCmuwu35vvsTEEqxSvTNbdBK16k5JfLTn65qwcUYwn7107_frk4rKtDT9iY_LeBmzvOt9ken9YvhMzWaFXbcDiav1ONKsD7GlpESaWrHkrWqMGIJ-LYYI8jd_rWMkLwsnzqXunGs4y_itm-Otwk-yaZjFcE16aJbC65iOl4jajelH3NiKOppQBX4MjkCPHEh3wKhhTMjJOkXLn4nB-_bTwK-A", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jul 13 23:04:43 volumio volumio[932]: error: Received malformed Token, ignoring Jul 13 23:04:43 volumio volumio[3859]: [Vollibrespot] : Loading with Spotify URI Jul 13 23:04:44 volumio volumio[3859]: [Vollibrespot] : (206978 ms) loaded Jul 13 23:04:44 volumio volumio[3859]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:04:44 volumio volumio[932]: [SpotifyConnect] PlaybackInactive Jul 13 23:04:44 volumio volumio[932]: info: [1720883084193] ControllerSpotify::pushState Jul 13 23:04:44 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:04:44 volumio volumio[932]: info: CoreStateMachine::pushState Jul 13 23:04:44 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:44 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:04:44 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 13 23:04:44 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:44 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:04:49 volumio volumio[3859]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track } } Jul 13 23:04:49 volumio volumio[932]: [SpotifyConnect] PlaybackInactive Jul 13 23:04:49 volumio volumio[932]: info: [1720883089299] ControllerSpotify::pushState Jul 13 23:04:49 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:04:49 volumio volumio[932]: info: CoreStateMachine::pushState Jul 13 23:04:49 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:49 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 13 23:04:49 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:49 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:04:50 volumio volumio[3859]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 229450618156643588585215578171828932647, audio_type: Track }, position_ms: 0 } Jul 13 23:04:50 volumio volumio[932]: [SpotifyConnect] play Jul 13 23:04:51 volumio volumio[3859]: [Vollibrespot] : Event: SinkActive Jul 13 23:04:51 volumio volumio[932]: [SpotifyConnect] Out of the blue Jul 13 23:04:51 volumio volumio[932]: info: [1720883091067] ControllerSpotify::pushState Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::pushState Jul 13 23:04:51 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 13 23:04:51 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:51 volumio volumio[932]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received spop Jul 13 23:04:51 volumio volumio[932]: [SpotifyConnect] Sink acquired Jul 13 23:04:51 volumio volumio[932]: info: Acquiring new spotify session Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::volumioStop Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::stop Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock Jul 13 23:04:51 volumio volumio[932]: info: CorePlayQueue::getTrackBlock Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::pushState Jul 13 23:04:51 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 13 23:04:51 volumio volumio[932]: info: CoreStateMachine::serviceStop Jul 13 23:04:51 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 13 23:04:51 volumio volumio[932]: info: CoreCommandRouter::serviceStop Jul 13 23:04:51 volumio volumio[932]: error: WARNING: No stop method for service youtube2 Jul 13 23:04:51 volumio volumio[932]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 23:04:51 volumio volumio[932]: TypeError: Cannot read property 'then' of undefined Jul 13 23:04:51 volumio volumio[932]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2156:31) Jul 13 23:04:51 volumio volumio[932]: at SpotConnEvents.emit (events.js:400:28) Jul 13 23:04:51 volumio volumio[932]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:100:16) Jul 13 23:04:51 volumio volumio[932]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Jul 13 23:04:51 volumio volumio[932]: at Socket.emit (events.js:400:28) Jul 13 23:04:51 volumio volumio[932]: at UDP.onMessage [as onmessage] (dgram.js:931:8) Jul 13 23:04:51 volumio volumio[932]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 23:04:51 volumio sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 23:03 Jul 13 23:04:51 volumio sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"