Mar 04 19:23:03 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:03 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 88. Mar 04 19:23:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:04 volumio upmpdcli[5936]: Could not open config: /tmp/upmpdcli.conf Mar 04 19:23:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:23:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 04 19:23:08 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:08 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:13 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:13 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:18 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:18 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 89. Mar 04 19:23:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:19 volumio upmpdcli[5985]: Could not open config: /tmp/upmpdcli.conf Mar 04 19:23:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:23:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 04 19:23:23 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:23 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:28 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:28 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:29 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 19:23:29 volumio volumio[1064]: info: CURURI: music-library Mar 04 19:23:29 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand update Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: info: Mar 04 19:23:32 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand update took 0 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 1 milliseconds Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: info: Command Router : Notfying DB Updatetrue Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: info: Mar 04 19:23:32 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: info: Mar 04 19:23:32 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 37 milliseconds Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: info: Command Router : Notfying DB Updatetrue Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: info: Command Router : Notfying DB Updatetrue Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264205,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:32 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: ------------------------------ 63ms Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 04 19:23:32 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:32 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264205,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:32 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264205,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:32 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:32 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:32 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:32 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:32 volumio volumio[1064]: info: ------------------------------ 31ms Mar 04 19:23:32 volumio volumio[1064]: info: ------------------------------ 31ms Mar 04 19:23:33 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:33 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:34 volumio volumio[1064]: info: MPD Database updated - AlbumList cache refreshed Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: database Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: MPD Database updated - AlbumList cache refreshed Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: database Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: MPD Database updated - AlbumList cache refreshed Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: database Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: Mar 04 19:23:34 volumio volumio[1064]: ---------------------------- MPD announces state update: update Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 8 milliseconds Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: info: Command Router : Notfying DB Updatefalse Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: info: Command Router : Notfying DB Updatefalse Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 26 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 26 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand status took 26 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: info: Command Router : Notfying DB Updatefalse Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::Close All Modals sent Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 43ms Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 43ms Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 43ms Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 43ms Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 19:23:34 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":266441,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02 - Moderation_002_flac.wav","artist":null,"album":null,"uri":"INTERNAL/Beady Belle - Home 2001/02 - Moderation_002_flac.wav","trackType":"wav"} Mar 04 19:23:34 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:34 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:34 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:34 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 45ms Mar 04 19:23:34 volumio volumio[1064]: info: ------------------------------ 45ms Mar 04 19:23:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 90. Mar 04 19:23:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:34 volumio upmpdcli[6040]: Could not open config: /tmp/upmpdcli.conf Mar 04 19:23:34 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:23:34 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 04 19:23:36 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 19:23:36 volumio volumio[1064]: info: CURURI: music-library/INTERNAL Mar 04 19:23:36 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:38 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 19:23:38 volumio volumio[1064]: info: CURURI: music-library/INTERNAL/CHRIS SPHEERIS Mar 04 19:23:38 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:38 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:38 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:43 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 04 19:23:43 volumio volumio[1064]: info: CURURI: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse Mar 04 19:23:43 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/01. Magaya.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/03. The joy of This Longing.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/04. Marionette.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/05. The New West.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/06. Shahzada.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/07. Remain In Motion.wav Mar 04 19:23:43 volumio volumio[1064]: info: Preloading song: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/08. Invisible Hands.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/01. Magaya.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F01.%20Magaya.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/01. Magaya.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F02.%20Dancing%20with%20The%20Muse.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/03. The joy of This Longing.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F03.%20The%20joy%20of%20This%20Longing.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/03. The joy of This Longing.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/04. Marionette.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F04.%20Marionette.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/04. Marionette.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/05. The New West.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F05.%20The%20New%20West.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/05. The New West.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/06. Shahzada.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F06.%20Shahzada.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/06. Shahzada.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/07. Remain In Motion.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F07.%20Remain%20In%20Motion.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/07. Remain In Motion.wav Mar 04 19:23:43 volumio volumio[1064]: info: Exploding uri music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/08. Invisible Hands.wav in service mpd Mar 04 19:23:43 volumio volumio[1064]: info: ALBUMART /albumart?cacheid=872&path=%2Fmnt%2FINTERNAL%2FCHRIS%20SPHEERIS%2FChris%20Spheeris%201999%20-%20Dancing%20with%20The%20Muse%2F08.%20Invisible%20Hands.wav&metadata=false Mar 04 19:23:43 volumio volumio[1064]: info: URI /mnt/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/08. Invisible Hands.wav Mar 04 19:23:43 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:43 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:44 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::ClearQueue Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::stPlaybackTimer Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::serviceStop Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::serviceStop Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::stop Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::clearPlayQueue Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::saveQueue Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Mar 04 19:23:44 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/01. Magaya.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/01. Magaya.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::saveQueue Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPlay Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::play index 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::addQueueItems Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::addQueueItems Mar 04 19:23:44 volumio volumio[1064]: info: Preload queue cleared Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/03. The joy of This Longing.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/03. The joy of This Longing.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/04. Marionette.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/04. Marionette.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/05. The New West.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/05. The New West.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/06. Shahzada.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/06. Shahzada.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/07. Remain In Motion.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/07. Remain In Motion.wav Mar 04 19:23:44 volumio volumio[1064]: info: Adding Item to queue: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/08. Invisible Hands.wav Mar 04 19:23:44 volumio volumio[1064]: info: Using cached record of: music-library/INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/08. Invisible Hands.wav Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::saveQueue Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::play index undefined Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::startPlaybackTimer Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand stop took 5 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand stop took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand clear took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 0 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav" Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: No code Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: No code Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 5ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 5ms Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: error: updateQueue error: null Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 5ms Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand add "INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav" took 5 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand play Mar 04 19:23:44 volumio volumio[1064]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 7ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 3ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 3ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 3ms Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand play took 2 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces system playlist update Mar 04 19:23:44 volumio volumio[1064]: info: Ignoring MPD Status Update Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 1ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 1ms Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: info: Mar 04 19:23:44 volumio volumio[1064]: ---------------------------- MPD announces state update: player Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::getState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 3 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:44 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:44 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 9ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 9ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 9ms Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseState Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:44 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:44 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 11ms Mar 04 19:23:44 volumio volumio[1064]: info: ------------------------------ 10ms Mar 04 19:23:44 volumio volumio[1064]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 19:23:44 volumio volumio[1064]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:23:44 volumio volumio[1064]: info: ControllerMpd::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::servicePushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"02. Dancing with The Muse.wav","artist":null,"album":null,"uri":"INTERNAL/CHRIS SPHEERIS/Chris Spheeris 1999 - Dancing with The Muse/02. Dancing with The Muse.wav","trackType":"wav"} Mar 04 19:23:44 volumio volumio[1064]: verbose: CURRENT POSITION 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState stateService play Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:23:44 volumio volumio[1064]: info: Received an update from plugin. extracting info from payload Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:44 volumio volumio[1064]: info: CoreStateMachine::pushState Mar 04 19:23:44 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:23:44 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState Mar 04 19:23:45 volumio volumio[1064]: info: ------------------------------ 14ms Mar 04 19:23:48 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:48 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:50 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 91. Mar 04 19:23:50 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:50 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 04 19:23:50 volumio upmpdcli[6089]: Could not open config: /tmp/upmpdcli.conf Mar 04 19:23:50 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:23:50 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 04 19:23:53 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:53 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:58 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:23:58 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:23:59 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Mar 04 19:23:59 volumio volumio[1064]: info: CorePlayQueue::getTrack 1 Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: info: Retrieving Cloud Streaming UI Mar 04 19:24:01 volumio volumio[1064]: info: Getting Tidal Cloud Configuration Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: info: Getting Qobuz Cloud Configuration Mar 04 19:24:01 volumio volumio[1064]: info: Asking plugin for UI Config Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: info: Getting Spotify Cloud Configuration Mar 04 19:24:01 volumio volumio[1064]: info: Asking plugin for UI Config Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: info: Saving Spotify Acccount Mar 04 19:24:01 volumio volumio[1064]: info: Got Tidal Cloud Configuration Mar 04 19:24:01 volumio volumio[1064]: info: Got it Mar 04 19:24:01 volumio volumio[1064]: info: Got it Mar 04 19:24:01 volumio volumio[1064]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:24:01 volumio volumio[1064]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 04 19:24:01 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 04 19:24:03 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:24:03 volumio volumio[1064]: info: Discovery: Restarting Browsing Mar 04 19:24:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 04 19:24:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 04 19:24:04 volumio dbus-daemon[874]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 19:24:04 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:24:04 volumio volumio[1064]: Error: dns service error: refused Mar 04 19:24:04 volumio volumio[1064]: at new Browser (/volumio/node_modules/mdns/lib/browser.js:88:10) Mar 04 19:24:04 volumio volumio[1064]: at Object.create [as createBrowser] (/volumio/node_modules/mdns/lib/browser.js:116:10) Mar 04 19:24:04 volumio volumio[1064]: at ControllerVolumioDiscovery.browseForService (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:718:32) Mar 04 19:24:04 volumio volumio[1064]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 04 19:24:04 volumio volumio[1064]: at ControllerNetworkfs.discoverShares (/volumio/app/plugins/system_controller/networkfs/index.js:826:36) Mar 04 19:24:04 volumio volumio[1064]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 04 19:24:04 volumio volumio[1064]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1614:45) Mar 04 19:24:04 volumio volumio[1064]: at Socket.emit (node:events:514:28) Mar 04 19:24:04 volumio volumio[1064]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 04 19:24:04 volumio volumio[1064]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 04 19:24:04 volumio volumio[1064]: errorCode: -65553 Mar 04 19:24:04 volumio volumio[1064]: } Mar 04 19:24:04 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:24:04 volumio sudo[6140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 19:23' Mar 04 19:24:04 volumio sudo[6140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"