-- Logs begin at Sun 2026-02-08 01:00:58 UTC, end at Sat 2026-02-21 19:47:17 UTC. -- Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:00 mwvolumio volumio[24166]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track }, position_ms: 122299 } Feb 21 19:46:00 mwvolumio volumio[1387]: [SpotifyConnect] play Feb 21 19:46:00 mwvolumio volumio[1387]: [SpotifyConnect] Nothin' on You (feat. Bruno Mars) Feb 21 19:46:00 mwvolumio volumio[1387]: info: [1771703160755] ControllerSpotify::pushState Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:00 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:00 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:00 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:00 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:00 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:01 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:01 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:01 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track }, position_ms: 123046 } Feb 21 19:46:01 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:01 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:01 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:01 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:01 mwvolumio volumio[1387]: info: [1771703161266] ControllerSpotify::pushState Feb 21 19:46:01 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:01 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:02 mwvolumio volumio[1387]: [SpotifyConnect] Nothin' on You (feat. Bruno Mars) Feb 21 19:46:02 mwvolumio volumio[1387]: info: [1771703162302] ControllerSpotify::pushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:02 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:02 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track } } Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:02 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:02 mwvolumio volumio[1387]: info: [1771703162310] ControllerSpotify::pushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:02 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:05 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:05 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:07 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:07 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:09 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:09 mwvolumio volumio[24166]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track }, position_ms: 123332 } Feb 21 19:46:09 mwvolumio volumio[1387]: [SpotifyConnect] play Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:09 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:09 mwvolumio volumio[1387]: [SpotifyConnect] Nothin' on You (feat. Bruno Mars) Feb 21 19:46:09 mwvolumio volumio[1387]: info: [1771703169959] ControllerSpotify::pushState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:09 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:09 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:09 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:09 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:09 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:10 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:10 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track }, position_ms: 124140 } Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:10 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:10 mwvolumio volumio[1387]: info: [1771703170473] ControllerSpotify::pushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] Nothin' on You (feat. Bruno Mars) Feb 21 19:46:10 mwvolumio volumio[1387]: info: [1771703170700] ControllerSpotify::pushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:10 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:10 mwvolumio volumio[1387]: info: [1771703170944] ControllerSpotify::pushState Feb 21 19:46:10 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track } } Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:10 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:10 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:11 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:11 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:12 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 224867475059304643954991988170729648176, audio_type: Track }, track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Loading with Spotify URI Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : (215746 ms) loaded Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:13 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:13 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:13 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:13 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, position_ms: 596 } Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:13 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:13 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:13 mwvolumio volumio[1387]: info: [1771703173894] ControllerSpotify::pushState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:14 mwvolumio volumio[1387]: info: Is Connect Playback Feb 21 19:46:14 mwvolumio volumio[1387]: info: [1771703174075] ControllerSpotify::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:14 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:14 mwvolumio volumio[1387]: info: [1771703174121] ControllerSpotify::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:14 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:14 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:14 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:14 mwvolumio volumio[1387]: info: [1771703174550] ControllerSpotify::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:14 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:14 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:15 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:15 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:15 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] play Feb 21 19:46:16 mwvolumio volumio[24166]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, position_ms: 1041 } Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:16 mwvolumio volumio[1387]: info: [1771703176481] ControllerSpotify::pushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:16 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:16 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:16 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:16 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, position_ms: 1794 } Feb 21 19:46:16 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:16 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:16 mwvolumio volumio[1387]: info: [1771703176993] ControllerSpotify::pushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:16 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:17 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:17 mwvolumio volumio[1387]: info: [1771703177182] ControllerSpotify::pushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:17 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:17 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:17 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:17 mwvolumio volumio[1387]: info: [1771703177518] ControllerSpotify::pushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:17 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:17 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:17 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:17 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:21 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:21 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:21 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreStateMachine::volatilePlay Feb 21 19:46:22 mwvolumio volumio[1387]: info: Spotify Play Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Fetching autoplay context uri Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV"> Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Resolving uri "spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV" Feb 21 19:46:22 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:22 mwvolumio volumio[1387]: info: [1771703182778] ControllerSpotify::pushState Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:22 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:22 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:22 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:22 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:22 mwvolumio volumio[24166]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E4utVKkAV30Oy"> Feb 21 19:46:23 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:23 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, position_ms: 2758 } Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:23 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:23 mwvolumio volumio[1387]: info: [1771703183292] ControllerSpotify::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:23 mwvolumio volumio[1387]: info: [1771703183494] ControllerSpotify::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:23 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:23 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:23 mwvolumio volumio[1387]: info: [1771703183861] ControllerSpotify::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:23 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:23 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:25 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:25 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreStateMachine::volatilePlay Feb 21 19:46:26 mwvolumio volumio[1387]: info: Spotify Play Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Fetching autoplay context uri Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV"> Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Resolving uri "spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV" Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkActive Feb 21 19:46:26 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:26 mwvolumio volumio[1387]: info: [1771703186613] ControllerSpotify::pushState Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:26 mwvolumio volumio[1387]: [SpotifyConnect] Sink acquired Feb 21 19:46:26 mwvolumio volumio[1387]: info: Acquiring new spotify session Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:46:26 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:46:26 mwvolumio volumio[1387]: [SpotifyConnect] Spotify Received stop Feb 21 19:46:26 mwvolumio volumio[24166]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E4utVKkAV30Oy"> Feb 21 19:46:27 mwvolumio volumio[24166]: [Vollibrespot] : Pause Feb 21 19:46:27 mwvolumio volumio[24166]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track }, position_ms: 3778 } Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] Pause Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] pause Feb 21 19:46:27 mwvolumio volumio[1387]: info: Checking Spotify Web API Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Active Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 19:46:27 mwvolumio volumio[1387]: info: [1771703187127] ControllerSpotify::pushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] Ridin' Solo Feb 21 19:46:27 mwvolumio volumio[1387]: info: [1771703187302] ControllerSpotify::pushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:27 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:27 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:27 mwvolumio volumio[24166]: [Vollibrespot] : Event: SinkInactive Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] Sink released Feb 21 19:46:27 mwvolumio volumio[1387]: info: [1771703187705] ControllerSpotify::pushState Feb 21 19:46:27 mwvolumio volumio[24166]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::servicePushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:46:27 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is inactive Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:27 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:29 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:29 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:31 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:31 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:35 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:35 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:36 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:36 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:46:39 mwvolumio volumio[1387]: info: Starting Uninstall of plugin music_service - spop Feb 21 19:46:39 mwvolumio volumio[1387]: info: Uninstalling plugin spop Feb 21 19:46:39 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Feb 21 19:46:39 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 19:46:39 mwvolumio volumio[1387]: [SpotifyConnect] Closing metadata listener Feb 21 19:46:39 mwvolumio volumio[1387]: info: Disabling plugin spop Feb 21 19:46:39 mwvolumio volumio[1387]: info: Checking if uninstall.sh is present Feb 21 19:46:39 mwvolumio volumio[1387]: info: Executing uninstall.sh Feb 21 19:46:39 mwvolumio sudo[24394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Feb 21 19:46:39 mwvolumio sudo[24394]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:39 mwvolumio systemd[1]: Stopping Volspotconnect2 Daemon... Feb 21 19:46:39 mwvolumio systemd[1]: Stopped Volspotconnect2 Daemon. Feb 21 19:46:39 mwvolumio sudo[24394]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:39 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Daemon service stoped! Feb 21 19:46:39 mwvolumio sudo[24401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Feb 21 19:46:39 mwvolumio sudo[24401]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:39 mwvolumio sudo[24401]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:39 mwvolumio volumio[1387]: info: Uninstall script completed Feb 21 19:46:39 mwvolumio volumio[1387]: info: Removing plugin spop from configuration Feb 21 19:46:39 mwvolumio volumio[1387]: info: Successfully removed spop configuration files Feb 21 19:46:39 mwvolumio volumio[1387]: info: Plugin folders cleanup Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning into folder /volumio/app/plugins/ Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category audio_interface Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category miscellanea Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category music_service Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category plugins.json Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category system_controller Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category user_interface Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning into folder /data/plugins/ Feb 21 19:46:39 mwvolumio volumio[1387]: info: Scanning category music_service Feb 21 19:46:39 mwvolumio volumio[1387]: info: Cleaning folder for spop Feb 21 19:46:39 mwvolumio volumio[1387]: info: Plugin folders cleanup completed Feb 21 19:46:39 mwvolumio volumio[1387]: info: [] Feb 21 19:46:47 mwvolumio volumio[1387]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Feb 21 19:46:48 mwvolumio volumio[1387]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Feb 21 19:46:48 mwvolumio volumio[1387]: info: Folder /tmp/plugins removed Feb 21 19:46:48 mwvolumio volumio[1387]: info: Checking if plugin already exists Feb 21 19:46:48 mwvolumio volumio[1387]: info: Rename folder Feb 21 19:46:48 mwvolumio volumio[1387]: info: Folder /tmp/downloaded_plugin.zip removed Feb 21 19:46:48 mwvolumio volumio[1387]: info: Move to category Feb 21 19:46:49 mwvolumio volumio[1387]: info: Checking if install.sh is present Feb 21 19:46:49 mwvolumio volumio[1387]: info: Executing install.sh Feb 21 19:46:49 mwvolumio sudo[24471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/volspotconnect2/install.sh Feb 21 19:46:49 mwvolumio sudo[24471]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:49 mwvolumio volumio[1387]: info: Launching a bash shell Feb 21 19:46:49 mwvolumio volumio[1387]: info: Installing volspotconnect2 dependencies Feb 21 19:46:49 mwvolumio volumio[1387]: info: Detected cpu architecture as armv7l Feb 21 19:46:49 mwvolumio volumio[1387]: info: Latest version: v0.2.5 Requested version: v0.2.2 Feb 21 19:46:49 mwvolumio volumio[1387]: info: Supported device (arch = armv7l), downloading required packages for vollibrespot v0.2.2 Feb 21 19:46:49 mwvolumio volumio[1387]: info: Downloading file Feb 21 19:46:51 mwvolumio volumio[1387]: info: Extracting... Feb 21 19:46:51 mwvolumio volumio[1387]: info: -rw-r--r-- 1 root root 4289193 Feb 21 19:46 vollibrespot-armv7l.tar.xz Feb 21 19:46:51 mwvolumio volumio[1387]: info: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Feb 21 19:46:51 mwvolumio sudo[24504]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/tar -xvf volspotconnect2.service.tar -C / Feb 21 19:46:51 mwvolumio sudo[24504]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:51 mwvolumio volumio[1387]: info: etc/systemd/system/volspotconnect2.service Feb 21 19:46:51 mwvolumio sudo[24504]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:51 mwvolumio sudo[24512]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/music_service/volspotconnect2/onstart1.sh Feb 21 19:46:51 mwvolumio sudo[24512]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:51 mwvolumio sudo[24512]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:51 mwvolumio sudo[24471]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:51 mwvolumio volumio[1387]: info: volspotconnect2 installed Feb 21 19:46:51 mwvolumio volumio[1387]: Plugin install end detected on script Feb 21 19:46:51 mwvolumio volumio[1387]: info: Install script completed Feb 21 19:46:51 mwvolumio volumio[1387]: info: Adding reference to registry Feb 21 19:46:51 mwvolumio volumio[1387]: info: Done installing plugin. Feb 21 19:46:51 mwvolumio volumio[1387]: info: Folder /tmp/plugins removed Feb 21 19:46:51 mwvolumio volumio[1387]: info: Folder /tmp/downloaded_plugin.zip removed Feb 21 19:46:51 mwvolumio volumio[1387]: info: Folder /data/temp removed Feb 21 19:46:53 mwvolumio volumio[1387]: info: Enabling plugin volspotconnect2 Feb 21 19:46:53 mwvolumio volumio[1387]: info: Loading plugin "volspotconnect2"... Feb 21 19:46:53 mwvolumio volumio[1387]: info: PLUGIN START: volspotconnect2 Feb 21 19:46:53 mwvolumio volumio[1387]: [SpotifyConnect] Creating VLS config file Feb 21 19:46:53 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 19:46:53 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 19:46:53 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 19:46:53 mwvolumio volumio[1387]: [SpotifyConnect] Starting metadata listener Feb 21 19:46:53 mwvolumio sudo[24526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Feb 21 19:46:53 mwvolumio sudo[24526]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:46:53 mwvolumio systemd[1]: Starting Volspotconnect2 Daemon... Feb 21 19:46:53 mwvolumio systemd[1]: Started Volspotconnect2 Daemon. Feb 21 19:46:53 mwvolumio sudo[24526]: pam_unix(sudo:session): session closed for user root Feb 21 19:46:53 mwvolumio volumio[24533]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Feb 21 19:46:53 mwvolumio volumio[24533]: Reading Config from "volspotify.toml" Feb 21 19:46:53 mwvolumio volumio[1387]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 21 19:46:53 mwvolumio volumio[24533]: [Vollibrespot] : Using Alsa backend with device: softvolume Feb 21 19:46:53 mwvolumio volumio[1387]: [Metrics] SpotifyConnect: 0s 85.62ms Feb 21 19:46:53 mwvolumio volumio[1387]: info: Done. Feb 21 19:46:53 mwvolumio dhcpcd[1192]: wlan0: carrier lost Feb 21 19:46:53 mwvolumio dhcpcd[1192]: eth0: adding route to 192.168.1.0/24 Feb 21 19:46:53 mwvolumio wpa_supplicant[1145]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:35:1d:17:95:09 reason=0 locally_generated=1 Feb 21 19:46:53 mwvolumio dhcpcd[1192]: eth0: adding default route via 192.168.1.1 Feb 21 19:46:53 mwvolumio dhcpcd[1192]: wlan0: deleting host route to 192.168.1.9 via 127.0.0.1 Feb 21 19:46:53 mwvolumio dhcpcd[1192]: wlan0: deleting route to 192.168.1.0/24 Feb 21 19:46:53 mwvolumio dhcpcd[1192]: wlan0: deleting default route via 192.168.1.1 Feb 21 19:46:53 mwvolumio avahi-daemon[864]: Withdrawing address record for 192.168.1.9 on wlan0. Feb 21 19:46:53 mwvolumio avahi-daemon[864]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.9. Feb 21 19:46:53 mwvolumio avahi-daemon[864]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 21 19:46:53 mwvolumio wpa_supplicant[1145]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Feb 21 19:46:53 mwvolumio volumio[1387]: info: mDNS: A device disapperared from network Feb 21 19:46:53 mwvolumio volumio[1387]: info: mDNS: Device mwvolumio disapperared from network Feb 21 19:46:53 mwvolumio volumio[24533]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Feb 21 19:46:54 mwvolumio systemd[1]: Stopping LSB: Start NTP daemon... Feb 21 19:46:54 mwvolumio ntpd[2312]: ntpd exiting on signal 15 Feb 21 19:46:54 mwvolumio ntp[24640]: Stopping NTP server: ntpd. Feb 21 19:46:54 mwvolumio systemd[1]: Starting LSB: Start NTP daemon... Feb 21 19:46:54 mwvolumio ntpd[24658]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Feb 21 19:46:54 mwvolumio ntpd[24659]: proto: precision = 0.521 usec Feb 21 19:46:54 mwvolumio ntpd[24659]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Feb 21 19:46:54 mwvolumio ntp[24651]: Starting NTP server: ntpd. Feb 21 19:46:54 mwvolumio systemd[1]: Started LSB: Start NTP daemon. Feb 21 19:46:54 mwvolumio dhcpcd[1192]: eth0: removing route to 192.168.1.0/24 Feb 21 19:46:54 mwvolumio dhcpcd[1192]: eth0: removing default route via 192.168.1.1 Feb 21 19:46:54 mwvolumio ntpd[24659]: Listen and drop on 1 v6wildcard :: UDP 123 Feb 21 19:46:54 mwvolumio ntpd[24659]: Listen normally on 2 lo 127.0.0.1 UDP 123 Feb 21 19:46:54 mwvolumio ntpd[24659]: Listen normally on 3 eth0 192.168.1.150 UDP 123 Feb 21 19:46:54 mwvolumio ntpd[24659]: peers refreshed Feb 21 19:46:54 mwvolumio ntpd[24659]: Listening on routing socket on fd #20 for interface updates Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:46:55 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:46:55 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:00 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:00 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:01 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:01 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:01 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:01 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:01 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:01 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:02 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 19:47:02 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:04 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:04 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:04 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:05 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio wpa_supplicant[1145]: wlan0: Failed to initiate sched scan Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:05 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:05 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:05 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:07 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:07 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:07 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:09 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:09 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:11 mwvolumio wpa_supplicant[1145]: wlan0: Failed to initiate sched scan Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:11 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:11 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:11 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 21 19:47:12 mwvolumio sudo[24698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 21 19:47:12 mwvolumio sudo[24698]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 19:47:12 mwvolumio sudo[24698]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio sudo[24705]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 19:47:12 mwvolumio sudo[24705]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio sudo[24713]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24713]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio sudo[24725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 19:47:12 mwvolumio sudo[24737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 19:47:12 mwvolumio sudo[24725]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24725]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio sudo[24737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 19:47:12 mwvolumio sudo[24737]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio sudo[24749]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 19:47:12 mwvolumio sudo[24749]: pam_unix(sudo:session): session closed for user root Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:12 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:12 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:12 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:13 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:13 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:13 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:15 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 19:47:15 mwvolumio volumio[1387]: info: Discovery: Getting this device information Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConf Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 19:47:15 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Feb 21 19:47:16 mwvolumio wpa_supplicant[1145]: wlan0: Failed to initiate sched scan Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Authenticated as "mcwiskin" ! Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Setting up new mixer: card:hw:2 mixer:SoftMaster index:0 Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 99 (MilliBel(0)[dB]) HW: false Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Using Alsa linear volume Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Using alsa sink Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Metadata pipe established Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Country: "GB" Feb 21 19:47:16 mwvolumio volumio[24533]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Feb 21 19:47:16 mwvolumio volumio[1387]: [SpotifyConnect] 0 Feb 21 19:47:16 mwvolumio volumio[1387]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 Feb 21 19:47:16 mwvolumio volumio[1387]: info: CoreStateMachine::pushState Feb 21 19:47:16 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioPushState Feb 21 19:47:16 mwvolumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Fetching autoplay context uri Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Event: SessionActive { became_active_at: 1771703237013 } Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : SessionActive! Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:47:17 mwvolumio volumio[1387]: [SpotifyConnect] A connect session has begun Feb 21 19:47:17 mwvolumio volumio[1387]: [SpotifyConnect] Device palyback is active! Feb 21 19:47:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioGetState Feb 21 19:47:17 mwvolumio volumio[1387]: [SpotifyConnect] Currently active: spop Feb 21 19:47:17 mwvolumio volumio[1387]: [SpotifyConnect] Stopping currently active service Feb 21 19:47:17 mwvolumio volumio[1387]: info: CoreCommandRouter::volumioStop Feb 21 19:47:17 mwvolumio volumio[1387]: info: CoreStateMachine::stop Feb 21 19:47:17 mwvolumio volumio[1387]: info: CoreStateMachine::serviceStop Feb 21 19:47:17 mwvolumio volumio[1387]: info: CoreCommandRouter::serviceStop Feb 21 19:47:17 mwvolumio volumio[1387]: error: WARNING: No stop method for service spop Feb 21 19:47:17 mwvolumio volumio[1387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 19:47:17 mwvolumio volumio[1387]: TypeError: Cannot read property 'then' of undefined Feb 21 19:47:17 mwvolumio volumio[1387]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Feb 21 19:47:17 mwvolumio volumio[1387]: at emitNone (events.js:106:13) Feb 21 19:47:17 mwvolumio volumio[1387]: at SpotConnEvents.emit (events.js:208:7) Feb 21 19:47:17 mwvolumio volumio[1387]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Feb 21 19:47:17 mwvolumio volumio[1387]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Feb 21 19:47:17 mwvolumio volumio[1387]: at emitTwo (events.js:126:13) Feb 21 19:47:17 mwvolumio volumio[1387]: at Socket.emit (events.js:214:7) Feb 21 19:47:17 mwvolumio volumio[1387]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Feb 21 19:47:17 mwvolumio volumio[1387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV"> Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Resolving uri "spotify:station:artist:0z4gvV4rjIZ9wHck67ucSV" Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQArhFv9n7OYpaRlC68i4Llx3VPWm1Dlf98h6BmD7RAryTbP8p5bzhUI-rF8K_T-gByI3CAHKo96W8Q6-dTDMoHtku1K_sgCHlkEBX5T-737kpE2fEyxvzi7057lV-WJLRZrFK9sQ3o0yQ-aRc4EO7WSU2nG6kkIOK7DmJWC6AuRUzvd_IaDscQQIjN44oX-xb0GcLDYMF4FROGCF3GdAUVQZteV6uPDrDOnbBr6NPU6gts", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Loading with Spotify URI Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E4utVKkAV30Oy"> Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : (215746 ms) loaded Feb 21 19:47:17 mwvolumio volumio[24533]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 297725614863866948881678762894645598606, audio_type: Track } } Feb 21 19:47:17 mwvolumio sudo[24791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-21 19:46 Feb 21 19:47:17 mwvolumio sudo[24791]: 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"