-- Logs begin at Tue 2023-05-23 15:22:30 UTC, end at Tue 2023-05-23 15:34:04 UTC. --
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 23 15:33:06 volumio2 volumio[1722]: info: Discovery: Getting this device information
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::volumioGetState
May 23 15:33:06 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 23 15:33:06 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 23 15:33:06 volumio2 nmbd[831]: [2023/05/23 15:33:06.928253, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
May 23 15:33:06 volumio2 nmbd[831]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.68 for name WORKGROUP<1d>.
May 23 15:33:06 volumio2 nmbd[831]: This response was from IP 192.168.178.14, reporting an IP address of 192.168.178.14.
May 23 15:33:09 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
May 23 15:33:09 volumio2 volumio[1722]: info: In handleBrowseUri, curUri=spotify
May 23 15:33:09 volumio2 volumio[1722]: error: An error occurred while listing Spotify new albums WebapiError: certificate is not yet valid
May 23 15:33:09 volumio2 volumio[1722]: error: An error occurred while listing Spotify featured playlists WebapiError: certificate is not yet valid
May 23 15:33:09 volumio2 volumio[1722]: error: An error occurred while listing Spotify categories WebapiError: certificate is not yet valid
May 23 15:33:15 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPlay
May 23 15:33:15 volumio2 volumio[1722]: verbose: UNSET VOLATILE
May 23 15:33:15 volumio2 volumio[1722]: info: CoreStateMachine::play index undefined
May 23 15:33:15 volumio2 volumio[1722]: info: CoreStateMachine::setConsumeUpdateService undefined
May 23 15:33:15 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:15 volumio2 volumio[1722]: info: CoreStateMachine::startPlaybackTimer
May 23 15:33:15 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:15 volumio2 volumio[1722]: info: [1684855995711] ControllerWebradio::clearAddPlayTrack
May 23 15:33:15 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand stop
May 23 15:33:15 volumio2 volumio[1722]: info: sendMpdCommand stop took 12 milliseconds
May 23 15:33:15 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand clear
May 23 15:33:15 volumio2 volumio[1722]: info:
May 23 15:33:15 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:15 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:15 volumio2 volumio[1722]: info: sendMpdCommand clear took 4 milliseconds
May 23 15:33:15 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958"
May 23 15:33:15 volumio2 volumio[1722]: error: updateQueue error: null
May 23 15:33:15 volumio2 volumio[1722]: info: ------------------------------ 6ms
May 23 15:33:15 volumio2 volumio[1722]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497958" took 133 milliseconds
May 23 15:33:15 volumio2 volumio[1722]: info: CoreStateMachine::setConsumeUpdateService mpd
May 23 15:33:15 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand play
May 23 15:33:15 volumio2 volumio[1722]: info:
May 23 15:33:15 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:15 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:15 volumio2 volumio[1722]: info: sendMpdCommand play took 3 milliseconds
May 23 15:33:15 volumio2 volumio[1722]: info: ------------------------------ 5ms
May 23 15:33:16 volumio2 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
May 23 15:33:16 volumio2 volumio[1722]: info:
May 23 15:33:16 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:16 volumio2 volumio[1722]: info:
May 23 15:33:16 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand status took 19 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:16 volumio2 volumio[1722]: info:
May 23 15:33:16 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:16 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand status took 6 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 5 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:16 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:16 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Electric Light Orchestra - Telephone line","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:16 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus stop
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ------------------------------ 76ms
May 23 15:33:16 volumio2 volumio[1722]: info: ------------------------------ 81ms
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 78 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:16 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:16 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Electric Light Orchestra - Telephone line","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:16 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus play
May 23 15:33:16 volumio2 volumio[1722]: info: Received an update from plugin. extracting info from payload
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ------------------------------ 204ms
May 23 15:33:16 volumio2 volumio[1722]: info:
May 23 15:33:16 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:16 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:16 volumio2 volumio[1722]: info:
May 23 15:33:16 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:16 volumio2 volumio[1722]: info: ------------------------------ 11ms
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand status took 17 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:16 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 3 milliseconds
May 23 15:33:16 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:16 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:16 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1187,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Electric Light Orchestra - Telephone line","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:16 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus play
May 23 15:33:16 volumio2 volumio[1722]: info: Received an update from plugin. extracting info from payload
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:16 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:16 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:16 volumio2 volumio[1722]: info: ------------------------------ 76ms
May 23 15:33:17 volumio2 kernel: rpi_firmware_get_throttled: 2 callbacks suppressed
May 23 15:33:17 volumio2 kernel: Under-voltage detected! (0x00050005)
May 23 15:33:18 volumio2 volumio[1722]: info:
May 23 15:33:18 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:18 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:18 volumio2 volumio[1722]: info:
May 23 15:33:18 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:18 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:18 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:18 volumio2 volumio[1722]: info: ------------------------------ 10ms
May 23 15:33:18 volumio2 volumio[1722]: info: sendMpdCommand status took 5 milliseconds
May 23 15:33:18 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:18 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:18 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 3 milliseconds
May 23 15:33:18 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:18 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:18 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:18 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Electric Light Orchestra - Telephone line","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:18 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:18 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:18 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus play
May 23 15:33:18 volumio2 volumio[1722]: info: Received an update from plugin. extracting info from payload
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 23 15:33:18 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:18 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:18 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:18 volumio2 volumio[1722]: info: ------------------------------ 82ms
May 23 15:33:27 volumio2 volumio[1722]: info: VolumeController::SetAlsaVolume70
May 23 15:33:27 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:27 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:27 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:29 volumio2 volumio[1722]: info: VolumeController::SetAlsaVolume60
May 23 15:33:29 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:29 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:29 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 23 15:33:44 volumio2 volumio[1722]: info: Discovery: Getting this device information
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::volumioGetState
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 23 15:33:44 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , getConf
May 23 15:33:50 volumio2 volumio[1722]: info:
May 23 15:33:50 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:50 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:50 volumio2 volumio[1722]: info:
May 23 15:33:50 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:50 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:50 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:50 volumio2 volumio[1722]: info: ------------------------------ 15ms
May 23 15:33:50 volumio2 volumio[1722]: info: sendMpdCommand status took 5 milliseconds
May 23 15:33:50 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:50 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:50 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 3 milliseconds
May 23 15:33:50 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:50 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:50 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:50 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34586,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:50 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:50 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:50 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus play
May 23 15:33:50 volumio2 volumio[1722]: info: Received an update from plugin. extracting info from payload
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 23 15:33:50 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:50 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:50 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:50 volumio2 volumio[1722]: info: ------------------------------ 53ms
May 23 15:33:52 volumio2 volumio[1722]: info:
May 23 15:33:52 volumio2 volumio[1722]: ---------------------------- MPD announces system playlist update
May 23 15:33:52 volumio2 volumio[1722]: info: Ignoring MPD Status Update
May 23 15:33:52 volumio2 volumio[1722]: info:
May 23 15:33:52 volumio2 volumio[1722]: ---------------------------- MPD announces state update: player
May 23 15:33:52 volumio2 volumio[1722]: info: ControllerMpd::getState
May 23 15:33:52 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand status
May 23 15:33:52 volumio2 volumio[1722]: info: ------------------------------ 8ms
May 23 15:33:52 volumio2 volumio[1722]: info: sendMpdCommand status took 5 milliseconds
May 23 15:33:52 volumio2 volumio[1722]: verbose: ControllerMpd::parseState
May 23 15:33:52 volumio2 volumio[1722]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 23 15:33:52 volumio2 volumio[1722]: info: sendMpdCommand playlistinfo took 3 milliseconds
May 23 15:33:52 volumio2 volumio[1722]: verbose: ControllerMpd::parseTrackInfo
May 23 15:33:52 volumio2 volumio[1722]: info: ControllerMpd::pushState
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::servicePushState
May 23 15:33:52 volumio2 volumio[1722]: info: CorePlayQueue::getTrack 0
May 23 15:33:52 volumio2 volumio[1722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"OLDIE ANTENNE - Oldies but Goldies","artist":"OLDIE ANTENNE","album":null,"uri":"http://stream.antenne.de:80/oldies-but-goldies","trackType":""}
May 23 15:33:52 volumio2 volumio[1722]: verbose: CURRENT POSITION 0
May 23 15:33:52 volumio2 volumio[1722]: info: CoreStateMachine::syncState stateService play
May 23 15:33:52 volumio2 volumio[1722]: info: CoreStateMachine::syncState currentStatus play
May 23 15:33:52 volumio2 volumio[1722]: info: Received an update from plugin. extracting info from payload
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
May 23 15:33:52 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:52 volumio2 volumio[1722]: info: CoreStateMachine::pushState
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::volumioPushState
May 23 15:33:52 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 15:33:52 volumio2 volumio[1722]: info: ------------------------------ 50ms
May 23 15:33:57 volumio2 volumio[1722]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
May 23 15:33:57 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 23 15:33:57 volumio2 volumio[1722]: [SpotifyConnect] Closing metadata listener
May 23 15:33:57 volumio2 volumio[1722]: info: Disabling plugin spop
May 23 15:33:57 volumio2 volumio[1722]: info: Done.
May 23 15:33:57 volumio2 sudo[3115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service
May 23 15:33:57 volumio2 sudo[3115]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:33:57 volumio2 systemd[1]: Stopping Volspotconnect2 Daemon...
May 23 15:33:57 volumio2 systemd[1]: Stopped Volspotconnect2 Daemon.
May 23 15:33:57 volumio2 sudo[3115]: pam_unix(sudo:session): session closed for user root
May 23 15:33:57 volumio2 volumio[1722]: [SpotifyConnect] Vollibrespot Daemon service stoped!
May 23 15:33:58 volumio2 volumio[1722]: info: Enabling plugin spop
May 23 15:33:58 volumio2 volumio[1722]: info: Loading plugin "spop"...
May 23 15:33:58 volumio2 volumio[1722]: info: PLUGIN START: spop
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 23 15:33:58 volumio2 volumio[1722]: [SpotifyConnect] Creating VLS config file
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:33:58 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:33:58 volumio2 volumio[1722]: [SpotifyConnect] Starting metadata listener
May 23 15:33:58 volumio2 sudo[3127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
May 23 15:33:58 volumio2 sudo[3127]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:33:58 volumio2 sudo[3127]: pam_unix(sudo:session): session closed for user root
May 23 15:33:58 volumio2 sudo[3131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
May 23 15:33:58 volumio2 sudo[3131]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:33:58 volumio2 systemd[1]: Starting Volspotconnect2 Daemon...
May 23 15:33:58 volumio2 systemd[1]: Started Volspotconnect2 Daemon.
May 23 15:33:58 volumio2 sudo[3131]: pam_unix(sudo:session): session closed for user root
May 23 15:33:58 volumio2 volumio[3141]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 23 15:33:58 volumio2 volumio[3141]: Reading Config from "/tmp/volspotify.toml"
May 23 15:33:58 volumio2 volumio[3141]: [Vollibrespot] : Using Alsa backend with device: plughw:2
May 23 15:33:58 volumio2 volumio[1722]: [SpotifyConnect] Vollibrespot Daemon service started!
May 23 15:33:58 volumio2 volumio[1722]: [Metrics] SpotifyConnect: 0s 197.85ms
May 23 15:33:58 volumio2 volumio[1722]: info: Done.
May 23 15:33:58 volumio2 volumio[3141]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443"
May 23 15:33:59 volumio2 volumio[3141]: [Vollibrespot] : Authenticated as "janwalhof" !
May 23 15:33:59 volumio2 volumio[3141]: [Vollibrespot] : Using alsa sink
May 23 15:33:59 volumio2 volumio[3141]: [Vollibrespot] : Metadata pipe established
May 23 15:33:59 volumio2 volumio[3141]: [Vollibrespot] : Country: "NL"
May 23 15:33:59 volumio2 volumio[3141]: [Vollibrespot] : Event: Volume { volume_to_mixer: 45875 }
May 23 15:33:59 volumio2 volumio[1722]: [SpotifyConnect] Volume Spotify: 70.00076295109483 Volumio: 70
May 23 15:33:59 volumio2 kernel: Under-voltage detected! (0x00050005)
May 23 15:33:59 volumio2 volumio[1722]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
May 23 15:33:59 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 23 15:33:59 volumio2 volumio[1722]: [SpotifyConnect] Closing metadata listener
May 23 15:33:59 volumio2 volumio[1722]: info: Disabling plugin spop
May 23 15:33:59 volumio2 volumio[1722]: info: Done.
May 23 15:33:59 volumio2 sudo[3155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service
May 23 15:33:59 volumio2 sudo[3155]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:33:59 volumio2 systemd[1]: Stopping Volspotconnect2 Daemon...
May 23 15:33:59 volumio2 systemd[1]: Stopped Volspotconnect2 Daemon.
May 23 15:33:59 volumio2 sudo[3155]: pam_unix(sudo:session): session closed for user root
May 23 15:33:59 volumio2 volumio[1722]: [SpotifyConnect] Vollibrespot Daemon service stoped!
May 23 15:34:00 volumio2 volumio[1722]: [SpotifyConnect] Stopping Vollibrespot daemon
May 23 15:34:00 volumio2 volumio[1722]: [SpotifyConnect] Closing metadata listener
May 23 15:34:00 volumio2 volumio[1722]: info: Disabling plugin volspotconnect2
May 23 15:34:00 volumio2 volumio[1722]: info: Done.
May 23 15:34:00 volumio2 sudo[3168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service
May 23 15:34:00 volumio2 sudo[3168]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:34:00 volumio2 systemd[1]: Stopping Volspotconnect2 Daemon...
May 23 15:34:00 volumio2 systemd[1]: Stopped Volspotconnect2 Daemon.
May 23 15:34:00 volumio2 sudo[3168]: pam_unix(sudo:session): session closed for user root
May 23 15:34:00 volumio2 volumio[1722]: [SpotifyConnect] Vollibrespot Daemon service stoped!
May 23 15:34:01 volumio2 volumio[1722]: info: Enabling plugin volspotconnect2
May 23 15:34:01 volumio2 volumio[1722]: info: Loading plugin "volspotconnect2"...
May 23 15:34:01 volumio2 volumio[1722]: info: PLUGIN START: volspotconnect2
May 23 15:34:01 volumio2 volumio[1722]: [SpotifyConnect] Creating VLS config file
May 23 15:34:01 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:34:01 volumio2 volumio[1722]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 23 15:34:01 volumio2 volumio[1722]: [SpotifyConnect] Starting metadata listener
May 23 15:34:01 volumio2 sudo[3181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service
May 23 15:34:01 volumio2 sudo[3181]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 23 15:34:01 volumio2 systemd[1]: Starting Volspotconnect2 Daemon...
May 23 15:34:01 volumio2 systemd[1]: Started Volspotconnect2 Daemon.
May 23 15:34:01 volumio2 sudo[3181]: pam_unix(sudo:session): session closed for user root
May 23 15:34:01 volumio2 volumio[3186]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07
May 23 15:34:01 volumio2 volumio[3186]: Reading Config from "volspotify.toml"
May 23 15:34:01 volumio2 volumio[3186]: [Vollibrespot] : Using Alsa backend with device: plughw:2
May 23 15:34:01 volumio2 volumio[1722]: [SpotifyConnect] Vollibrespot Daemon service started!
May 23 15:34:01 volumio2 volumio[1722]: [Metrics] SpotifyConnect: 0s 181.58ms
May 23 15:34:01 volumio2 volumio[1722]: info: Done.
May 23 15:34:02 volumio2 volumio[3186]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" }
May 23 15:34:02 volumio2 volumio[1722]: info: Checking Spotify Web API
May 23 15:34:02 volumio2 volumio[1722]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 23 15:34:02 volumio2 volumio[1722]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running
May 23 15:34:02 volumio2 volumio[1722]: at Socket._healthCheck (dgram.js:638:11)
May 23 15:34:02 volumio2 volumio[1722]: at Socket.send (dgram.js:435:8)
May 23 15:34:02 volumio2 volumio[1722]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21)
May 23 15:34:02 volumio2 volumio[1722]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23)
May 23 15:34:02 volumio2 volumio[1722]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2410:18)
May 23 15:34:02 volumio2 volumio[1722]: at ontimeout (timers.js:482:11)
May 23 15:34:02 volumio2 volumio[1722]: at tryOnTimeout (timers.js:317:5)
May 23 15:34:02 volumio2 volumio[1722]: at Timer.listOnTimeout (timers.js:277:5)
May 23 15:34:02 volumio2 volumio[1722]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 23 15:34:03 volumio2 sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-23 15:33
May 23 15:34:04 volumio2 sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae"
VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021"
VOLUMIO_VERSION="2.917"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"