-- Logs begin at Fri 2024-04-12 10:11:57 -03, end at Fri 2024-04-12 14:56:11 -03. -- Apr 12 14:55:00 volumio python[588]: 8.8.8.8 is still not reachable, trying again in 1 sec Apr 12 14:55:02 volumio volumio[687]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Send the Pain Below","mbid":"9a45d530-a1e9-44df-9cae-277522f70d31","url":"https://www.last.fm/music/Chevelle/_/Send+the+Pain+Below","duration":"252000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"400952","playcount":"2333825","artist":{"name":"Chevelle","mbid":"8456e9f7-debf-4579-a86c-33a325a35d2d","url":"https://www.last.fm/music/Chevelle"},"album":{"@":{"position":"3"},"artist":"Chevelle","title":"Wonder What's Next","mbid":"3e5e3a41-9d84-44c0-a574-48cda73cde95","url":"https://www.last.fm/music/Chevelle/Wonder+What%27s+Next","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/7e1236413df85484308673ca1ac139d5.png","@":{"size":"extralarge"}}]},"userplaycount":"2","userloved":"0","toptags":{"tag":[{"name":"alternative rock","url":"https://www.last.fm/tag/alternative+rock"},{"name":"rock","url":"https://www.last.fm/tag/rock"},{"name":"alternative","url":"https://www.last.fm/tag/alternative"},{"name":"hard rock","url":"https://www.last.fm/tag/hard+rock"},{"name":"chevelle","url":"https://www.last.fm/tag/chevelle"}]},"wiki":{"published":"06 Oct 2009, 18:55","summary":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm.","content":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Apr 12 14:55:11 volumio python[588]: 8.8.8.8 is still not reachable, trying again in 1 sec Apr 12 14:55:22 volumio python[588]: 8.8.8.8 is still not reachable, trying again in 1 sec Apr 12 14:55:23 volumio python[588]: 8.8.8.8 is reachable again, resuming Volumio playback now Apr 12 14:55:23 volumio python[588]: Starting Volumio playback... Apr 12 14:55:23 volumio python[588]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 12 14:55:23 volumio python[588]: Dload Upload Total Spent Left Speed Apr 12 14:55:23 volumio volumio[687]: info: Apr 12 14:55:23 volumio volumio[687]: ---------------------------- Client requests Volumio play Apr 12 14:55:23 volumio volumio[687]: info: CoreCommandRouter::volumioPlay Apr 12 14:55:23 volumio volumio[687]: info: CoreStateMachine::play index undefined Apr 12 14:55:23 volumio volumio[687]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:55:23 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:23 volumio volumio[687]: info: CoreStateMachine::startPlaybackTimer Apr 12 14:55:23 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:23 volumio volumio[687]: info: [1712944523673] ControllerWebradio::clearAddPlayTrack Apr 12 14:55:23 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 14:55:23 volumio python[588]: [158B blob data] Apr 12 14:55:23 volumio volumio[687]: info: sendMpdCommand stop took 3 milliseconds Apr 12 14:55:23 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 14:55:23 volumio volumio[687]: info: sendMpdCommand clear took 1 milliseconds Apr 12 14:55:23 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4" Apr 12 14:55:23 volumio volumio[687]: info: Apr 12 14:55:23 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:23 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:23 volumio volumio[687]: info: Apr 12 14:55:23 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:23 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:23 volumio volumio[687]: info: Apr 12 14:55:23 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:23 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:24 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:24 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:24 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:24 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:25 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:25 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:25 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:25 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:26 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4" Apr 12 14:55:26 volumio volumio[687]: error: updateQueue error: null Apr 12 14:55:26 volumio volumio[687]: error: updateQueue error: null Apr 12 14:55:26 volumio volumio[687]: error: updateQueue error: null Apr 12 14:55:26 volumio volumio[687]: info: Apr 12 14:55:26 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:26 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 2949ms Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 2946ms Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 2945ms Apr 12 14:55:26 volumio volumio[687]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4" took 4 milliseconds Apr 12 14:55:26 volumio volumio[687]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 14:55:26 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand play Apr 12 14:55:26 volumio volumio[687]: Upnp client error: Error: This socket has been ended by the other party Apr 12 14:55:26 volumio volumio[687]: info: Apr 12 14:55:26 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:26 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:26 volumio volumio[687]: info: Apr 12 14:55:26 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:26 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 8ms Apr 12 14:55:26 volumio volumio[687]: info: sendMpdCommand play took 5 milliseconds Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 2ms Apr 12 14:55:26 volumio volumio[687]: info: ------------------------------ 1ms Apr 12 14:55:27 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:27 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:28 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:28 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 4 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 3 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 3 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 6 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 3 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 2 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus stop Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 29ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 35ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 35ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 89ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 86ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 86ms Apr 12 14:55:32 volumio volumio[687]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Cidade Apr 12 14:55:32 volumio volumio[687]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Cidade Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 8ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 7 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 6ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 5 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 5ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 4 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces system playlist update Apr 12 14:55:32 volumio volumio[687]: info: Ignoring MPD Status Update Apr 12 14:55:32 volumio volumio[687]: info: Apr 12 14:55:32 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 62ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 62 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 60 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 60 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 9ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 8 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 7ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand status took 6 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":365,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 106ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 105ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 107ms Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 41 milliseconds Apr 12 14:55:32 volumio volumio[687]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:32 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:32 volumio volumio[687]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"CHEVELLE - SEND THE PAIN BELOW","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIOCIDADE.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcxMTE5NzkyMiwiaXNzIjoidGlzcnYifQ.7K5cH2ZhKB2akwa-PRF5nit04GDawlnCFVQ3T7WEeT4","trackType":""} Apr 12 14:55:32 volumio volumio[687]: verbose: CURRENT POSITION 0 Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState stateService play Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::syncState currentStatus play Apr 12 14:55:32 volumio volumio[687]: info: Received an update from plugin. extracting info from payload Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:32 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 144ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 88ms Apr 12 14:55:32 volumio volumio[687]: info: ------------------------------ 88ms Apr 12 14:55:32 volumio volumio[687]: info: [LastFM] Split composite title into artist: CHEVELLE and title: SEND THE PAIN BELOW Apr 12 14:55:32 volumio volumio[687]: info: [LastFM] Split composite title into artist: CHEVELLE and title: SEND THE PAIN BELOW Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:32 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:37 volumio volumio[687]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Send the Pain Below","mbid":"9a45d530-a1e9-44df-9cae-277522f70d31","url":"https://www.last.fm/music/Chevelle/_/Send+the+Pain+Below","duration":"252000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"400952","playcount":"2333825","artist":{"name":"Chevelle","mbid":"8456e9f7-debf-4579-a86c-33a325a35d2d","url":"https://www.last.fm/music/Chevelle"},"album":{"@":{"position":"3"},"artist":"Chevelle","title":"Wonder What's Next","mbid":"3e5e3a41-9d84-44c0-a574-48cda73cde95","url":"https://www.last.fm/music/Chevelle/Wonder+What%27s+Next","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/7e1236413df85484308673ca1ac139d5.png","@":{"size":"extralarge"}}]},"userplaycount":"2","userloved":"0","toptags":{"tag":[{"name":"alternative rock","url":"https://www.last.fm/tag/alternative+rock"},{"name":"rock","url":"https://www.last.fm/tag/rock"},{"name":"alternative","url":"https://www.last.fm/tag/alternative"},{"name":"hard rock","url":"https://www.last.fm/tag/hard+rock"},{"name":"chevelle","url":"https://www.last.fm/tag/chevelle"}]},"wiki":{"published":"06 Oct 2009, 18:55","summary":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm.","content":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Apr 12 14:55:37 volumio volumio[687]: info: [LastFM] Updated missing track duration: 252000 Apr 12 14:55:37 volumio volumio[687]: info: [LastFM] Updated missing track album: Wonder What's Next Apr 12 14:55:37 volumio volumio[687]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Send the Pain Below","mbid":"9a45d530-a1e9-44df-9cae-277522f70d31","url":"https://www.last.fm/music/Chevelle/_/Send+the+Pain+Below","duration":"252000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"400952","playcount":"2333825","artist":{"name":"Chevelle","mbid":"8456e9f7-debf-4579-a86c-33a325a35d2d","url":"https://www.last.fm/music/Chevelle"},"album":{"@":{"position":"3"},"artist":"Chevelle","title":"Wonder What's Next","mbid":"3e5e3a41-9d84-44c0-a574-48cda73cde95","url":"https://www.last.fm/music/Chevelle/Wonder+What%27s+Next","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/7e1236413df85484308673ca1ac139d5.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/7e1236413df85484308673ca1ac139d5.png","@":{"size":"extralarge"}}]},"userplaycount":"2","userloved":"0","toptags":{"tag":[{"name":"alternative rock","url":"https://www.last.fm/tag/alternative+rock"},{"name":"rock","url":"https://www.last.fm/tag/rock"},{"name":"alternative","url":"https://www.last.fm/tag/alternative"},{"name":"hard rock","url":"https://www.last.fm/tag/hard+rock"},{"name":"chevelle","url":"https://www.last.fm/tag/chevelle"}]},"wiki":{"published":"06 Oct 2009, 18:55","summary":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm.","content":"This song is about keeping everything locked away, bottled up. Just ignoring the pain, and try to keep pushing through the misery of being hurt by someone you love. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Apr 12 14:55:39 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:39 volumio volumio[687]: info: CoreCommandRouter::volumioGetState Apr 12 14:55:59 volumio python[588]: {"time":1712944523669,"response":"play Success"}8.8.8.8 is not reachable (2 subsequently dropped packets), stopping Volumio playback Apr 12 14:55:59 volumio python[588]: Stopping Volumio playback... Apr 12 14:55:59 volumio python[588]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 12 14:55:59 volumio python[588]: Dload Upload Total Spent Left Speed Apr 12 14:55:59 volumio volumio[687]: info: Apr 12 14:55:59 volumio volumio[687]: ---------------------------- Client requests Volumio stop Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::volumioStop Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::stop Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::stPlaybackTimer Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::updateTrackBlock Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrackBlock Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::serviceStop Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::serviceStop Apr 12 14:55:59 volumio volumio[687]: info: [1712944559424] ControllerWebradio::stop Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 14:55:59 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:59 volumio volumio[687]: info: Apr 12 14:55:59 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand stop took 49 milliseconds Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:59 volumio python[588]: [158B blob data] Apr 12 14:55:59 volumio volumio[687]: info: Apr 12 14:55:59 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:59 volumio volumio[687]: info: Apr 12 14:55:59 volumio volumio[687]: ---------------------------- MPD announces state update: player Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::getState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand status Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand status took 7 milliseconds Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand status took 4 milliseconds Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand status took 8 milliseconds Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseState Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 14:55:59 volumio volumio[687]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:59 volumio volumio[687]: verbose: ControllerMpd::parseTrackInfo Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 14:55:59 volumio volumio[687]: info: ControllerMpd::pushState Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::servicePushState Apr 12 14:55:59 volumio volumio[687]: info: CoreStateMachine::pushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: CoreCommandRouter::volumioPushState Apr 12 14:55:59 volumio volumio[687]: info: CorePlayQueue::getTrack 0 Apr 12 14:55:59 volumio volumio[687]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 14:55:59 volumio volumio[687]: info: ------------------------------ 39ms Apr 12 14:55:59 volumio volumio[687]: info: ------------------------------ 36ms Apr 12 14:55:59 volumio volumio[687]: info: ------------------------------ 34ms Apr 12 14:55:59 volumio volumio[687]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 14:55:59 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:59 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:59 volumio volumio[687]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 12 14:55:59 volumio volumio[687]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 12 14:55:59 volumio volumio[687]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 12 14:56:09 volumio python[588]: {"time":1712944559418,"response":"stop Success"}8.8.8.8 is still not reachable, trying again in 1 sec Apr 12 14:56:11 volumio volumio[687]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 14:56:11 volumio volumio[687]: Error: connect ETIMEDOUT 130.211.19.189:80 Apr 12 14:56:11 volumio volumio[687]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 12 14:56:11 volumio volumio[687]: errno: -110, Apr 12 14:56:11 volumio volumio[687]: code: 'ETIMEDOUT', Apr 12 14:56:11 volumio volumio[687]: syscall: 'connect', Apr 12 14:56:11 volumio volumio[687]: address: '130.211.19.189', Apr 12 14:56:11 volumio volumio[687]: port: 80 Apr 12 14:56:11 volumio volumio[687]: } Apr 12 14:56:11 volumio volumio[687]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 14:56:11 volumio sudo[5341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 14:55 Apr 12 14:56:11 volumio sudo[5341]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"