-- Logs begin at Fri 2024-04-19 04:22:10 CDT, end at Sat 2024-04-20 08:26:37 CDT. -- Apr 20 08:25:07 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:07 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:07 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:10 volumio3 sudo[9060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:25:10 volumio3 sudo[9060]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:11 volumio3 sudo[9060]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:11 volumio3 sudo[9063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:25:11 volumio3 sudo[9063]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:11 volumio3 sudo[9063]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:11 volumio3 volumio[887]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 6 Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 08:25:11 volumio3 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 20 08:25:11 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 08:25:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:25:12 volumio3 sudo[9066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:25:12 volumio3 sudo[9066]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:12 volumio3 sudo[9066]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:12 volumio3 sudo[9069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:25:12 volumio3 sudo[9069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:12 volumio3 sudo[9069]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:12 volumio3 volumio[887]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 6 Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 08:25:12 volumio3 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 20 08:25:12 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 08:25:12 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:25:15 volumio3 volumio[887]: info: CoreCommandRouter::volumioPause Apr 20 08:25:15 volumio3 volumio[887]: info: CoreStateMachine::pause Apr 20 08:25:15 volumio3 volumio[887]: info: CoreStateMachine::stPlaybackTimer Apr 20 08:25:15 volumio3 volumio[887]: info: CoreStateMachine::servicePause Apr 20 08:25:15 volumio3 volumio[887]: info: CoreCommandRouter::servicePause Apr 20 08:25:15 volumio3 volumio[887]: info: Spotify Received pause Apr 20 08:25:16 volumio3 volumio[21259]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 103377 } Apr 20 08:25:16 volumio3 volumio[887]: [SpotifyConnect] pause Apr 20 08:25:16 volumio3 volumio[887]: info: [1713619516101] ControllerSpotify::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:16 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:25:16 volumio3 volumio[887]: info: [1713619516677] ControllerSpotify::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:16 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkInactive Apr 20 08:25:16 volumio3 volumio[21259]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track } } Apr 20 08:25:16 volumio3 volumio[887]: [SpotifyConnect] PlaybackInactive Apr 20 08:25:16 volumio3 volumio[887]: info: [1713619516724] ControllerSpotify::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:16 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:17 volumio3 volumio[887]: info: CoreCommandRouter::volumioVolatilePlay Apr 20 08:25:17 volumio3 volumio[887]: info: CoreStateMachine::volatilePlay Apr 20 08:25:17 volumio3 volumio[887]: info: Spotify Play Apr 20 08:25:17 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:18 volumio3 volumio[21259]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 103508 } Apr 20 08:25:18 volumio3 volumio[887]: [SpotifyConnect] play Apr 20 08:25:18 volumio3 volumio[887]: info: [1713619518025] ControllerSpotify::pushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:18 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkActive Apr 20 08:25:18 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:25:18 volumio3 volumio[887]: info: [1713619518599] ControllerSpotify::pushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:18 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:18 volumio3 volumio[887]: [SpotifyConnect] Sink acquired Apr 20 08:25:18 volumio3 volumio[887]: info: Continuing Spotify Session Apr 20 08:25:18 volumio3 volumio[887]: info: Checking Spotify Web API Apr 20 08:25:25 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 08:25:25 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 08:25:27 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:27 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:27 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:25:30 volumio3 volumio[887]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 08:25:30 volumio3 volumio[887]: info: Received Get System Version Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 08:25:30 volumio3 volumio[887]: info: Received Get System Info Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 08:25:30 volumio3 volumio[887]: info: Discovery: Getting this device information Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:30 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 08:25:37 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:47 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:47 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:48 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Apr 20 08:25:48 volumio3 volumio[887]: error: MRS: impossible to set browserPlayback volume: device not found Apr 20 08:25:53 volumio3 sudo[9119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 08:25:53 volumio3 sudo[9119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:53 volumio3 sudo[9119]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:53 volumio3 sudo[9122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 08:25:53 volumio3 sudo[9122]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 08:25:53 volumio3 sudo[9122]: pam_unix(sudo:session): session closed for user root Apr 20 08:25:53 volumio3 volumio[887]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Mobile/15E148 Safari/604.1 Total Clients: 6 Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 08:25:53 volumio3 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 20 08:25:53 volumio3 volumio[887]: info: Listing playlists Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 08:25:53 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:25:55 volumio3 volumio[887]: info: CoreCommandRouter::volumioRandom Apr 20 08:25:55 volumio3 volumio[887]: info: CoreCommandRouter::writePlayerControls Apr 20 08:25:55 volumio3 volumio[887]: info: CoreStateMachine::setRandom true Apr 20 08:25:55 volumio3 volumio[887]: info: Spotify Random: true Apr 20 08:25:55 volumio3 volumio[21259]: [Vollibrespot] : Event: Shuffle { status: true } Apr 20 08:25:55 volumio3 volumio[887]: info: [1713619555809] ControllerSpotify::pushState Apr 20 08:25:55 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:55 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:55 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:55 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:56 volumio3 volumio[887]: info: CoreCommandRouter::volumioRandom Apr 20 08:25:56 volumio3 volumio[887]: info: CoreCommandRouter::writePlayerControls Apr 20 08:25:56 volumio3 volumio[887]: info: CoreStateMachine::setRandom false Apr 20 08:25:56 volumio3 volumio[887]: info: Spotify Random: false Apr 20 08:25:57 volumio3 volumio[21259]: [Vollibrespot] : Attempting to resolve page "hm://context-resolve/v1/" Apr 20 08:25:57 volumio3 volumio[21259]: [Vollibrespot] : Event: Shuffle { status: false } Apr 20 08:25:57 volumio3 volumio[887]: info: [1713619557194] ControllerSpotify::pushState Apr 20 08:25:57 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:25:57 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:25:57 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:25:57 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:25:57 volumio3 volumio[21259]: [Vollibrespot] : error 404 for uri hm://context-resolve/v1/ Apr 20 08:25:57 volumio3 volumio[21259]: [Vollibrespot] : ContextPollError: MercuryError Apr 20 08:25:57 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:03 volumio3 volumio[887]: info: Apr 20 08:26:03 volumio3 volumio[887]: ---------------------------- Client requests Volumio pause Apr 20 08:26:03 volumio3 volumio[887]: info: CoreCommandRouter::volumioPause Apr 20 08:26:03 volumio3 volumio[887]: info: CoreStateMachine::pause Apr 20 08:26:03 volumio3 volumio[887]: info: CoreStateMachine::stPlaybackTimer Apr 20 08:26:03 volumio3 volumio[887]: info: CoreStateMachine::servicePause Apr 20 08:26:03 volumio3 volumio[887]: info: CoreCommandRouter::servicePause Apr 20 08:26:03 volumio3 volumio[887]: info: Spotify Received pause Apr 20 08:26:03 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:03 volumio3 volumio[887]: info: Listing playlists Apr 20 08:26:03 volumio3 volumio[887]: info: Listing playlists Apr 20 08:26:03 volumio3 volumio[21259]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 149489 } Apr 20 08:26:03 volumio3 volumio[887]: [SpotifyConnect] pause Apr 20 08:26:04 volumio3 volumio[887]: info: [1713619564004] ControllerSpotify::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:04 volumio3 volumio[887]: info: Apr 20 08:26:04 volumio3 volumio[887]: ---------------------------- Client requests Volumio pause Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::volumioPause Apr 20 08:26:04 volumio3 volumio[887]: info: CoreStateMachine::pause Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:04 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkInactive Apr 20 08:26:04 volumio3 volumio[21259]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track } } Apr 20 08:26:04 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:26:04 volumio3 volumio[887]: info: [1713619564581] ControllerSpotify::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:04 volumio3 volumio[887]: [SpotifyConnect] PlaybackInactive Apr 20 08:26:04 volumio3 volumio[887]: info: [1713619564582] ControllerSpotify::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:04 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:05 volumio3 volumio[887]: info: Apr 20 08:26:05 volumio3 volumio[887]: ---------------------------- Client requests Volumio play Apr 20 08:26:05 volumio3 volumio[887]: info: CoreCommandRouter::volumioPlay Apr 20 08:26:05 volumio3 volumio[887]: verbose: UNSET VOLATILE: Service: spop Apr 20 08:26:05 volumio3 volumio[887]: [SpotifyConnect] unSetVolatile called Apr 20 08:26:05 volumio3 volumio[887]: info: Spotify Unset Volatile called Apr 20 08:26:05 volumio3 volumio[887]: [SpotifyConnect] Relinquishing Volumio State to another service Apr 20 08:26:05 volumio3 volumio[887]: [SpotifyConnect] Spotify Received stop Apr 20 08:26:05 volumio3 volumio[887]: info: CoreStateMachine::play index undefined Apr 20 08:26:05 volumio3 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 08:26:05 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:05 volumio3 volumio[887]: info: CoreStateMachine::startPlaybackTimer Apr 20 08:26:05 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:05 volumio3 volumio[887]: info: Spotify Resume Apr 20 08:26:05 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:05 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:05 volumio3 volumio[21259]: [Vollibrespot] : Pause Apr 20 08:26:05 volumio3 volumio[887]: [SpotifyConnect] Pause Apr 20 08:26:06 volumio3 volumio[21259]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 149768 } Apr 20 08:26:06 volumio3 volumio[887]: [SpotifyConnect] play Apr 20 08:26:06 volumio3 volumio[887]: info: [1713619566102] ControllerSpotify::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:06 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:06 volumio3 volumio[887]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version]","artist":"United Guitar Players","album":"Popular Hits On Spanish Acoustic Guitar, Vol. 3","albumart":"https://i.scdn.co/image/ab67616d0000b2738fb128696b770262eb3e8383","uri":"spotify:track:5gawamF0uxE0z4SGPscdzh","trackType":"spotify","seek":149489,"duration":211,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"codec":"","repeatMode":"all","random":false} Apr 20 08:26:06 volumio3 volumio[887]: verbose: CURRENT POSITION 0 Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::syncState stateService play Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::syncState currentStatus pause Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:06 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkActive Apr 20 08:26:06 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:26:06 volumio3 volumio[887]: info: [1713619566682] ControllerSpotify::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:06 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:06 volumio3 volumio[887]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version]","artist":"United Guitar Players","album":"Popular Hits On Spanish Acoustic Guitar, Vol. 3","albumart":"https://i.scdn.co/image/ab67616d0000b2738fb128696b770262eb3e8383","uri":"spotify:track:5gawamF0uxE0z4SGPscdzh","trackType":"spotify","seek":149768,"duration":211,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"codec":"","repeatMode":"all","random":false} Apr 20 08:26:06 volumio3 volumio[887]: verbose: CURRENT POSITION 0 Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::syncState stateService play Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::syncState currentStatus play Apr 20 08:26:06 volumio3 volumio[887]: info: Received an update from plugin. extracting info from payload Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:06 volumio3 volumio[887]: [SpotifyConnect] Sink acquired Apr 20 08:26:06 volumio3 volumio[887]: info: Continuing Spotify Session Apr 20 08:26:06 volumio3 volumio[887]: info: Checking Spotify Web API Apr 20 08:26:06 volumio3 volumio[887]: [SpotifyConnect] Vollibrespot Active Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 08:26:06 volumio3 volumio[887]: info: [1713619566684] ControllerSpotify::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:06 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:07 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:09 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 08:26:09 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 08:26:11 volumio3 volumio[887]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 08:26:11 volumio3 volumio[887]: info: Received Get System Version Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 08:26:11 volumio3 volumio[887]: info: Received Get System Info Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 08:26:11 volumio3 volumio[887]: info: Discovery: Getting this device information Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:11 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 08:26:17 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:27 volumio3 volumio[887]: info: CoreCommandRouter::volumioGetState Apr 20 08:26:27 volumio3 volumio[887]: info: Listing playlists Apr 20 08:26:27 volumio3 volumio[887]: info: Listing playlists Apr 20 08:26:31 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 20 08:26:31 volumio3 volumio[887]: info: Pausing Remote Device: http://192.168.1.61 Apr 20 08:26:31 volumio3 volumio[887]: info: Apr 20 08:26:31 volumio3 volumio[887]: ---------------------------- Client requests Volumio pause Apr 20 08:26:31 volumio3 volumio[887]: info: CoreCommandRouter::volumioPause Apr 20 08:26:31 volumio3 volumio[887]: info: CoreStateMachine::pause Apr 20 08:26:31 volumio3 volumio[887]: info: CoreStateMachine::stPlaybackTimer Apr 20 08:26:31 volumio3 volumio[887]: info: CoreStateMachine::servicePause Apr 20 08:26:31 volumio3 volumio[887]: info: CoreCommandRouter::servicePause Apr 20 08:26:31 volumio3 volumio[887]: info: Spotify Received pause Apr 20 08:26:31 volumio3 volumio[887]: info: Done pausing: Apr 20 08:26:32 volumio3 volumio[21259]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 175855 } Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] pause Apr 20 08:26:32 volumio3 volumio[887]: info: [1713619592196] ControllerSpotify::pushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 20 08:26:32 volumio3 volumio[887]: info: Playing Remote Device: http://192.168.1.61 Apr 20 08:26:32 volumio3 volumio[887]: info: Apr 20 08:26:32 volumio3 volumio[887]: ---------------------------- Client requests Volumio play Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::volumioPlay Apr 20 08:26:32 volumio3 volumio[887]: verbose: UNSET VOLATILE: Service: spop Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] unSetVolatile called Apr 20 08:26:32 volumio3 volumio[887]: info: Spotify Unset Volatile called Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] Relinquishing Volumio State to another service Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] Spotify Received stop Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::play index undefined Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 08:26:32 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::startPlaybackTimer Apr 20 08:26:32 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:32 volumio3 volumio[887]: info: Spotify Resume Apr 20 08:26:32 volumio3 volumio[887]: info: Done playing: Apr 20 08:26:32 volumio3 volumio[21259]: [Vollibrespot] : Pause Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] Pause Apr 20 08:26:32 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkInactive Apr 20 08:26:32 volumio3 volumio[21259]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track } } Apr 20 08:26:32 volumio3 volumio[887]: [SpotifyConnect] PlaybackInactive Apr 20 08:26:32 volumio3 volumio[887]: info: [1713619592855] ControllerSpotify::pushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:32 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:32 volumio3 volumio[887]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version]","artist":"United Guitar Players","album":"Popular Hits On Spanish Acoustic Guitar, Vol. 3","albumart":"https://i.scdn.co/image/ab67616d0000b2738fb128696b770262eb3e8383","uri":"spotify:track:5gawamF0uxE0z4SGPscdzh","trackType":"spotify","seek":175855,"duration":211,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"codec":"","repeatMode":"all","random":false} Apr 20 08:26:32 volumio3 volumio[887]: verbose: CURRENT POSITION 0 Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::syncState stateService pause Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::syncState currentStatus pause Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:32 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:32 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:32 volumio3 volumio[887]: info: CoreStateMachine::stPlaybackTimer Apr 20 08:26:33 volumio3 volumio[887]: info: [1713619593226] ControllerSpotify::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:33 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:33 volumio3 volumio[887]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version]","artist":"United Guitar Players","album":"Popular Hits On Spanish Acoustic Guitar, Vol. 3","albumart":"https://i.scdn.co/image/ab67616d0000b2738fb128696b770262eb3e8383","uri":"spotify:track:5gawamF0uxE0z4SGPscdzh","trackType":"spotify","seek":175855,"duration":211,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"codec":"","repeatMode":"all","random":false} Apr 20 08:26:33 volumio3 volumio[887]: verbose: CURRENT POSITION 0 Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::syncState stateService play Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::syncState currentStatus pause Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:33 volumio3 volumio[21259]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 229761548133863649916206078894841873471, audio_type: Track }, position_ms: 176274 } Apr 20 08:26:33 volumio3 volumio[887]: [SpotifyConnect] play Apr 20 08:26:33 volumio3 volumio[21259]: [Vollibrespot] : Event: SinkActive Apr 20 08:26:33 volumio3 volumio[887]: [SpotifyConnect] Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version] Apr 20 08:26:33 volumio3 volumio[887]: info: [1713619593891] ControllerSpotify::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:33 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:33 volumio3 volumio[887]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Paint It Black (In The Style Of The Rolling Stones) [Instrumental Version]","artist":"United Guitar Players","album":"Popular Hits On Spanish Acoustic Guitar, Vol. 3","albumart":"https://i.scdn.co/image/ab67616d0000b2738fb128696b770262eb3e8383","uri":"spotify:track:5gawamF0uxE0z4SGPscdzh","trackType":"spotify","seek":176274,"duration":211,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"codec":"","repeatMode":"all","random":false} Apr 20 08:26:33 volumio3 volumio[887]: verbose: CURRENT POSITION 0 Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::syncState stateService play Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::syncState currentStatus play Apr 20 08:26:33 volumio3 volumio[887]: info: Received an update from plugin. extracting info from payload Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CorePlayQueue::getTrack 0 Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:33 volumio3 volumio[887]: [SpotifyConnect] Sink acquired Apr 20 08:26:33 volumio3 volumio[887]: info: Continuing Spotify Session Apr 20 08:26:33 volumio3 volumio[887]: info: Checking Spotify Web API Apr 20 08:26:33 volumio3 volumio[887]: [SpotifyConnect] Vollibrespot Active Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 08:26:33 volumio3 volumio[887]: info: [1713619593892] ControllerSpotify::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::servicePushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreStateMachine::pushState Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 08:26:33 volumio3 volumio[887]: info: CoreCommandRouter::volumioPushState Apr 20 08:26:37 volumio3 volumio[887]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Apr 20 08:26:37 volumio3 volumio[887]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Apr 20 08:26:37 volumio3 volumio[887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 08:26:37 volumio3 volumio[887]: TypeError: Cannot read property 'then' of undefined Apr 20 08:26:37 volumio3 volumio[887]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:364:9) Apr 20 08:26:37 volumio3 volumio[887]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2248:30) Apr 20 08:26:37 volumio3 volumio[887]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Apr 20 08:26:37 volumio3 volumio[887]: at Socket.emit (events.js:400:28) Apr 20 08:26:37 volumio3 volumio[887]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 20 08:26:37 volumio3 volumio[887]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 20 08:26:37 volumio3 volumio[887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 08:26:37 volumio3 sudo[9180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 08:25 Apr 20 08:26:37 volumio3 sudo[9180]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"