-- 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"