-- Logs begin at Fri 2021-12-17 11:53:07 UTC, end at Thu 2026-05-28 15:49:17 UTC. -- May 28 15:48:28 volumio volumio[929]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track }, position_ms: 91148 } May 28 15:48:28 volumio volumio[843]: [SpotifyConnect] pause May 28 15:48:28 volumio volumio[843]: [SpotifyConnect] En voiture Simone May 28 15:48:28 volumio volumio[843]: info: [1779983308993] ControllerSpotify::pushState May 28 15:48:28 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:28 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:28 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:28 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:29 volumio sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:29 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:29 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root May 28 15:48:29 volumio volumio[929]: [Vollibrespot] : Event: SinkInactive May 28 15:48:29 volumio volumio[929]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:48:29 volumio volumio[843]: [SpotifyConnect] Sink released May 28 15:48:29 volumio volumio[843]: info: [1779983309470] ControllerSpotify::pushState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:29 volumio volumio[843]: info: CorePlayQueue::getTrack 0 May 28 15:48:29 volumio volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop May 28 15:48:29 volumio volumio[843]: [SpotifyConnect] Device palyback is inactive May 28 15:48:29 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:29 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:29 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root May 28 15:48:29 volumio volumio[929]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track }, position_ms: 91406 } May 28 15:48:29 volumio volumio[843]: [SpotifyConnect] play May 28 15:48:29 volumio volumio[843]: [SpotifyConnect] En voiture Simone May 28 15:48:29 volumio volumio[843]: info: [1779983309751] ControllerSpotify::pushState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:29 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:29 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:29 volumio volumio[929]: [Vollibrespot] : Event: SinkActive May 28 15:48:29 volumio volumio[843]: info: CorePlayQueue::getTrack 0 May 28 15:48:29 volumio volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop May 28 15:48:29 volumio volumio[843]: [SpotifyConnect] Sink acquired May 28 15:48:29 volumio volumio[843]: info: Continuing Spotify Session May 28 15:48:29 volumio volumio[843]: info: Checking Spotify Web API May 28 15:48:29 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:29 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:29 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root May 28 15:48:30 volumio volumio[929]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:48:30 volumio volumio[929]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track }, track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:48:30 volumio volumio[843]: [SpotifyConnect] En voiture Simone May 28 15:48:30 volumio volumio[843]: info: [1779983310778] ControllerSpotify::pushState May 28 15:48:30 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:30 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:30 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:30 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:30 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:30 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:30 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:30 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:30 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root May 28 15:48:31 volumio volumio[929]: [Vollibrespot] : Event: SinkInactive May 28 15:48:31 volumio volumio[843]: [SpotifyConnect] Sink released May 28 15:48:31 volumio volumio[929]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:48:31 volumio volumio[843]: info: [1779983311230] ControllerSpotify::pushState May 28 15:48:31 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:31 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:31 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:31 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:31 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:31 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:31 volumio volumio[843]: info: CorePlayQueue::getTrack 0 May 28 15:48:31 volumio volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop May 28 15:48:31 volumio volumio[843]: [SpotifyConnect] Device palyback is inactive May 28 15:48:31 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:31 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:31 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root May 28 15:48:32 volumio volumio[929]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track }, position_ms: 0 } May 28 15:48:32 volumio volumio[843]: [SpotifyConnect] play May 28 15:48:32 volumio volumio[929]: [Vollibrespot] : Event: SinkActive May 28 15:48:32 volumio volumio[843]: [SpotifyConnect] En voiture Simone May 28 15:48:32 volumio volumio[843]: info: [1779983312646] ControllerSpotify::pushState May 28 15:48:32 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:32 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:32 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:32 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:32 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:32 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:32 volumio volumio[843]: info: CorePlayQueue::getTrack 0 May 28 15:48:32 volumio volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop May 28 15:48:32 volumio volumio[843]: [SpotifyConnect] Sink acquired May 28 15:48:32 volumio volumio[843]: info: Continuing Spotify Session May 28 15:48:32 volumio volumio[843]: info: Checking Spotify Web API May 28 15:48:32 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:32 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:32 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root May 28 15:48:37 volumio volumio[843]: info: Airplay started streaming May 28 15:48:37 volumio volumio[843]: info: CoreCommandRouter::volumioStop May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::stop May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::serviceStop May 28 15:48:37 volumio volumio[843]: info: Received STOP, but no service to execute it May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 15:48:37 volumio volumio[843]: info: Airplay started streaming May 28 15:48:37 volumio volumio[843]: info: CoreCommandRouter::volumioStop May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::stop May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::serviceStop May 28 15:48:37 volumio volumio[843]: info: CoreCommandRouter::serviceStop May 28 15:48:37 volumio volumio[843]: error: WARNING: No stop method for service airplay May 28 15:48:37 volumio volumio[843]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 15:48:38 volumio volumio[929]: [Vollibrespot] : Event: SessionInactive { became_inactive_at: 1779983318085 } May 28 15:48:38 volumio volumio[843]: [SpotifyConnect] Connect Session has ended May 28 15:48:38 volumio volumio[929]: [Vollibrespot] : Event: SinkInactive May 28 15:48:38 volumio volumio[843]: [SpotifyConnect] Sink released May 28 15:48:38 volumio volumio[929]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:48:38 volumio volumio[843]: info: [1779983318622] ControllerSpotify::pushState May 28 15:48:38 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:38 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:38 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:38 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:38 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:38 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:38 volumio volumio[843]: info: CorePlayQueue::getTrack 0 May 28 15:48:38 volumio volumio[843]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received spop May 28 15:48:38 volumio volumio[843]: [SpotifyConnect] Device palyback is inactive May 28 15:48:38 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:38 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:38 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:48:39 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:48:39 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:48:39 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root May 28 15:48:39 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:48:39 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:48:39 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:13 volumio sudo[1475]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:13 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:13 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:13 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root May 28 15:49:13 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:13 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:13 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::servicePushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::syncState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::pushState May 28 15:49:14 volumio volumio[843]: info: CoreStateMachine::getState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::volumioPushState May 28 15:49:14 volumio volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:49:14 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root May 28 15:49:14 volumio sudo[1549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:49:14 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:49:14 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Event: SessionActive { became_active_at: 1779983356183 } May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : SessionActive! May 28 15:49:16 volumio volumio[843]: [SpotifyConnect] A connect session has begun May 28 15:49:16 volumio volumio[843]: info: Acquiring new spotify session May 28 15:49:16 volumio volumio[843]: info: CoreCommandRouter::volumioStop May 28 15:49:16 volumio volumio[843]: info: CoreStateMachine::stop May 28 15:49:16 volumio volumio[843]: info: CoreStateMachine::serviceStop May 28 15:49:16 volumio volumio[843]: info: CoreCommandRouter::serviceStop May 28 15:49:16 volumio volumio[843]: error: WARNING: No stop method for service airplay May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Fetching autoplay context uri May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 184364421560294831904992294852114863980, audio_type: Track } } May 28 15:49:16 volumio volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 15:49:16 volumio volumio[843]: TypeError: Cannot read property 'then' of undefined May 28 15:49:16 volumio volumio[843]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) May 28 15:49:16 volumio volumio[843]: at emitNone (events.js:106:13) May 28 15:49:16 volumio volumio[843]: at SpotConnEvents.emit (events.js:208:7) May 28 15:49:16 volumio volumio[843]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) May 28 15:49:16 volumio volumio[843]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) May 28 15:49:16 volumio volumio[843]: at emitTwo (events.js:126:13) May 28 15:49:16 volumio volumio[843]: at Socket.emit (events.js:214:7) May 28 15:49:16 volumio volumio[843]: at UDP.onMessage [as onmessage] (dgram.js:659:8) May 28 15:49:16 volumio volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:track:4dISxj4VluxjmwnWpXdyIk"> May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Resolving uri "spotify:station:track:4dISxj4VluxjmwnWpXdyIk" May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAbqbFXxQaUVmHNTL2Cx_X5Fx2YdLTCWD-roPD41mtweyFIE2FjgxxtlFKN58B4Qq6j0WVvdFdCMB2nhAux9yI7bshzXtA1sgDw6l9iK5yXLbuJqQwPgkatkCTeJhDDuuju42cAf7NYt9pcPKsprZ5Puvb2lXrYPnImaScH4Vo6BJThFgEhEqINSxmf7VZ3nMIGxoUa7Z3iI3v5oEf-kNcm72A1RUMA-5aIssRaSChiww", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Loading with Spotify URI May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : (164226 ms) loaded May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 28 15:49:16 volumio volumio[929]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 May 28 15:49:16 volumio volumio[929]: stack backtrace: May 28 15:49:16 volumio volumio[929]: [Vollibrespot] : Resolved 50 tracks from <"spotify:track:4dISxj4VluxjmwnWpXdyIk"> May 28 15:49:16 volumio volumio[929]: 0: 0xa7933c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 May 28 15:49:16 volumio volumio[929]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 May 28 15:49:16 volumio volumio[929]: 1: 0xa7933c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 May 28 15:49:16 volumio volumio[929]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 May 28 15:49:16 volumio volumio[929]: 2: 0xa7933c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 May 28 15:49:16 volumio volumio[929]: at src/libstd/sys_common/backtrace.rs:78 May 28 15:49:16 volumio volumio[929]: 3: 0xa7933c - ::fmt::he1a5d6f378e506c4 May 28 15:49:16 volumio volumio[929]: at src/libstd/sys_common/backtrace.rs:59 May 28 15:49:16 volumio volumio[929]: 4: 0xa99d5c - core::fmt::write::hb37ae5a5e0b70623 May 28 15:49:16 volumio volumio[929]: at src/libcore/fmt/mod.rs:1076 May 28 15:49:16 volumio volumio[929]: 5: 0xa72c64 - std::io::Write::write_fmt::ha24bb3f5a858327b May 28 15:49:16 volumio volumio[929]: at src/libstd/io/mod.rs:1537 May 28 15:49:16 volumio volumio[929]: 6: 0xa7b974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 May 28 15:49:16 volumio volumio[929]: at src/libstd/sys_common/backtrace.rs:62 May 28 15:49:16 volumio volumio[929]: 7: 0xa7b974 - std::sys_common::backtrace::print::h2217cbc390250439 May 28 15:49:16 volumio volumio[929]: at src/libstd/sys_common/backtrace.rs:49 May 28 15:49:16 volumio volumio[929]: 8: 0xa7b974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 May 28 15:49:16 volumio volumio[929]: at src/libstd/panicking.rs:198 May 28 15:49:16 volumio volumio[929]: 9: 0xa7b640 - std::panicking::default_hook::h46ab82039cbc65eb May 28 15:49:16 volumio volumio[929]: at src/libstd/panicking.rs:217 May 28 15:49:16 volumio volumio[929]: 10: 0xa7c054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 May 28 15:49:16 volumio volumio[929]: at src/libstd/panicking.rs:526 May 28 15:49:16 volumio volumio[929]: 11: 0xa7bc54 - rust_begin_unwind May 28 15:49:16 volumio volumio[929]: at src/libstd/panicking.rs:437 May 28 15:49:16 volumio volumio[929]: 12: 0xa97594 - core::panicking::panic_fmt::ha292e19d5ae716ed May 28 15:49:16 volumio volumio[929]: at src/libcore/panicking.rs:85 May 28 15:49:16 volumio volumio[929]: 13: 0xa974f0 - core::panicking::panic::heb457f4fa750842b May 28 15:49:16 volumio volumio[929]: at src/libcore/panicking.rs:50 May 28 15:49:16 volumio volumio[929]: 14: 0x676ee8 - ::write::h5da62cd8da1289c3 May 28 15:49:16 volumio volumio[929]: 15: 0x6503c4 - ::poll::h2bafd5dc8d40bf3f May 28 15:49:16 volumio volumio[929]: 16: 0x560eec - futures::task_impl::std::set::h2116cb0e9a5165fe May 28 15:49:16 volumio volumio[929]: 17: 0x555c6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa May 28 15:49:16 volumio volumio[929]: 18: 0x56f6a0 - futures::future::Future::wait::ha8819dd7a48bc248 May 28 15:49:16 volumio volumio[929]: 19: 0x5574b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 May 28 15:49:16 volumio volumio[929]: 20: 0x5765a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 May 28 15:49:16 volumio volumio[929]: 21: 0xa7f46c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 May 28 15:49:16 volumio volumio[929]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 May 28 15:49:16 volumio volumio[929]: 22: 0xa7f46c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 May 28 15:49:16 volumio volumio[929]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 May 28 15:49:16 volumio volumio[929]: 23: 0xa7f46c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc May 28 15:49:16 volumio volumio[929]: at src/libstd/sys/unix/thread.rs:87 May 28 15:49:17 volumio sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-28 15:48 May 28 15:49:17 volumio sudo[1572]: 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="c00a19098320053824010d132d6bfb39774a7d2f" VOLUMIO_FE_VERSION="e2f152c30d370c9c7c86508a02c0323bef5c243b" VOLUMIO_BE_VERSION="909cc76a7609428f005f8e2878227062cb5328a6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 17 00:18:22 CEST 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="cdde21c6e3d40ce352c051f661950d1b"