-- Logs begin at Thu 2019-02-14 11:11:59 WAT, end at Fri 2024-09-06 21:18:53 WAT. -- Sep 06 21:17:01 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 21:17:02 volumio volumio[9600]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 06 21:17:02 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:02 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:02 volumio volumio[9600]: info: Setting Geolocation for MyVolumio to eu9 Sep 06 21:17:02 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:02 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:02 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:03 volumio volumio[9600]: info: Updating MyVolumio device info Sep 06 21:17:03 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:03 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:03 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:03 volumio volumio[9600]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 06 21:17:03 volumio volumio[9600]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 06 21:17:04 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:04 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:05 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:05 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:05 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:05 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:05 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::volumioGetBrowseSources Sep 06 21:17:06 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 21:17:07 volumio volumio[9600]: info: MYVOLUMIO: Adding device Sep 06 21:17:07 volumio volumio[9600]: info: MYVOLUMIO: Evaluating Server Sep 06 21:17:09 volumio volumio[9600]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 06 21:17:09 volumio volumio[9600]: info: Completed starting MyVolumio Plugin Sep 06 21:17:09 volumio volumio[9600]: info: BOOT COMPLETED Sep 06 21:17:09 volumio volumio[9600]: [Metrics] CommandRouter: 58s 808.85ms Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::volumiosetStartupVolume Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::Close All Modals sent Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::Close All Modals sent Sep 06 21:17:09 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:09 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 06 21:17:10 volumio volumio[9600]: info: Setting Geolocation for MyVolumio to eu2 Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:10 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:11 volumio volumio[9600]: info: Updating MyVolumio device info Sep 06 21:17:11 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 21:17:12 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 21:17:12 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 21:17:13 volumio volumio[9600]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 06 21:17:14 volumio volumio[9600]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 06 21:17:14 volumio volumio[9600]: error: error Sep 06 21:17:14 volumio volumio[9600]: error: error Sep 06 21:17:15 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:15 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:15 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:15 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:15 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:17:25 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:25 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:25 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:25 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:25 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:17:35 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:35 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:35 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:35 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:35 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:17:45 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:45 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:45 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:45 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:45 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:17:49 volumio volumio[9600]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/squeezelite_mc/volumio/buster/armhf Sep 06 21:17:55 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:17:55 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:17:55 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:17:55 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:17:55 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:04 volumio volumio[9600]: error: error Sep 06 21:18:04 volumio volumio[9600]: error: error Sep 06 21:18:05 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:18:05 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:05 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:18:05 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:18:05 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:15 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:18:15 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:15 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:18:15 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:18:15 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:22 volumio volumio[9600]: info: Prefetching next song Sep 06 21:18:22 volumio volumio[9600]: info: DOING PREFETCH IN MPD Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - karma.mp3" Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:22 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand add "NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - karma.mp3" took 13 milliseconds Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:22 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:22 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces state update: options Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 32ms Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand consume 1 took 24 milliseconds Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 24ms Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 20ms Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces state update: options Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:22 volumio volumio[9600]: info: Sep 06 21:18:22 volumio volumio[9600]: ---------------------------- MPD announces state update: options Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand status took 13 milliseconds Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand status took 9 milliseconds Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand status took 6 milliseconds Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 06 21:18:22 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:22 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99348,"duration":104,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harlem's Nocturne","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - harlem's nocturne.mp3","trackType":"mp3"} Sep 06 21:18:22 volumio volumio[9600]: verbose: CURRENT POSITION 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:22 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99348,"duration":104,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harlem's Nocturne","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - harlem's nocturne.mp3","trackType":"mp3"} Sep 06 21:18:22 volumio volumio[9600]: verbose: CURRENT POSITION 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:22 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99348,"duration":104,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Harlem's Nocturne","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - harlem's nocturne.mp3","trackType":"mp3"} Sep 06 21:18:22 volumio volumio[9600]: verbose: CURRENT POSITION 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:22 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:22 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:22 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 217ms Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 210ms Sep 06 21:18:22 volumio volumio[9600]: info: ------------------------------ 208ms Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:22 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:25 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:18:25 volumio volumio[9600]: info: CorePlayQueue::getTrack 0 Sep 06 21:18:25 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:18:25 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:18:25 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::startPlaybackTimer Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:26 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces state update: player Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:26 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces state update: player Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 15ms Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand status took 11 milliseconds Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 9ms Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand status took 6 milliseconds Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces system playlist update Sep 06 21:18:26 volumio volumio[9600]: info: Ignoring MPD Status Update Sep 06 21:18:26 volumio volumio[9600]: info: Sep 06 21:18:26 volumio volumio[9600]: ---------------------------- MPD announces state update: player Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::getState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand status Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 10ms Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand status took 7 milliseconds Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseState Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Karma","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - karma.mp3","trackType":"mp3"} Sep 06 21:18:26 volumio volumio[9600]: verbose: CURRENT POSITION 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:26 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Karma","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - karma.mp3","trackType":"mp3"} Sep 06 21:18:26 volumio volumio[9600]: verbose: CURRENT POSITION 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:26 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 144ms Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 141ms Sep 06 21:18:26 volumio volumio[9600]: info: sendMpdCommand playlistinfo took 116 milliseconds Sep 06 21:18:26 volumio volumio[9600]: verbose: ControllerMpd::parseTrackInfo Sep 06 21:18:26 volumio volumio[9600]: info: ControllerMpd::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::servicePushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":256,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Karma","artist":"Alicia Keys","album":"The Diary of Alicia Keys","uri":"NAS/Music/Alicia Keys/the diary of alicia keys/Alicia Keys - karma.mp3","trackType":"mp3"} Sep 06 21:18:26 volumio volumio[9600]: verbose: CURRENT POSITION 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState stateService play Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::syncState currentStatus play Sep 06 21:18:26 volumio volumio[9600]: info: Received an update from plugin. extracting info from payload Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:26 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:26 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:26 volumio volumio[9600]: info: ------------------------------ 191ms Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:26 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:27 volumio volumio[9600]: info: CoreStateMachine::pushState Sep 06 21:18:27 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:27 volumio volumio[9600]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 21:18:27 volumio volumio[9600]: info: CoreCommandRouter::volumioPushState Sep 06 21:18:27 volumio volumio[9600]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 06 21:18:35 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:18:35 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:35 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:18:35 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:18:35 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:45 volumio volumio[9600]: info: CoreCommandRouter::volumioGetState Sep 06 21:18:45 volumio volumio[9600]: info: CorePlayQueue::getTrack 1 Sep 06 21:18:45 volumio volumio[9600]: info: CoreCommandRouter::volumioGetQueue Sep 06 21:18:45 volumio volumio[9600]: info: CoreStateMachine::getQueue Sep 06 21:18:45 volumio volumio[9600]: info: CorePlayQueue::getQueue Sep 06 21:18:52 volumio volumio[9600]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 21:18:52 volumio volumio[9600]: Error: connect ETIMEDOUT 151.101.190.79:443 Sep 06 21:18:52 volumio volumio[9600]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 06 21:18:52 volumio volumio[9600]: errno: -110, Sep 06 21:18:52 volumio volumio[9600]: code: 'ETIMEDOUT', Sep 06 21:18:52 volumio volumio[9600]: syscall: 'connect', Sep 06 21:18:52 volumio volumio[9600]: address: '151.101.190.79', Sep 06 21:18:52 volumio volumio[9600]: port: 443 Sep 06 21:18:52 volumio volumio[9600]: } Sep 06 21:18:52 volumio volumio[9600]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 21:18:53 volumio sudo[10008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 21:17 Sep 06 21:18:53 volumio sudo[10008]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"