-- Logs begin at Wed 2024-10-30 17:32:26 UTC, end at Wed 2024-10-30 17:45:01 UTC. -- Oct 30 17:44:01 volumio2 volumio[1015]: info: Oct 30 17:44:01 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:01 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:01 volumio2 volumio[1015]: info: Oct 30 17:44:01 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:01 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:01 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:01 volumio2 volumio[1015]: info: ------------------------------ 3ms Oct 30 17:44:01 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:01 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:01 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:01 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:01 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:01 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:01 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":538485,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:01 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:01 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:01 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:01 volumio2 volumio[1015]: info: ------------------------------ 20ms Oct 30 17:44:09 volumio2 volumio[1015]: info: Oct 30 17:44:09 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:09 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:09 volumio2 volumio[1015]: info: Oct 30 17:44:09 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:09 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:09 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:09 volumio2 volumio[1015]: info: ------------------------------ 3ms Oct 30 17:44:09 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:09 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:09 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:09 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 17:44:09 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:09 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:09 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:09 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:09 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:09 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:09 volumio2 volumio[1015]: info: ------------------------------ 26ms Oct 30 17:44:14 volumio2 volumio[1015]: info: Oct 30 17:44:14 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:14 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:14 volumio2 volumio[1015]: info: Oct 30 17:44:14 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:14 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:14 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:14 volumio2 volumio[1015]: info: ------------------------------ 2ms Oct 30 17:44:14 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:14 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:14 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:14 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:14 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:14 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:14 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":551177,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:14 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:14 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:14 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:14 volumio2 volumio[1015]: info: ------------------------------ 26ms Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 17:44:16 volumio2 volumio[1015]: info: Discovery: Getting this device information Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetState Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 17:44:16 volumio2 volumio[1015]: info: Discovery: Getting this device information Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetState Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:16 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetState Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 17:44:18 volumio2 volumio[1015]: info: Listing playlists Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetQueue Oct 30 17:44:18 volumio2 volumio[1015]: info: CoreStateMachine::getQueue Oct 30 17:44:18 volumio2 volumio[1015]: info: CorePlayQueue::getQueue Oct 30 17:44:19 volumio2 volumio[1015]: info: Oct 30 17:44:19 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:19 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:19 volumio2 volumio[1015]: info: Oct 30 17:44:19 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:19 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:19 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:19 volumio2 volumio[1015]: info: ------------------------------ 2ms Oct 30 17:44:19 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:19 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:19 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:19 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:19 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:19 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:19 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":556384,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:19 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:19 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:19 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:19 volumio2 volumio[1015]: info: ------------------------------ 20ms Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 30 17:44:26 volumio2 volumio[1015]: info: Oct 30 17:44:26 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:26 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:26 volumio2 volumio[1015]: info: Oct 30 17:44:26 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:26 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:26 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:26 volumio2 volumio[1015]: info: ------------------------------ 3ms Oct 30 17:44:26 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:26 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:26 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:26 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:26 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:26 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:26 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":563777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:26 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:26 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:26 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:26 volumio2 volumio[1015]: info: ------------------------------ 28ms Oct 30 17:44:31 volumio2 volumio[1015]: info: Oct 30 17:44:31 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:31 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:31 volumio2 volumio[1015]: info: Oct 30 17:44:31 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:31 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:31 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:31 volumio2 volumio[1015]: info: ------------------------------ 8ms Oct 30 17:44:31 volumio2 volumio[1015]: info: sendMpdCommand status took 8 milliseconds Oct 30 17:44:31 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:31 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:31 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 17:44:31 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:31 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:31 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":568883,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:31 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:31 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:31 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:31 volumio2 volumio[1015]: info: ------------------------------ 26ms Oct 30 17:44:36 volumio2 volumio[1015]: info: Oct 30 17:44:36 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:36 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:36 volumio2 volumio[1015]: info: Oct 30 17:44:36 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:36 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:36 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:36 volumio2 volumio[1015]: info: ------------------------------ 9ms Oct 30 17:44:36 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:36 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:36 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:36 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 17:44:36 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:36 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:36 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":573985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:36 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:36 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:36 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:36 volumio2 volumio[1015]: info: ------------------------------ 24ms Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 17:44:44 volumio2 volumio[1015]: info: Discovery: Getting this device information Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::volumioGetState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 30 17:44:44 volumio2 volumio[1015]: info: Oct 30 17:44:44 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:44 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:44 volumio2 volumio[1015]: info: Oct 30 17:44:44 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:44 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:44 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:44 volumio2 volumio[1015]: info: ------------------------------ 3ms Oct 30 17:44:44 volumio2 volumio[1015]: info: sendMpdCommand status took 3 milliseconds Oct 30 17:44:44 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:44 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:44 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 17:44:44 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:44 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:44 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":581781,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:44 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:44 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:44 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:44 volumio2 volumio[1015]: info: ------------------------------ 27ms Oct 30 17:44:49 volumio2 volumio[1015]: info: Oct 30 17:44:49 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:49 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:49 volumio2 volumio[1015]: info: Oct 30 17:44:49 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:49 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:49 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:49 volumio2 volumio[1015]: info: ------------------------------ 3ms Oct 30 17:44:49 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:49 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:49 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:49 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:49 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:49 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:49 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":587084,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:49 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:49 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:49 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:49 volumio2 volumio[1015]: info: ------------------------------ 21ms Oct 30 17:44:56 volumio2 volumio[1015]: info: Oct 30 17:44:56 volumio2 volumio[1015]: ---------------------------- MPD announces system playlist update Oct 30 17:44:56 volumio2 volumio[1015]: info: Ignoring MPD Status Update Oct 30 17:44:56 volumio2 volumio[1015]: info: Oct 30 17:44:56 volumio2 volumio[1015]: ---------------------------- MPD announces state update: player Oct 30 17:44:56 volumio2 volumio[1015]: info: ControllerMpd::getState Oct 30 17:44:56 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Oct 30 17:44:56 volumio2 volumio[1015]: info: ------------------------------ 2ms Oct 30 17:44:56 volumio2 volumio[1015]: info: sendMpdCommand status took 2 milliseconds Oct 30 17:44:56 volumio2 volumio[1015]: verbose: ControllerMpd::parseState Oct 30 17:44:56 volumio2 volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 17:44:56 volumio2 volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 30 17:44:56 volumio2 volumio[1015]: verbose: ControllerMpd::parseTrackInfo Oct 30 17:44:56 volumio2 volumio[1015]: info: ControllerMpd::pushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::servicePushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CorePlayQueue::getTrack 0 Oct 30 17:44:56 volumio2 volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":593777,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"INXS - This Time","artist":"INXS","album":null,"uri":"http://2.mystreaming.net/er/INXS/icecast.audio","trackType":"audio"} Oct 30 17:44:56 volumio2 volumio[1015]: verbose: CURRENT POSITION 0 Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreStateMachine::syncState stateService play Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreStateMachine::syncState currentStatus play Oct 30 17:44:56 volumio2 volumio[1015]: info: Received an update from plugin. extracting info from payload Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreStateMachine::pushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::volumioPushState Oct 30 17:44:56 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 17:44:56 volumio2 volumio[1015]: info: ------------------------------ 18ms Oct 30 17:45:00 volumio2 volumio[1015]: info: CALLMETHOD: music_service nanosound_cd saveReg [object Object] Oct 30 17:45:00 volumio2 volumio[1015]: info: CoreCommandRouter::executeOnPlugin: nanosound_cd , saveReg Oct 30 17:45:00 volumio2 volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 17:45:00 volumio2 volumio[1015]: TypeError: Cannot read property 'response' of undefined Oct 30 17:45:00 volumio2 volumio[1015]: at Request._callback (/data/plugins/music_service/nanosound_cd/index.js:40:10) Oct 30 17:45:00 volumio2 volumio[1015]: at self.callback (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:185:22) Oct 30 17:45:00 volumio2 volumio[1015]: at emitOne (events.js:116:13) Oct 30 17:45:00 volumio2 volumio[1015]: at Request.emit (events.js:211:7) Oct 30 17:45:00 volumio2 volumio[1015]: at Request.onRequestError (/data/plugins/music_service/nanosound_cd/node_modules/request/request.js:877:8) Oct 30 17:45:00 volumio2 volumio[1015]: at emitOne (events.js:116:13) Oct 30 17:45:00 volumio2 volumio[1015]: at ClientRequest.emit (events.js:211:7) Oct 30 17:45:00 volumio2 volumio[1015]: at Socket.socketErrorListener (_http_client.js:387:9) Oct 30 17:45:00 volumio2 volumio[1015]: at emitOne (events.js:116:13) Oct 30 17:45:00 volumio2 volumio[1015]: at Socket.emit (events.js:211:7) Oct 30 17:45:00 volumio2 volumio[1015]: at emitErrorNT (internal/streams/destroy.js:64:8) Oct 30 17:45:00 volumio2 volumio[1015]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Oct 30 17:45:00 volumio2 volumio[1015]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 30 17:45:00 volumio2 volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 17:45:01 volumio2 sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 17:44 Oct 30 17:45:01 volumio2 sudo[3091]: 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"