-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-02-09 09:25:24 CET. -- Feb 09 09:24:00 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:00 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dusty%20Springfield/Anthology/48f1db0d-d046-4d37-a995-c3d1f4b1eb42.jpg' Feb 09 09:24:00 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Feb 09 09:24:00 progressivevolumio volumio[1047]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Feb 09 09:24:00 progressivevolumio volumio[1047]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Feb 09 09:24:00 progressivevolumio volumio[1047]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Feb 09 09:24:00 progressivevolumio volumio[1047]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Feb 09 09:24:00 progressivevolumio volumio[1047]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Feb 09 09:24:00 progressivevolumio volumio[1047]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 09 09:24:00 progressivevolumio volumio[1047]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 09 09:24:02 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Elvis%20Presley/Elvis%27%20Golden%20Records%20Vol.3/c072c9ee-1438-43f9-bd7f-092926258979.jpg' Feb 09 09:24:02 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Elvis%20Presley/The%20Complete%20Elvis%20Presley%20Masters%20(Disc%2002)/51677fc9-0d36-4b7c-b088-3af54193fda1.jpg' Feb 09 09:24:04 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:05 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:13 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ennio%20Morricone/The%20Best/3bb3f61d-08a8-4baa-9438-ceea0d63b4a6.jpg' Feb 09 09:24:14 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:14 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:14 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:15 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:17 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Smashing%20Pumpkins/Mellon%20Collie%20And%20The%20Infinite%20Sadness/f7bef3ee-b911-44a0-8631-b2c5e438adfc.jpg' Feb 09 09:24:18 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Smashing%20Pumpkins/Pisces%20Iscariot%20(Deluxe%20Edition)/aa9a313a-6bf9-4019-bbfd-15fff01160e9.jpg' Feb 09 09:24:19 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Suede/Coming%20Up%20%7BDeluxe%20Edition%7D/ff05dc57-6bb4-404d-8829-9041e2ae45cb.jpg' Feb 09 09:24:19 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beatles/Artifacts%20-%20Beatlemania/d3836652-77b7-4c1a-a5d3-133d11e1dbd9.jpg' Feb 09 09:24:20 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:20 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Doors/Morrison%20Hotel/17504dc9-ffc4-4fc5-af4f-967506613c48.jpg' Feb 09 09:24:22 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Timecop%201983/Journeys/994c607c-390d-44d9-b348-78835a83a30e.jpg' Feb 09 09:24:24 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/Jackie%20Brown%3A%20Music%20From%20The%20Miramax%20Motion%20Picture/5ddf268a-7a3d-4225-8b0b-2bff2ee3dec2.jpg' Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 09 09:24:24 progressivevolumio volumio[1047]: info: Received Get System Version Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 09:24:24 progressivevolumio volumio[1047]: info: Received Get System Info Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 09:24:24 progressivevolumio volumio[1047]: info: Discovery: Getting this device information Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:24 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 09:24:25 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:29 progressivevolumio volumio[1047]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yung%20Hurn/1220/457ae987-5f37-4128-a614-024c55e718d2.jpg' Feb 09 09:24:30 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:34 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:34 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:34 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:35 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:40 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:44 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:45 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:50 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:54 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:54 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:54 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:24:55 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:24:59 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 09 09:24:59 progressivevolumio volumio[1047]: info: Preload queue cleared Feb 09 09:24:59 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 09 09:24:59 progressivevolumio volumio[1047]: info: Preload queue cleared Feb 09 09:25:00 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:04 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:05 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Preload queue cleared Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::ClearQueue Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::serviceStop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::serviceStop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [1770625510747] ControllerWebradio::stop Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Preload queue cleared Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s21198 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Handling event: musicStop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] *** musicStop *** Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Feb 09 09:25:10 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand stop took 21 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Feb 09 09:25:10 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:10 progressivevolumio volumio[1047]: info: Feb 09 09:25:10 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Kobina w/ Noa (R)","artist":"Internet Public Radio","album":null,"uri":"https://c11.radioboss.fm/stream/270","trackType":""} Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: No code Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Kobina w/ Noa (R)","artist":"Internet Public Radio","album":null,"uri":"https://c11.radioboss.fm/stream/270","trackType":""} Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: No code Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Kobina w/ Noa (R)","artist":"Internet Public Radio","album":null,"uri":"https://c11.radioboss.fm/stream/270","trackType":""} Feb 09 09:25:10 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: No code Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:10 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ------------------------------ 35ms Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ------------------------------ 34ms Feb 09 09:25:10 progressivevolumio volumio[1047]: info: ------------------------------ 31ms Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:10 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: stop Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPlay Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::play index 0 Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::stop Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 09 09:25:11 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:11 progressivevolumio volumio[1047]: info: [1770625511101] ControllerWebradio::clearAddPlayTrack Feb 09 09:25:11 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 09:25:11 progressivevolumio volumio[1047]: info: sendMpdCommand stop took 2 milliseconds Feb 09 09:25:11 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Feb 09 09:25:11 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:11 progressivevolumio volumio[1047]: info: sendMpdCommand clear took 0 milliseconds Feb 09 09:25:11 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand load "http://quarrel.str3am.com:7270" Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Feb 09 09:25:11 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Feb 09 09:25:11 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:11 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:11 progressivevolumio volumio[1047]: error: updateQueue error: null Feb 09 09:25:11 progressivevolumio volumio[1047]: info: ------------------------------ 3ms Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand add "http://quarrel.str3am.com:7270" Feb 09 09:25:12 progressivevolumio volumio[1047]: error: updateQueue error: null Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1075ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1075ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand add "http://quarrel.str3am.com:7270" took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 5ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 30ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 30ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 28ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"quarrel.str3am.com:7270","artist":null,"album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 39ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 39ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Handling event: musicPlay Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] *** musicPlay *** Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 2ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 2ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 0 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 19ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 20ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Ignoring MPD Status Update Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Feb 09 09:25:12 progressivevolumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::getState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 1ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseState Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 45ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 44ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 44ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ControllerMpd::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":"WMPG","album":null,"uri":"http://quarrel.str3am.com:7270","trackType":"com:7270"} Feb 09 09:25:12 progressivevolumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreStateMachine::pushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 09 09:25:12 progressivevolumio volumio[1047]: info: ------------------------------ 42ms Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:12 progressivevolumio volumio[1047]: info: [GPIO_Control] Status changed: play Feb 09 09:25:14 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:14 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:25:14 progressivevolumio volumio[1047]: info: Listing playlists Feb 09 09:25:15 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:20 progressivevolumio volumio[1047]: info: CoreCommandRouter::volumioGetState Feb 09 09:25:24 progressivevolumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 09:25:24 progressivevolumio volumio[1047]: Error: connect ETIMEDOUT 158.69.209.125:443 Feb 09 09:25:24 progressivevolumio volumio[1047]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 09 09:25:24 progressivevolumio volumio[1047]: errno: -110, Feb 09 09:25:24 progressivevolumio volumio[1047]: code: 'ETIMEDOUT', Feb 09 09:25:24 progressivevolumio volumio[1047]: syscall: 'connect', Feb 09 09:25:24 progressivevolumio volumio[1047]: address: '158.69.209.125', Feb 09 09:25:24 progressivevolumio volumio[1047]: port: 443 Feb 09 09:25:24 progressivevolumio volumio[1047]: } Feb 09 09:25:24 progressivevolumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 09:25:24 progressivevolumio sudo[3260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 09:24 Feb 09 09:25:24 progressivevolumio sudo[3260]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"