-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Sun 2024-12-01 13:44:16 +07. -- Dec 01 13:43:00 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 01 13:43:00 volumio volumio[975]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_explore%22%7D%7D Dec 01 13:43:00 volumio volumio[975]: error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Dec 01 13:43:00 volumio volumio[975]: at HTTPClient. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Dec 01 13:43:00 volumio volumio[975]: at Generator.next () Dec 01 13:43:00 volumio volumio[975]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Dec 01 13:43:00 volumio volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 01 13:43:00 volumio volumio[975]: error: Failed to execute browseSource: Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Dec 01 13:43:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri Dec 01 13:43:02 volumio volumio[975]: info: [ytmusic-browse] browseUri: ytmusic/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEmusic_library_landing%22%7D%7D Dec 01 13:43:02 volumio volumio[975]: error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Dec 01 13:43:02 volumio volumio[975]: at HTTPClient. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:9501:13) Dec 01 13:43:02 volumio volumio[975]: at Generator.next () Dec 01 13:43:02 volumio volumio[975]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Dec 01 13:43:02 volumio volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Dec 01 13:43:02 volumio volumio[975]: error: Failed to execute browseSource: Error: Request to https://www.youtube.com/youtubei/v1/browse?prettyPrint=false&alt=json failed with status 400 Dec 01 13:43:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:06 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:07 volumio ntpd[773]: Soliciting pool server 115.165.161.155 Dec 01 13:43:07 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:07 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:08 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:10 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:13 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:13 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:13 volumio ntpd[773]: Soliciting pool server 2404:fbc0:0:2046::c Dec 01 13:43:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:14 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:43:16 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37929 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37930 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37931 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37932 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37933 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37934 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37935 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37936 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37937 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37938 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37939 Dec 01 13:43:16 volumio volumio[975]: info: Preloading song: upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37940 Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37929 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37930 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37931 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37932 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37933 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37934 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37935 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37936 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37937 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37938 in service upnp_browser Dec 01 13:43:16 volumio ntpd[773]: Soliciting pool server 115.165.161.155 Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37939 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/http://192.168.1.7:50001/ContentDirectory/control@22$@37940 in service upnp_browser Dec 01 13:43:16 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:16 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 01 13:43:16 volumio volumio[975]: info: CoreStateMachine::ClearQueue Dec 01 13:43:16 volumio volumio[975]: info: CoreStateMachine::stop Dec 01 13:43:16 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 13:43:16 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Dec 01 13:43:16 volumio volumio[975]: info: CorePlayQueue::saveQueue Dec 01 13:43:16 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Dec 01 13:43:16 volumio volumio[975]: info: CoreStateMachine::addQueueItems Dec 01 13:43:16 volumio volumio[975]: info: CorePlayQueue::addQueueItems Dec 01 13:43:16 volumio volumio[975]: info: Preload queue cleared Dec 01 13:43:16 volumio volumio[975]: info: Adding Item to queue: upnp/folder/http://192.168.1.7:50001/ContentDirectory/control@22$4123 Dec 01 13:43:16 volumio volumio[975]: info: Exploding uri upnp/folder/http://192.168.1.7:50001/ContentDirectory/control@22$4123 in service upnp_browser Dec 01 13:43:17 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Dec 01 13:43:17 volumio volumio[975]: info: CorePlayQueue::saveQueue Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Dec 01 13:43:17 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Dec 01 13:43:17 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::play index 0 Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::stop Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::play index undefined Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 13:43:17 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Dec 01 13:43:17 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:17 volumio volumio[975]: info: [1733035397172] ControllerUPNPBrowser::clearAddPlayTrack Dec 01 13:43:17 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 13:43:17 volumio volumio[975]: info: sendMpdCommand stop took 10 milliseconds Dec 01 13:43:17 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: sendMpdCommand clear took 5 milliseconds Dec 01 13:43:17 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.7:50002/m/NDLNA/37929.wav" Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: error: updateQueue error: null Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 19ms Dec 01 13:43:17 volumio ntpd[773]: Soliciting pool server 115.165.161.155 Dec 01 13:43:17 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.7:50002/m/NDLNA/37929.wav" Dec 01 13:43:17 volumio volumio[975]: error: updateQueue error: null Dec 01 13:43:17 volumio volumio[975]: error: updateQueue error: null Dec 01 13:43:17 volumio volumio[975]: error: updateQueue error: null Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 768ms Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 767ms Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 764ms Dec 01 13:43:17 volumio volumio[975]: info: sendMpdCommand add "http://192.168.1.7:50002/m/NDLNA/37929.wav" took 5 milliseconds Dec 01 13:43:17 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 13:43:17 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: Dec 01 13:43:17 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:43:17 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 12ms Dec 01 13:43:17 volumio volumio[975]: info: sendMpdCommand play took 9 milliseconds Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 9ms Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 6ms Dec 01 13:43:17 volumio volumio[975]: info: ------------------------------ 5ms Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 18 milliseconds Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 20 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 18 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 15 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 32ms Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: Dec 01 13:43:18 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 9 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 7 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand status took 4 milliseconds Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 188ms Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 187ms Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 196ms Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 163 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 162 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 162 milliseconds Dec 01 13:43:18 volumio volumio[975]: info: sendMpdCommand playlistinfo took 163 milliseconds Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:18 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:18 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:18 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 424ms Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 416ms Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 416ms Dec 01 13:43:18 volumio volumio[975]: info: ------------------------------ 415ms Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:18 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio sudo[2241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:18 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:18 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:18 volumio sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:18 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:18 volumio sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:18 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio systemd[1]: Started peppymeterbasic Daemon. Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio sudo[2255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio sudo[2257]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio sudo[2267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2267]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:19 volumio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2270]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2274]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2270]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:19 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:19 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio sudo[2274]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:19 volumio volumio[975]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 01 13:43:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 13:43:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 13:43:20 volumio volumio[975]: info: Discovery: Getting this device information Dec 01 13:43:20 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:43:20 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 13:43:23 volumio volumio[2249]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:43:23 volumio volumio[2249]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:43:23 volumio volumio[2249]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:43:26 volumio volumio[975]: info: VolumeController::SetAlsaVolume+ Dec 01 13:43:26 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:26 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:26 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:26 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:26 volumio volumio[975]: info: Dec 01 13:43:26 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:26 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:26 volumio volumio[975]: info: Dec 01 13:43:26 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:26 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:26 volumio volumio[975]: info: Dec 01 13:43:26 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:26 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:26 volumio volumio[975]: info: Dec 01 13:43:26 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:26 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:26 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Dec 01 13:43:26 volumio volumio[975]: info: sendMpdCommand status took 15 milliseconds Dec 01 13:43:26 volumio volumio[975]: info: sendMpdCommand status took 13 milliseconds Dec 01 13:43:26 volumio volumio[975]: info: sendMpdCommand status took 10 milliseconds Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:26 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:26 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:26 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:27 volumio volumio[975]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 01 13:43:27 volumio volumio[975]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 01 13:43:27 volumio volumio[975]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 01 13:43:27 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:27 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:27 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:27 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9220,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:27 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:27 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:27 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:27 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9220,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:27 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:27 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:27 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:27 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:27 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9220,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:27 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:27 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:27 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:27 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9220,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:27 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:27 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:27 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:27 volumio volumio[975]: info: ------------------------------ 348ms Dec 01 13:43:27 volumio volumio[975]: info: ------------------------------ 345ms Dec 01 13:43:27 volumio volumio[975]: info: ------------------------------ 347ms Dec 01 13:43:27 volumio volumio[975]: info: ------------------------------ 344ms Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio sudo[2323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2323]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:27 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio sudo[2323]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:27 volumio sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:27 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:27 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:27 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:27 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 13:43:34 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 01 13:43:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 13:43:42 volumio volumio[975]: info: VolumeController::SetAlsaVolume96 Dec 01 13:43:42 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:42 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:42 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:42 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:42 volumio volumio[975]: info: Dec 01 13:43:42 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:42 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:42 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:42 volumio volumio[975]: info: Dec 01 13:43:42 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:42 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:42 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:42 volumio volumio[975]: info: Dec 01 13:43:42 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:42 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:42 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:42 volumio sudo[2368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:42 volumio volumio[975]: info: Dec 01 13:43:42 volumio volumio[975]: ---------------------------- MPD announces state update: mixer Dec 01 13:43:42 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:42 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:43 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand status took 53 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand status took 46 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand status took 37 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand status took 31 milliseconds Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 13:43:43 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:43 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:43:43 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:43 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:43 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25213,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:43 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:43 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:43 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:43 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25213,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:43 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:43 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:43 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:43 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25213,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:43 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:43 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:43 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:43 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25213,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37929.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37929.wav","trackType":"wav"} Dec 01 13:43:43 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:43 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:43 volumio volumio[975]: info: ------------------------------ 340ms Dec 01 13:43:43 volumio volumio[975]: info: ------------------------------ 330ms Dec 01 13:43:43 volumio volumio[975]: info: ------------------------------ 320ms Dec 01 13:43:43 volumio volumio[975]: info: ------------------------------ 314ms Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2377]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:43 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:43 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:43 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:43 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:43 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:43 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:43 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:43:49 volumio volumio[975]: info: CoreCommandRouter::volumioNext Dec 01 13:43:49 volumio volumio[975]: info: CoreStateMachine::next Dec 01 13:43:49 volumio volumio[975]: info: ControllerMpd::next Dec 01 13:43:49 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand next Dec 01 13:43:50 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 13:43:50 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 13:43:50 volumio volumio[975]: info: Discovery: Getting this device information Dec 01 13:43:50 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:43:50 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 13:43:59 volumio volumio[975]: info: Dec 01 13:43:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand next took 9482 milliseconds Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:59 volumio volumio[975]: info: Dec 01 13:43:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:59 volumio volumio[975]: info: Dec 01 13:43:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:59 volumio volumio[975]: info: Dec 01 13:43:59 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:43:59 volumio volumio[975]: Upnp client error: Error: This socket has been ended by the other party Dec 01 13:43:59 volumio volumio[975]: Upnp client error: Error: This socket has been ended by the other party Dec 01 13:43:59 volumio volumio[975]: Upnp client error: Error: This socket has been ended by the other party Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand status took 11 milliseconds Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand status took 9 milliseconds Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand status took 6 milliseconds Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Dec 01 13:43:59 volumio volumio[975]: 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} Dec 01 13:43:59 volumio volumio[975]: verbose: CURRENT POSITION 0 Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::play index undefined Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: 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} Dec 01 13:43:59 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: No code Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: 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} Dec 01 13:43:59 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: No code Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: 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} Dec 01 13:43:59 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: No code Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: [1733035439623] ControllerUPNPBrowser::clearAddPlayTrack Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 13:43:59 volumio volumio[975]: info: ------------------------------ 277ms Dec 01 13:43:59 volumio volumio[975]: info: ------------------------------ 277ms Dec 01 13:43:59 volumio volumio[975]: info: ------------------------------ 275ms Dec 01 13:43:59 volumio volumio[975]: info: ------------------------------ 275ms Dec 01 13:43:59 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:43:59 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:43:59 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:43:59 volumio volumio[975]: info: sendMpdCommand stop took 62 milliseconds Dec 01 13:43:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:43:59 volumio sudo[2449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:43:59 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:43:59 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:43:59 volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:43:59 volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:59 volumio systemd[1]: Stopping peppymeterbasic Daemon... Dec 01 13:43:59 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Dec 01 13:43:59 volumio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:43:59 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:43:59 volumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:43:59 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:43:59 volumio systemd[1]: peppymeterbasic.service: Succeeded. Dec 01 13:43:59 volumio systemd[1]: Stopped peppymeterbasic Daemon. Dec 01 13:43:59 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2452]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 01 13:44:00 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: sendMpdCommand clear took 573 milliseconds Dec 01 13:44:00 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.7:50002/m/NDLNA/37930.wav" Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 01 13:44:00 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:00 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:00 volumio volumio[975]: error: updateQueue error: null Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 73ms Dec 01 13:44:00 volumio volumio[975]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:00 volumio volumio[975]: info: peppymeterbasic Daemon Stop Dec 01 13:44:00 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.7:50002/m/NDLNA/37930.wav" Dec 01 13:44:00 volumio volumio[975]: error: updateQueue error: null Dec 01 13:44:00 volumio volumio[975]: error: updateQueue error: null Dec 01 13:44:00 volumio volumio[975]: error: updateQueue error: null Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 447ms Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 444ms Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 441ms Dec 01 13:44:00 volumio volumio[975]: info: sendMpdCommand add "http://192.168.1.7:50002/m/NDLNA/37930.wav" took 4 milliseconds Dec 01 13:44:00 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 13:44:00 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: Dec 01 13:44:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Dec 01 13:44:00 volumio volumio[975]: info: Ignoring MPD Status Update Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 9ms Dec 01 13:44:00 volumio volumio[975]: info: sendMpdCommand play took 6 milliseconds Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 6ms Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 4ms Dec 01 13:44:00 volumio volumio[975]: info: ------------------------------ 3ms Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 13 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 11 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 10 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 8 milliseconds Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 7 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:01 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:01 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:01 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:01 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:01 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:01 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:01 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:01 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:01 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:01 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:01 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:01 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:01 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:01 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:01 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:01 volumio volumio[975]: info: ------------------------------ 67ms Dec 01 13:44:01 volumio volumio[975]: info: ------------------------------ 186ms Dec 01 13:44:01 volumio volumio[975]: info: ------------------------------ 187ms Dec 01 13:44:01 volumio volumio[975]: info: ------------------------------ 187ms Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: Dec 01 13:44:01 volumio volumio[975]: ---------------------------- MPD announces state update: player Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::getState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand playlistinfo took 193 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 19 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 16 milliseconds Dec 01 13:44:01 volumio volumio[975]: info: sendMpdCommand status took 9 milliseconds Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::parseState Dec 01 13:44:01 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 13:44:01 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:01 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:01 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:01 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:01 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:01 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:01 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio volumio[975]: info: ------------------------------ 278ms Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio systemd[1]: Started peppymeterbasic Daemon. Dec 01 13:44:02 volumio sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio sudo[2518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio sudo[2510]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio volumio[975]: info: sendMpdCommand playlistinfo took 465 milliseconds Dec 01 13:44:02 volumio volumio[975]: info: sendMpdCommand playlistinfo took 465 milliseconds Dec 01 13:44:02 volumio volumio[975]: info: sendMpdCommand playlistinfo took 463 milliseconds Dec 01 13:44:02 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:02 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:02 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Dec 01 13:44:02 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:02 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:02 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:02 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:02 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:02 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:02 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:02 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:02 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio volumio[975]: info: ControllerMpd::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Dec 01 13:44:02 volumio volumio[975]: info: CorePlayQueue::getTrack 1 Dec 01 13:44:02 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"37930.wav","artist":null,"album":null,"uri":"http://192.168.1.7:50002/m/NDLNA/37930.wav","trackType":"wav"} Dec 01 13:44:02 volumio volumio[975]: verbose: CURRENT POSITION 1 Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Dec 01 13:44:02 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio sudo[2519]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: CoreStateMachine::pushState Dec 01 13:44:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Dec 01 13:44:02 volumio sudo[2519]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio volumio[975]: info: ------------------------------ 804ms Dec 01 13:44:02 volumio volumio[975]: info: ------------------------------ 803ms Dec 01 13:44:02 volumio volumio[975]: info: ------------------------------ 797ms Dec 01 13:44:02 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:02 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:02 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:02 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:02 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:02 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:02 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:02 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:03 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:03 volumio sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:03 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:03 volumio sudo[2533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:03 volumio sudo[2533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:03 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:03 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 01 13:44:03 volumio sudo[2533]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:03 volumio sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:03 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio sudo[2540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio volumio[975]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 01 13:44:03 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:03 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:03 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:03 volumio volumio[975]: info: peppymeterbasic Daemon Started Dec 01 13:44:04 volumio volumio[2506]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:44:04 volumio volumio[2506]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:44:04 volumio volumio[2506]: libpng warning: iCCP: known incorrect sRGB profile Dec 01 13:44:07 volumio sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 13:44:07 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:07 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:07 volumio sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 13:44:07 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:07 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:07 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.109 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.108 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 01 13:44:07 volumio sudo[2563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 13:44:07 volumio sudo[2563]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:07 volumio sudo[2563]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:07 volumio sudo[2565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 13:44:07 volumio sudo[2565]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 13:44:07 volumio sudo[2565]: pam_unix(sudo:session): session closed for user root Dec 01 13:44:07 volumio volumio[975]: verbose: New Socket.io Connection to 192.168.68.112 from 192.168.68.109 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.108 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 01 13:44:07 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 13:44:08 volumio volumio[975]: info: Received Get System Info Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 13:44:08 volumio volumio[975]: info: Discovery: Getting this device information Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:44:08 volumio volumio[975]: info: Listing playlists Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 01 13:44:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetQueue Dec 01 13:44:08 volumio volumio[975]: info: CoreStateMachine::getQueue Dec 01 13:44:08 volumio volumio[975]: info: CorePlayQueue::getQueue Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 13:44:09 volumio volumio[975]: info: Received Get System Info Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 13:44:09 volumio volumio[975]: info: Discovery: Getting this device information Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:44:09 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 13:44:10 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 13:44:10 volumio volumio[975]: info: Received Get System Info Dec 01 13:44:10 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 13:44:10 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 13:44:10 volumio volumio[975]: info: Discovery: Getting this device information Dec 01 13:44:10 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Dec 01 13:44:10 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 13:44:11 volumio ntpd[773]: Soliciting pool server 103.184.124.23 Dec 01 13:44:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:44:15 volumio volumio[975]: info: Preload queue cleared Dec 01 13:44:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 13:44:16 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 13:44:16 volumio volumio[975]: TypeError: Cannot read property 'length' of undefined Dec 01 13:44:16 volumio volumio[975]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Dec 01 13:44:16 volumio volumio[975]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Dec 01 13:44:16 volumio volumio[975]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Dec 01 13:44:16 volumio volumio[975]: at Parser.emit (events.js:315:20) Dec 01 13:44:16 volumio volumio[975]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Dec 01 13:44:16 volumio volumio[975]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Dec 01 13:44:16 volumio volumio[975]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Dec 01 13:44:16 volumio volumio[975]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Dec 01 13:44:16 volumio volumio[975]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Dec 01 13:44:16 volumio volumio[975]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Dec 01 13:44:16 volumio volumio[975]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Dec 01 13:44:16 volumio volumio[975]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Dec 01 13:44:16 volumio volumio[975]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Dec 01 13:44:16 volumio volumio[975]: at IncomingMessage.emit (events.js:327:22) Dec 01 13:44:16 volumio volumio[975]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 01 13:44:16 volumio volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 01 13:44:16 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 13:44:16 volumio sudo[2593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 13:43 Dec 01 13:44:16 volumio sudo[2593]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"