-- 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"