Jan 26 23:58:03 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:07 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:07 volumio22 volumio[1310]: info: Enabling MyMusic plugin upnp_browser Jan 26 23:58:07 volumio22 volumio[1310]: info: Enabling plugin upnp_browser Jan 26 23:58:07 volumio22 volumio[1310]: info: Loading plugin "upnp_browser"... Jan 26 23:58:07 volumio22 volumio[1310]: info: Starting UPNP Browser Jan 26 23:58:07 volumio22 volumio[1310]: info: PLUGIN START: upnp_browser Jan 26 23:58:07 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:58:07 volumio22 volumio[1310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 23:58:07 volumio22 volumio[1310]: info: [1769446687633] CoreMusicLibrary::Adding element Media Servers Jan 26 23:58:07 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 23:58:07 volumio22 volumio[1310]: info: UPNP Browser: Client initialized successfully Jan 26 23:58:07 volumio22 volumio[1310]: info: Done. Jan 26 23:58:11 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:15 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:19 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:23 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:25 volumio22 volumio[1310]: info: Enabling MyMusic plugin upnp Jan 26 23:58:25 volumio22 volumio[1310]: info: Enabling plugin upnp Jan 26 23:58:25 volumio22 volumio[1310]: info: Loading plugin "upnp"... Jan 26 23:58:25 volumio22 volumio[1310]: info: [1769446705106] Starting Upmpd Daemon Jan 26 23:58:25 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 23:58:25 volumio22 volumio[1310]: info: Done. Jan 26 23:58:27 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:31 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:35 volumio22 sudo[3519]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 23:58:35 volumio22 sudo[3519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:58:35 volumio22 sudo[3521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 23:58:35 volumio22 sudo[3521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:58:35 volumio22 sudo[3519]: pam_unix(sudo:session): session closed for user root Jan 26 23:58:35 volumio22 sudo[3521]: pam_unix(sudo:session): session closed for user root Jan 26 23:58:35 volumio22 sudo[3523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 23:58:35 volumio22 sudo[3523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:58:35 volumio22 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 23:58:35 volumio22 sudo[3523]: pam_unix(sudo:session): session closed for user root Jan 26 23:58:35 volumio22 volumio[1310]: info: Upmpdcli Daemon Started Jan 26 23:58:35 volumio22 upmpdcli[3543]: writing RSA key Jan 26 23:58:35 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:39 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:39 volumio22 volumio[1310]: info: Clearing queue after UPNP request Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::ClearQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::clearPlayQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::saveQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 5ms Jan 26 23:58:40 volumio22 volumio[1310]: info: sendMpdCommand status took 5 milliseconds Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: 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} Jan 26 23:58:40 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: No code Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 42ms Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 44ms Jan 26 23:58:40 volumio22 volumio[1310]: info: sendMpdCommand status took 43 milliseconds Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 42ms Jan 26 23:58:40 volumio22 volumio[1310]: info: sendMpdCommand status took 40 milliseconds Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:40 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: 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} Jan 26 23:58:40 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: No code Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: 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} Jan 26 23:58:40 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: No code Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 114ms Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 113ms Jan 26 23:58:40 volumio22 volumio[1310]: info: Clearing queue after UPNP request Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::ClearQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::stop Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::clearPlayQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::saveQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushQueue Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: info: Jan 26 23:58:40 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:40 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:40 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:58:40 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:40 volumio22 volumio[1310]: info: Starting UPNP Playback Jan 26 23:58:40 volumio22 volumio[1310]: info: Preparing playback through UPNP Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreCommandRouter::volumioGetState Jan 26 23:58:40 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:58:40 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 3ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:58:41 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 11 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 12 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 12 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: info: Jan 26 23:58:41 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 21 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 20 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 19 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 19 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 5 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand status took 4 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 81ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 81ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 82ms Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 71 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 71 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 71 milliseconds Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:58:41 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:58:41 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":185,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Happy Lucky Chappy","artist":"Ano","album":"BONE BORN BOMB","uri":"http://192.168.1.164:14225/qobuz/336533844.flac","trackType":"qobuz"} Jan 26 23:58:41 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:58:41 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 142ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 119ms Jan 26 23:58:41 volumio22 volumio[1310]: info: ------------------------------ 117ms Jan 26 23:58:43 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:47 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:51 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:55 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:58:59 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:03 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:07 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:08 volumio22 volumio[1310]: info: Clearing queue after UPNP request Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::ClearQueue Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::stop Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::clearPlayQueue Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::saveQueue Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushQueue Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:08 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:08 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:08 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:08 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:08 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:08 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:08 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:08 volumio22 volumio[1310]: info: Jan 26 23:59:08 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:08 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:08 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:08 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:08 volumio22 volumio[1310]: info: ------------------------------ 6ms Jan 26 23:59:08 volumio22 volumio[1310]: info: sendMpdCommand status took 4 milliseconds Jan 26 23:59:08 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:08 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:08 volumio22 volumio[1310]: 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} Jan 26 23:59:08 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:08 volumio22 volumio[1310]: info: No code Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:08 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:08 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: ------------------------------ 40ms Jan 26 23:59:09 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:09 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:09 volumio22 volumio[1310]: info: ------------------------------ 40ms Jan 26 23:59:09 volumio22 volumio[1310]: info: sendMpdCommand status took 41 milliseconds Jan 26 23:59:09 volumio22 volumio[1310]: info: ------------------------------ 40ms Jan 26 23:59:09 volumio22 volumio[1310]: info: sendMpdCommand status took 39 milliseconds Jan 26 23:59:09 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:09 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:09 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: 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} Jan 26 23:59:09 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: No code Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: 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} Jan 26 23:59:09 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService stop Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: No code Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:09 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:09 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:09 volumio22 volumio[1310]: info: ------------------------------ 104ms Jan 26 23:59:09 volumio22 volumio[1310]: info: ------------------------------ 103ms Jan 26 23:59:11 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:15 volumio22 volumio[1310]: info: Clearing queue after UPNP request Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreStateMachine::ClearQueue Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreStateMachine::stop Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 23:59:15 volumio22 volumio[1310]: info: CorePlayQueue::clearPlayQueue Jan 26 23:59:15 volumio22 volumio[1310]: info: CorePlayQueue::saveQueue Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushQueue Jan 26 23:59:15 volumio22 volumio[1310]: info: Jan 26 23:59:15 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:15 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:15 volumio22 volumio[1310]: info: Jan 26 23:59:15 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:15 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:15 volumio22 volumio[1310]: info: Jan 26 23:59:15 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:15 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:15 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:15 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:15 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:15 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:15 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:15 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:15 volumio22 volumio[1310]: info: Starting UPNP Playback Jan 26 23:59:15 volumio22 volumio[1310]: info: Preparing playback through UPNP Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreCommandRouter::volumioGetState Jan 26 23:59:15 volumio22 volumio[1310]: info: CorePlayQueue::getTrack 0 Jan 26 23:59:15 volumio22 volumio[1310]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 3ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 3ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 0ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces system playlist update Jan 26 23:59:16 volumio22 volumio[1310]: info: Ignoring MPD Status Update Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 2ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 1ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 9 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 9 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 10 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 34 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 29 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 30 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 29 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 28 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 91ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 90ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 91ms Jan 26 23:59:16 volumio22 volumio[1310]: info: Jan 26 23:59:16 volumio22 volumio[1310]: ---------------------------- MPD announces state update: player Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::getState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand clearerror took 88 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 83 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand status took 33 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 148ms Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 148ms Jan 26 23:59:16 volumio22 volumio[1310]: info: sendMpdCommand playlistinfo took 26 milliseconds Jan 26 23:59:16 volumio22 volumio[1310]: verbose: ControllerMpd::parseTrackInfo Jan 26 23:59:16 volumio22 volumio[1310]: info: ControllerMpd::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::servicePushState Jan 26 23:59:16 volumio22 volumio[1310]: verbose: In UPNP mode Jan 26 23:59:16 volumio22 volumio[1310]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":186,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ちゅ、多様性。","artist":"Ano","album":"ちゅ、多様性。","uri":"http://192.168.1.164:14225/qobuz/184236859","trackType":"qobuz"} Jan 26 23:59:16 volumio22 volumio[1310]: verbose: CURRENT POSITION 0 Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState stateService pause Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:16 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:16 volumio22 volumio[1310]: info: ------------------------------ 75ms Jan 26 23:59:19 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:23 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:27 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:45 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:47 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:49 volumio22 kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Jan 26 23:59:49 volumio22 kernel: usb 1-1.2: New USB device found, idVendor=2972, idProduct=0107, bcdDevice= 1.05 Jan 26 23:59:49 volumio22 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jan 26 23:59:49 volumio22 kernel: usb 1-1.2: Product: FIIO K17 Jan 26 23:59:49 volumio22 kernel: usb 1-1.2: Manufacturer: GuangZhou FiiO Electronics Co.,Ltd Jan 26 23:59:49 volumio22 kernel: hid-generic 0003:2972:0107.0001: hiddev96,hidraw0: USB HID v1.10 Device [GuangZhou FiiO Electronics Co.,Ltd FIIO K17] on usb-0000:01:00.0-1.2/input3 Jan 26 23:59:49 volumio22 kernel: usbcore: registered new interface driver snd-usb-audio Jan 26 23:59:49 volumio22 volumio[1310]: info: Jan 26 23:59:49 volumio22 volumio[1310]: ---------------------------- USB Audio Device Attached Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::Close All Modals sent Jan 26 23:59:49 volumio22 volumio[1310]: info: Preparing to save Alsa Options, stopping services first Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::volumioGetState Jan 26 23:59:49 volumio22 volumio[1310]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"FIIO K17","alsacard":"K17"},"i2s":false} Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 23:59:49 volumio22 volumio[1310]: info: Device 5 does not have any Mixer Control Available Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 26 23:59:49 volumio22 volumio[1310]: info: Updating Volume Controller Parameters: Device: 5 Name: FIIO K17 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 26 23:59:49 volumio22 volumio[1310]: info: Disabling external Volume Control Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:49 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:49 volumio22 volumio[1310]: info: Preparing to generate the ALSA configuration file Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 23:59:50 volumio22 volumio[1310]: info: Asound.conf file unchanged, so no further update is needed Jan 26 23:59:50 volumio22 volumio[1310]: info: Output device has changed, restarting MPD Jan 26 23:59:50 volumio22 volumio[1310]: info: Output device has changed, restarting Shairport Sync Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 23:59:50 volumio22 sudo[3970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 23:59:50 volumio22 sudo[3970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:59:50 volumio22 sudo[3970]: pam_unix(sudo:session): session closed for user root Jan 26 23:59:50 volumio22 sudo[3972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 23:59:50 volumio22 sudo[3972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:59:50 volumio22 volumio[1310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 23:59:50 volumio22 volumio[1310]: info: Disabling MyMusic plugin upnp_browser Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 23:59:50 volumio22 volumio[1310]: info: Disabling plugin upnp_browser Jan 26 23:59:50 volumio22 volumio[1310]: info: Done. Jan 26 23:59:50 volumio22 volumio[1310]: info: MPD Permissions set Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 23:59:50 volumio22 volumio[1310]: info: Starting Shairport Sync Jan 26 23:59:50 volumio22 sudo[3985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 23:59:50 volumio22 sudo[3985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:59:50 volumio22 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 23:59:50 volumio22 sudo[3985]: pam_unix(sudo:session): session closed for user root Jan 26 23:59:50 volumio22 volumio[1310]: info: Shairport-Sync Started Jan 26 23:59:50 volumio22 systemd[1]: mpd.service: Deactivated successfully. Jan 26 23:59:50 volumio22 systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 23:59:50 volumio22 systemd[1]: mpd.service: Consumed 11.676s CPU time. Jan 26 23:59:50 volumio22 systemd[1]: mpd.socket: Deactivated successfully. Jan 26 23:59:50 volumio22 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 23:59:50 volumio22 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 23:59:50 volumio22 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 23:59:50 volumio22 systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 23:59:50 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 26 23:59:50 volumio22 sudo[4003]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 23:59:50 volumio22 sudo[4003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 23:59:50 volumio22 sudo[4003]: pam_unix(sudo:session): session closed for user root Jan 26 23:59:51 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:51 volumio22 volumio[1310]: info: Disabling MyMusic plugin upnp Jan 26 23:59:51 volumio22 sudo[4022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 26 23:59:51 volumio22 sudo[4022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 23:59:51 volumio22 systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Jan 26 23:59:51 volumio22 mpd[4005]: 2026-01-26T23:59:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 23:59:52 volumio22 systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 23:59:52 volumio22 sudo[3972]: pam_unix(sudo:session): session closed for user root Jan 26 23:59:52 volumio22 volumio[1310]: error: updateQueue error: null Jan 26 23:59:53 volumio22 volumio[1310]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 26 23:59:53 volumio22 volumio[1310]: info: CoreStateMachine::getcurrentVolume Jan 26 23:59:53 volumio22 volumio[1310]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 23:59:53 volumio22 volumio[1310]: info: CoreStateMachine::pushState Jan 26 23:59:53 volumio22 volumio[1310]: info: CoreCommandRouter::volumioPushState Jan 26 23:59:55 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 23:59:58 volumio22 volumio[1310]: info: Enabling MyMusic plugin upnp Jan 26 23:59:58 volumio22 volumio[1310]: info: Enabling plugin upnp Jan 26 23:59:58 volumio22 volumio[1310]: info: Loading plugin "upnp"... Jan 26 23:59:58 volumio22 volumio[1310]: info: [1769446798071] Starting Upmpd Daemon Jan 26 23:59:58 volumio22 volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 23:59:58 volumio22 volumio[1310]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 23:59:58 volumio22 volumio[1310]: Error: listen EADDRINUSE: address already in use :::6599 Jan 26 23:59:58 volumio22 volumio[1310]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Jan 26 23:59:58 volumio22 volumio[1310]: at listenInCluster (node:net:1920:12) Jan 26 23:59:58 volumio22 volumio[1310]: at Server.listen (node:net:2008:7) Jan 26 23:59:58 volumio22 volumio[1310]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 26 23:59:58 volumio22 volumio[1310]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 26 23:59:58 volumio22 volumio[1310]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 26 23:59:58 volumio22 volumio[1310]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 26 23:59:58 volumio22 volumio[1310]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jan 26 23:59:58 volumio22 volumio[1310]: code: 'EADDRINUSE', Jan 26 23:59:58 volumio22 volumio[1310]: errno: -98, Jan 26 23:59:58 volumio22 volumio[1310]: syscall: 'listen', Jan 26 23:59:58 volumio22 volumio[1310]: address: '::', Jan 26 23:59:58 volumio22 volumio[1310]: port: 6599 Jan 26 23:59:58 volumio22 volumio[1310]: } Jan 26 23:59:58 volumio22 volumio[1310]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 23:59:58 volumio22 sudo[4068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 23:58' Jan 26 23:59:58 volumio22 sudo[4068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"