-- Logs begin at Sat 2025-07-05 15:27:50 CEST, end at Sat 2025-07-05 16:35:45 CEST. -- Jul 05 16:34:12 volumio volumio[955]: info: Jul 05 16:34:12 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 05 16:34:12 volumio volumio[955]: info: Ignoring MPD Status Update Jul 05 16:34:12 volumio volumio[955]: info: Jul 05 16:34:12 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 05 16:34:12 volumio volumio[955]: info: ControllerMpd::getState Jul 05 16:34:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 05 16:34:12 volumio volumio[955]: info: ------------------------------ 2ms Jul 05 16:34:12 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Jul 05 16:34:12 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 05 16:34:12 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 16:34:12 volumio volumio[955]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 05 16:34:12 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 05 16:34:12 volumio volumio[955]: info: ControllerMpd::pushState Jul 05 16:34:12 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 05 16:34:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:12 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3896499,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","artist":"Radio 10","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","trackType":""} Jul 05 16:34:12 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 05 16:34:12 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 05 16:34:12 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 05 16:34:12 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 05 16:34:12 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:12 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:12 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 16:34:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:12 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:12 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:12 volumio volumio[955]: info: ------------------------------ 12ms Jul 05 16:34:12 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:12 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 16:34:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 16:34:21 volumio volumio[955]: info: Discovery: Getting this device information Jul 05 16:34:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 05 16:34:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 16:34:33 volumio volumio[955]: info: Jul 05 16:34:33 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 05 16:34:33 volumio volumio[955]: info: Ignoring MPD Status Update Jul 05 16:34:33 volumio volumio[955]: info: Jul 05 16:34:33 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 05 16:34:33 volumio volumio[955]: info: ControllerMpd::getState Jul 05 16:34:33 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 05 16:34:33 volumio volumio[955]: info: ------------------------------ 3ms Jul 05 16:34:33 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Jul 05 16:34:33 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 05 16:34:33 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 16:34:33 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 05 16:34:33 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 05 16:34:33 volumio volumio[955]: info: ControllerMpd::pushState Jul 05 16:34:33 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 05 16:34:33 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:33 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3916628,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","artist":"Radio 10","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","trackType":""} Jul 05 16:34:33 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 05 16:34:33 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 05 16:34:33 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 05 16:34:33 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 05 16:34:33 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:33 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:33 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 16:34:33 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:33 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:33 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:33 volumio volumio[955]: info: ------------------------------ 13ms Jul 05 16:34:33 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:33 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:37 volumio volumio[955]: info: Jul 05 16:34:37 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 05 16:34:37 volumio volumio[955]: info: Ignoring MPD Status Update Jul 05 16:34:37 volumio volumio[955]: info: Jul 05 16:34:37 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 05 16:34:37 volumio volumio[955]: info: ControllerMpd::getState Jul 05 16:34:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 05 16:34:37 volumio volumio[955]: info: ------------------------------ 1ms Jul 05 16:34:37 volumio volumio[955]: info: sendMpdCommand status took 1 milliseconds Jul 05 16:34:37 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 05 16:34:37 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 16:34:37 volumio volumio[955]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 05 16:34:37 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 05 16:34:37 volumio volumio[955]: info: ControllerMpd::pushState Jul 05 16:34:37 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 05 16:34:37 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:37 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3921128,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","artist":"Radio 10","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","trackType":""} Jul 05 16:34:37 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 05 16:34:37 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 05 16:34:37 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 05 16:34:37 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 05 16:34:37 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:37 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:34:37 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 16:34:37 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:37 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:34:37 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:34:37 volumio volumio[955]: info: ------------------------------ 16ms Jul 05 16:34:37 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:37 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:34:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetQueue Jul 05 16:34:47 volumio volumio[955]: info: CoreStateMachine::getQueue Jul 05 16:34:47 volumio volumio[955]: info: CorePlayQueue::getQueue Jul 05 16:34:50 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 05 16:34:50 volumio volumio[955]: info: handleBrowseUri took 126 milliseconds Jul 05 16:34:50 volumio volumio[955]: info: Preload queue cleared Jul 05 16:34:50 volumio volumio[955]: info: Preload queue cleared Jul 05 16:34:51 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 16:34:51 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 16:34:51 volumio volumio[955]: info: Discovery: Getting this device information Jul 05 16:34:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 05 16:34:51 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 16:35:14 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jul 05 16:35:16 volumio volumio[955]: info: handleBrowseUri took 2223 milliseconds Jul 05 16:35:17 volumio volumio[955]: info: Preload queue cleared Jul 05 16:35:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 16:35:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 16:35:21 volumio volumio[955]: info: Discovery: Getting this device information Jul 05 16:35:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 05 16:35:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 16:35:26 volumio volumio[955]: Searching all installed plugins Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: , search Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 05 16:35:26 volumio volumio[955]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jul 05 16:35:26 volumio volumio[955]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jul 05 16:35:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 05 16:35:27 volumio volumio[955]: Searching all installed plugins Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: , search Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 05 16:35:27 volumio volumio[955]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jul 05 16:35:27 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 05 16:35:31 volumio volumio[955]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jul 05 16:35:31 volumio volumio[955]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Jul 05 16:35:31 volumio volumio[955]: info: Shoutcast fout Jul 05 16:35:31 volumio volumio[955]: error: Search in plugin ytmusic timed out Jul 05 16:35:31 volumio volumio[955]: error: Search in plugin qobuz timed out Jul 05 16:35:31 volumio volumio[955]: info: All search sources collected, pushing search results Jul 05 16:35:31 volumio volumio[955]: info: searchQOBUZUri took 5445 milliseconds Jul 05 16:35:31 volumio volumio[955]: error: Failed search in plugin qobuz: Error: Unable to resolve or reject the same promise twice Jul 05 16:35:31 volumio volumio[955]: info: searchQOBUZUri took 4784 milliseconds Jul 05 16:35:31 volumio volumio[955]: Searching all installed plugins Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 05 16:35:31 volumio volumio[955]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 05 16:35:31 volumio volumio[955]: Searching all installed plugins Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 05 16:35:31 volumio volumio[955]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Jul 05 16:35:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jul 05 16:35:32 volumio volumio[955]: error: Search in plugin ytmusic timed out Jul 05 16:35:32 volumio volumio[955]: info: All search sources collected, pushing search results Jul 05 16:35:32 volumio volumio[955]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jul 05 16:35:32 volumio volumio[955]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Jul 05 16:35:32 volumio volumio[955]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Jul 05 16:35:32 volumio volumio[955]: info: searchQOBUZUri took 1136 milliseconds Jul 05 16:35:36 volumio volumio[955]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jul 05 16:35:36 volumio volumio[955]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Jul 05 16:35:36 volumio volumio[955]: info: searchQOBUZUri took 4328 milliseconds Jul 05 16:35:36 volumio volumio[955]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Jul 05 16:35:36 volumio volumio[955]: error: Search in plugin ytmusic timed out Jul 05 16:35:36 volumio volumio[955]: info: All search sources collected, pushing search results Jul 05 16:35:36 volumio volumio[955]: error: Search in plugin ytmusic timed out Jul 05 16:35:36 volumio volumio[955]: info: All search sources collected, pushing search results Jul 05 16:35:36 volumio volumio[955]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Jul 05 16:35:36 volumio volumio[955]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Jul 05 16:35:37 volumio volumio[955]: error: Failed search in plugin ytmusic: Error: Unable to resolve or reject the same promise twice Jul 05 16:35:38 volumio volumio[955]: info: Jul 05 16:35:38 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 05 16:35:38 volumio volumio[955]: info: Ignoring MPD Status Update Jul 05 16:35:38 volumio volumio[955]: info: Jul 05 16:35:38 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 05 16:35:38 volumio volumio[955]: info: ControllerMpd::getState Jul 05 16:35:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 05 16:35:38 volumio volumio[955]: info: ------------------------------ 5ms Jul 05 16:35:38 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Jul 05 16:35:38 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 05 16:35:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 16:35:38 volumio volumio[955]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 05 16:35:38 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 05 16:35:38 volumio volumio[955]: info: ControllerMpd::pushState Jul 05 16:35:38 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 05 16:35:38 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:35:38 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3981750,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Bon Jovi - You Give Love A Bad Name","artist":"Radio 10","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc1MTcxNjU4NSwiaXNzIjoidGlzcnYifQ.kc_nODPk5b75VLciJ2aMQ4rFGoMEriABIqzTCqqLET0","trackType":""} Jul 05 16:35:38 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 05 16:35:38 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 05 16:35:38 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 05 16:35:38 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 05 16:35:38 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:35:38 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 16:35:38 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:35:38 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:35:38 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:35:38 volumio volumio[955]: info: ------------------------------ 24ms Jul 05 16:35:38 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:35:38 volumio volumio[955]: info: [ASDebug] CurState: play PrevState: play Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::volumioStop Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::stop Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 05 16:35:44 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:35:44 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::serviceStop Jul 05 16:35:44 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::serviceStop Jul 05 16:35:44 volumio volumio[955]: info: [1751726144051] ControllerWebradio::stop Jul 05 16:35:44 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 16:35:44 volumio volumio[955]: info: [ASDebug] CurState: stop PrevState: play Jul 05 16:35:44 volumio volumio[955]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 05 16:35:44 volumio volumio[955]: info: Jul 05 16:35:44 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 05 16:35:44 volumio volumio[955]: info: sendMpdCommand stop took 79 milliseconds Jul 05 16:35:44 volumio volumio[955]: info: ControllerMpd::getState Jul 05 16:35:44 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 05 16:35:44 volumio volumio[955]: info: sendMpdCommand status took 2 milliseconds Jul 05 16:35:44 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 05 16:35:44 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 16:35:44 volumio volumio[955]: info: touch_display: Setting screensaver timeout to 255 seconds. Jul 05 16:35:44 volumio volumio[955]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 05 16:35:44 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 05 16:35:44 volumio volumio[955]: info: ControllerMpd::pushState Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 05 16:35:44 volumio volumio[955]: info: CoreStateMachine::pushState Jul 05 16:35:44 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:35:44 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 05 16:35:44 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 05 16:35:44 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 05 16:35:44 volumio volumio[955]: info: ------------------------------ 17ms Jul 05 16:35:44 volumio volumio[955]: info: [ASDebug] CurState: stop PrevState: play Jul 05 16:35:44 volumio volumio[955]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 05 16:35:44 volumio volumio[955]: info: [ASDebug] Togle GPIO: OFF Jul 05 16:35:44 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 16:35:44 volumio volumio[955]: Error: EBADF: bad file descriptor, write Jul 05 16:35:44 volumio volumio[955]: at Object.writeSync (fs.js:711:3) Jul 05 16:35:44 volumio volumio[955]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jul 05 16:35:44 volumio volumio[955]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jul 05 16:35:44 volumio volumio[955]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jul 05 16:35:44 volumio volumio[955]: at listOnTimeout (internal/timers.js:554:17) Jul 05 16:35:44 volumio volumio[955]: at processTimers (internal/timers.js:497:7) { Jul 05 16:35:44 volumio volumio[955]: errno: -9, Jul 05 16:35:44 volumio volumio[955]: syscall: 'write', Jul 05 16:35:44 volumio volumio[955]: code: 'EBADF' Jul 05 16:35:44 volumio volumio[955]: } Jul 05 16:35:44 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 16:35:45 volumio sudo[11266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 16:34 Jul 05 16:35:45 volumio sudo[11266]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"