-- Logs begin at Thu 2019-02-14 10:12:00 GMT, end at Fri 2024-08-23 11:06:23 BST. -- Aug 23 11:05:03 volumio volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 11:05:03 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 11:05:04 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 23 11:05:04 volumio sudo[18421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 23 11:05:04 volumio sudo[18432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 23 11:05:04 volumio sudo[18429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 23 11:05:04 volumio sudo[18429]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio sudo[18432]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio sudo[18421]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio sudo[18417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 23 11:05:04 volumio sudo[18417]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio sudo[18437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 11:05:04 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 23 11:05:04 volumio sudo[18437]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 23 11:05:04 volumio sudo[18443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 11:05:04 volumio sudo[18443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:04 volumio sudo[18443]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18432]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18417]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18437]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18429]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18421]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:04 volumio sudo[18449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 23 11:05:04 volumio sudo[18449]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 11:05:05 volumio sudo[18449]: pam_unix(sudo:session): session closed for user root Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 23 11:05:23 volumio volumio[991]: info: Getting Alsa Cards List without I2S DAC Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 23 11:05:23 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 23 11:05:47 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:47 volumio volumio[991]: info: CorePlayQueue::getTrack 2 Aug 23 11:05:47 volumio volumio[991]: info: Prefetching next song Aug 23 11:05:47 volumio volumio[991]: info: DOING PREFETCH IN MPD Aug 23 11:05:47 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand add "NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac" Aug 23 11:05:47 volumio volumio[991]: info: Aug 23 11:05:47 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:47 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:47 volumio volumio[991]: info: sendMpdCommand add "NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac" took 3 milliseconds Aug 23 11:05:47 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 23 11:05:47 volumio volumio[991]: info: Aug 23 11:05:47 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:47 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:47 volumio volumio[991]: info: Aug 23 11:05:47 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:47 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:47 volumio volumio[991]: info: ------------------------------ 6ms Aug 23 11:05:47 volumio volumio[991]: info: sendMpdCommand consume 1 took 5 milliseconds Aug 23 11:05:47 volumio volumio[991]: info: ------------------------------ 4ms Aug 23 11:05:47 volumio volumio[991]: info: ------------------------------ 2ms Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:52 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:52 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces system playlist update Aug 23 11:05:52 volumio volumio[991]: info: Ignoring MPD Status Update Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 15ms Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 7 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 8ms Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 6 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 6ms Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 5 milliseconds Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: Aug 23 11:05:52 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 9 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 6 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand status took 5 milliseconds Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseState Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 83ms Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 75ms Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 73ms Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 60 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 23 11:05:52 volumio volumio[991]: info: sendMpdCommand playlistinfo took 58 milliseconds Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: verbose: ControllerMpd::parseTrackInfo Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ControllerMpd::pushState Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::servicePushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"707 Kbps","isStreaming":false,"title":"Where Do You Think You're Going?","artist":"Dire Straits","album":"Communiqué","uri":"NAS/omv/Dire Straits/Communiqué [1979]/03. Where Do You Think You're Going.flac","trackType":"flac"} Aug 23 11:05:52 volumio volumio[991]: verbose: CURRENT POSITION 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState stateService play Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::syncState currentStatus play Aug 23 11:05:52 volumio volumio[991]: info: Received an update from plugin. extracting info from payload Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 1 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 123ms Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 117ms Aug 23 11:05:52 volumio volumio[991]: info: ------------------------------ 116ms Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::startPlaybackTimer Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 2 Aug 23 11:05:52 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:05:52 volumio volumio[991]: info: CorePlayQueue::getTrack 2 Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 11:05:52 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:06:18 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 23 11:06:18 volumio volumio[991]: info: CURURI: music-library/NAS/omv/Queens of the Stone Age Aug 23 11:06:18 volumio volumio[991]: info: Preload queue cleared Aug 23 11:06:22 volumio volumio[991]: info: Preload queue cleared Aug 23 11:06:22 volumio volumio[991]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::ClearQueue Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::stop Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::stPlaybackTimer Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::updateTrackBlock Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::getTrackBlock Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::pushState Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::getTrack 2 Aug 23 11:06:22 volumio volumio[991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 11:06:22 volumio volumio[991]: info: CoreCommandRouter::volumioPushState Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::serviceStop Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::getTrack 2 Aug 23 11:06:22 volumio volumio[991]: info: CoreCommandRouter::serviceStop Aug 23 11:06:22 volumio volumio[991]: info: ControllerMpd::stop Aug 23 11:06:22 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::clearPlayQueue Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::saveQueue Aug 23 11:06:22 volumio volumio[991]: info: CoreCommandRouter::volumioPushQueue Aug 23 11:06:22 volumio volumio[991]: info: CoreStateMachine::addQueueItems Aug 23 11:06:22 volumio volumio[991]: info: CorePlayQueue::addQueueItems Aug 23 11:06:22 volumio volumio[991]: info: Preload queue cleared Aug 23 11:06:22 volumio volumio[991]: info: Adding Item to queue: music-library/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork Aug 23 11:06:22 volumio volumio[991]: info: Exploding uri music-library/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork in service mpd Aug 23 11:06:22 volumio volumio[991]: info: sendMpdCommand stop took 39 milliseconds Aug 23 11:06:22 volumio volumio[991]: info: Aug 23 11:06:22 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:06:22 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:06:22 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:06:22 volumio volumio[991]: info: Aug 23 11:06:22 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:06:22 volumio volumio[991]: info: ControllerMpd::getState Aug 23 11:06:22 volumio volumio[991]: verbose: ControllerMpd::sendMpdCommand status Aug 23 11:06:22 volumio volumio[991]: info: Aug 23 11:06:22 volumio volumio[991]: ---------------------------- MPD announces state update: player Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F01%20-%20Keep%20Your%20Eyes%20Peeled.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/01 - Keep Your Eyes Peeled.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F02%20-%20I%20Sat%20by%20the%20Ocean.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/02 - I Sat by the Ocean.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F03%20-%20The%20Vampyre%20of%20Time%20and%20Memory.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/03 - The Vampyre of Time and Memory.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F04%20-%20If%20I%20Had%20a%20Tail.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/04 - If I Had a Tail.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F05%20-%20My%20God%20Is%20the%20Sun.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/05 - My God Is the Sun.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F06%20-%20Kalopsia.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/06 - Kalopsia.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F07%20-%20Fairweather%20Friends.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/07 - Fairweather Friends.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F08%20-%20Smooth%20Sailing.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/08 - Smooth Sailing.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F09%20-%20I%20Appear%20Missing.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/09 - I Appear Missing.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2F10%20-%20Like%20Clockwork.flac&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/10 - Like Clockwork.flac Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2FQueens%20of%20the%20Stone%20Age%20Like%20Clockwork.cue&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/Queens of the Stone Age Like Clockwork.cue Aug 23 11:06:22 volumio volumio[991]: info: ALBUMART /albumart?cacheid=901&web=Queens%20of%20the%20Stone%20Age/%E2%80%A6Like%20Clockwork/medium&path=%2Fmnt%2FNAS%2Fomv%2FQueens%20of%20the%20Stone%20Age%2F2013%20-%20...Like%20Clockwork%2FQueens%20of%20the%20Stone%20Age%20Like%20Clockwork.cue&metadata=false Aug 23 11:06:22 volumio volumio[991]: info: URI /mnt/NAS/omv/Queens of the Stone Age/2013 - ...Like Clockwork/Queens of the Stone Age Like Clockwork.cue Aug 23 11:06:22 volumio volumio[991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 11:06:22 volumio volumio[991]: Error: Unable to resolve or reject the same promise twice Aug 23 11:06:22 volumio volumio[991]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 23 11:06:22 volumio volumio[991]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 23 11:06:22 volumio volumio[991]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 23 11:06:22 volumio volumio[991]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 23 11:06:22 volumio volumio[991]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 23 11:06:22 volumio volumio[991]: at Socket.emit (events.js:315:20) Aug 23 11:06:22 volumio volumio[991]: at addChunk (internal/streams/readable.js:309:12) Aug 23 11:06:22 volumio volumio[991]: at readableAddChunk (internal/streams/readable.js:280:11) Aug 23 11:06:22 volumio volumio[991]: at Socket.Readable.push (internal/streams/readable.js:223:10) Aug 23 11:06:22 volumio volumio[991]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 23 11:06:22 volumio volumio[991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 11:06:23 volumio sudo[18672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 11:05 Aug 23 11:06:23 volumio sudo[18672]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"