-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-07-25 17:48:26 CEST. -- Jul 25 17:47:13 volumio volumio[1013]: verbose: New Socket.io Connection to 192.168.2.157 from 192.168.2.158 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 16 Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:18 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:18 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:18 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:18 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:24 volumio volumio[1013]: info: CALLMETHOD: music_service youtube2 configSaveBrowse [object Object] Jul 25 17:47:24 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: youtube2 , configSaveBrowse Jul 25 17:47:25 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:25 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:25 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:25 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:25 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:25 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:27 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:27 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:27 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:27 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:27 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:27 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:34 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:34 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:34 volumio volumio[1013]: info: Prefetching next song Jul 25 17:47:34 volumio volumio[1013]: info: DOING PREFETCH IN MPD Jul 25 17:47:34 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio/Dj Goja I Never Let You G.mp3" Jul 25 17:47:34 volumio volumio[1013]: info: Jul 25 17:47:34 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:34 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:34 volumio volumio[1013]: info: sendMpdCommand add "USB/Volumio/Dj Goja I Never Let You G.mp3" took 6 milliseconds Jul 25 17:47:34 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 25 17:47:34 volumio volumio[1013]: info: Jul 25 17:47:34 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:34 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:34 volumio volumio[1013]: info: Jul 25 17:47:34 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:34 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:34 volumio volumio[1013]: info: Jul 25 17:47:34 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:34 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:34 volumio volumio[1013]: info: ------------------------------ 16ms Jul 25 17:47:34 volumio volumio[1013]: info: sendMpdCommand consume 1 took 13 milliseconds Jul 25 17:47:34 volumio volumio[1013]: info: ------------------------------ 8ms Jul 25 17:47:34 volumio volumio[1013]: info: ------------------------------ 7ms Jul 25 17:47:34 volumio volumio[1013]: info: ------------------------------ 5ms Jul 25 17:47:34 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:34 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:34 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:34 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:34 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:34 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:35 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:35 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:35 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:35 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:35 volumio volumio[1013]: info: CorePlayQueue::getTrack 3 Jul 25 17:47:35 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:38 volumio volumio[1013]: info: CoreStateMachine::startPlaybackTimer Jul 25 17:47:38 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:39 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:39 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:39 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces system playlist update Jul 25 17:47:39 volumio volumio[1013]: info: Ignoring MPD Status Update Jul 25 17:47:39 volumio volumio[1013]: info: Jul 25 17:47:39 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 30ms Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand status took 28 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 28ms Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand status took 26 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 26ms Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand status took 24 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 24ms Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand status took 23 milliseconds Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseState Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 25 17:47:39 volumio volumio[1013]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseTrackInfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseTrackInfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseTrackInfo Jul 25 17:47:39 volumio volumio[1013]: verbose: ControllerMpd::parseTrackInfo Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dj Goja I Never Let You G.mp3","artist":null,"album":null,"uri":"USB/Volumio/Dj Goja I Never Let You G.mp3","trackType":"mp3"} Jul 25 17:47:39 volumio volumio[1013]: verbose: CURRENT POSITION 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState stateService play Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState currentStatus play Jul 25 17:47:39 volumio volumio[1013]: info: Received an update from plugin. extracting info from payload Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dj Goja I Never Let You G.mp3","artist":null,"album":null,"uri":"USB/Volumio/Dj Goja I Never Let You G.mp3","trackType":"mp3"} Jul 25 17:47:39 volumio volumio[1013]: verbose: CURRENT POSITION 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState stateService play Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState currentStatus play Jul 25 17:47:39 volumio volumio[1013]: info: Received an update from plugin. extracting info from payload Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dj Goja I Never Let You G.mp3","artist":null,"album":null,"uri":"USB/Volumio/Dj Goja I Never Let You G.mp3","trackType":"mp3"} Jul 25 17:47:39 volumio volumio[1013]: verbose: CURRENT POSITION 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState stateService play Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState currentStatus play Jul 25 17:47:39 volumio volumio[1013]: info: Received an update from plugin. extracting info from payload Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: ControllerMpd::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::servicePushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Dj Goja I Never Let You G.mp3","artist":null,"album":null,"uri":"USB/Volumio/Dj Goja I Never Let You G.mp3","trackType":"mp3"} Jul 25 17:47:39 volumio volumio[1013]: verbose: CURRENT POSITION 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState stateService play Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::syncState currentStatus play Jul 25 17:47:39 volumio volumio[1013]: info: Received an update from plugin. extracting info from payload Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 173ms Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 171ms Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 170ms Jul 25 17:47:39 volumio volumio[1013]: info: ------------------------------ 170ms Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:39 volumio volumio[1013]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dj Goja I Never Let You G.mp3 Jul 25 17:47:39 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:47:39 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:47:39 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:47:39 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:47:42 volumio volumio[1013]: info: CALLMETHOD: music_service youtube2 configSavePlayback [object Object] Jul 25 17:47:42 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: youtube2 , configSavePlayback Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:44 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:44 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 25 17:47:44 volumio volumio[1013]: info: Discovery: Getting this device information Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:47:44 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:47:44 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 25 17:47:54 volumio volumio[1013]: info: CALLMETHOD: music_service youtube2 configSaveYouTubePlaybackMode [object Object] Jul 25 17:47:54 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: youtube2 , configSaveYouTubePlaybackMode Jul 25 17:48:01 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:01 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:01 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:01 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:01 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:01 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:01 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:01 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:01 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:01 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:01 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:02 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:02 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:02 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:02 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:02 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:02 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:02 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:02 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:02 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:02 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:02 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:02 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:03 volumio volumio[1013]: info: VolumeController::SetAlsaVolume- Jul 25 17:48:03 volumio volumio[1013]: info: CoreCommandRouter::volumioGetState Jul 25 17:48:03 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:03 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:03 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:03 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:03 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:03 volumio volumio[1013]: info: [ASDebug] CurState: play PrevState: play Jul 25 17:48:18 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jul 25 17:48:18 volumio volumio[1013]: info: [youtube2-browse] browseUri: youtube2 Jul 25 17:48:19 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:19 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:19 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:21 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jul 25 17:48:21 volumio volumio[1013]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEtrending%22%2C%22params%22%3A%226gQJRkVleHBsb3Jl%22%7D%7D Jul 25 17:48:22 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:22 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:22 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:24 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::ClearQueue Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::stop Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::stPlaybackTimer Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::updateTrackBlock Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrackBlock Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::pushState Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::volumioPushState Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::serviceStop Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrack 4 Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::serviceStop Jul 25 17:48:24 volumio volumio[1013]: info: ControllerMpd::stop Jul 25 17:48:24 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::clearPlayQueue Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::saveQueue Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::volumioPushQueue Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::addQueueItems Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::addQueueItems Jul 25 17:48:24 volumio volumio[1013]: info: Preload queue cleared Jul 25 17:48:24 volumio volumio[1013]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22IL%20TEST%20DELL'AUTO%20CORTISSIMA.%22%2C%22artist%22%3A%22Carmagheddon%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F0tZ7fPn5K04%2Fhq2.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGABwAcABBvABAfgBrgiAAoAPigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLAjI_78_g31olIFGg1pbKiGxHcbqA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220tZ7fPn5K04%22%7D%7D%7D Jul 25 17:48:24 volumio volumio[1013]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22IL%20TEST%20DELL'AUTO%20CORTISSIMA.%22%2C%22artist%22%3A%22Carmagheddon%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F0tZ7fPn5K04%2Fhq2.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGABwAcABBvABAfgBrgiAAoAPigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLAjI_78_g31olIFGg1pbKiGxHcbqA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220tZ7fPn5K04%22%7D%7D%7D in service youtube2 Jul 25 17:48:24 volumio volumio[1013]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22IL%20TEST%20DELL'AUTO%20CORTISSIMA.%22%2C%22artist%22%3A%22Carmagheddon%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F0tZ7fPn5K04%2Fhq2.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGABwAcABBvABAfgBrgiAAoAPigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLAjI_78_g31olIFGg1pbKiGxHcbqA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220tZ7fPn5K04%22%7D%7D%7D Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::volumioPushQueue Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::saveQueue Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::updateTrackBlock Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrackBlock Jul 25 17:48:24 volumio volumio[1013]: info: CoreCommandRouter::volumioPlay Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::play index 0 Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::stop Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::play index undefined Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jul 25 17:48:24 volumio volumio[1013]: info: CoreStateMachine::startPlaybackTimer Jul 25 17:48:24 volumio volumio[1013]: info: CorePlayQueue::getTrack 0 Jul 25 17:48:24 volumio volumio[1013]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220tZ7fPn5K04%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22IL%20TEST%20DELL'AUTO%20CORTISSIMA.%22%2C%22artist%22%3A%22Carmagheddon%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F0tZ7fPn5K04%2Fhq2.jpg%3Fsqp%3D-oaymwE2CNACELwBSFXyq4qpAygIARUAAIhCGABwAcABBvABAfgBrgiAAoAPigIMCAAQARhlIGUoZTAP%26rs%3DAOn4CLAjI_78_g31olIFGg1pbKiGxHcbqA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%220tZ7fPn5K04%22%7D%7D%7D Jul 25 17:48:24 volumio volumio[1013]: info: [ASDebug] CurState: stop PrevState: play Jul 25 17:48:24 volumio volumio[1013]: info: [ASDebug] InitTimeout - Amp off in: 20 ms Jul 25 17:48:24 volumio volumio[1013]: info: Jul 25 17:48:24 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:48:24 volumio volumio[1013]: info: sendMpdCommand stop took 84 milliseconds Jul 25 17:48:24 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:48:24 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:48:24 volumio volumio[1013]: info: Jul 25 17:48:24 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:48:24 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:48:24 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:48:24 volumio volumio[1013]: info: Jul 25 17:48:24 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:48:24 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:48:24 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:48:24 volumio volumio[1013]: info: Jul 25 17:48:24 volumio volumio[1013]: ---------------------------- MPD announces state update: player Jul 25 17:48:24 volumio volumio[1013]: info: ControllerMpd::getState Jul 25 17:48:24 volumio volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jul 25 17:48:24 volumio volumio[1013]: info: [ASDebug] Togle GPIO: OFF Jul 25 17:48:24 volumio volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 17:48:24 volumio volumio[1013]: Error: ESPIPE: invalid seek, write Jul 25 17:48:24 volumio volumio[1013]: at Object.writeSync (fs.js:711:3) Jul 25 17:48:24 volumio volumio[1013]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jul 25 17:48:24 volumio volumio[1013]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:213:23) Jul 25 17:48:24 volumio volumio[1013]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:187:88) Jul 25 17:48:24 volumio volumio[1013]: at listOnTimeout (internal/timers.js:554:17) Jul 25 17:48:24 volumio volumio[1013]: at processTimers (internal/timers.js:497:7) { Jul 25 17:48:24 volumio volumio[1013]: errno: -29, Jul 25 17:48:24 volumio volumio[1013]: syscall: 'write', Jul 25 17:48:24 volumio volumio[1013]: code: 'ESPIPE' Jul 25 17:48:24 volumio volumio[1013]: } Jul 25 17:48:24 volumio volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 17:48:26 volumio sudo[7909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 17:47 Jul 25 17:48:26 volumio sudo[7909]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"