-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Tue 2024-11-26 15:36:46 EET. -- Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::servicePause Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::servicePause Nov 26 15:35:00 volumiozac volumio[3396]: info: [1732628100571] ControllerUPNPBrowser::pause Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand pause Nov 26 15:35:00 volumiozac volumio[3396]: info: Nov 26 15:35:00 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand pause took 2 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:00 volumiozac volumio[3396]: info: Nov 26 15:35:00 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:00 volumiozac volumio[3396]: info: Nov 26 15:35:00 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand status took 4 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand status took 3 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand status took 2 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:00 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:00 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":142971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:00 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:00 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":142971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:00 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:00 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:00 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":142971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:00 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:00 volumiozac volumio[3396]: info: ------------------------------ 29ms Nov 26 15:35:00 volumiozac volumio[3396]: info: ------------------------------ 27ms Nov 26 15:35:00 volumiozac volumio[3396]: info: ------------------------------ 30ms Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:00 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:00 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3","seek":141971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"bitrate":"128 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":0,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"mpd"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:00 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3","seek":141971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"bitrate":"128 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":0,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"mpd"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:00 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3","seek":141971,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"bitrate":"128 Kbps","random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":0,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"mpd"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:01 volumiozac volumio[3396]: info: [ASDebug] Togle GPIO: OFF Nov 26 15:35:03 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:03 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:03 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:05 volumiozac volumio[3396]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Nov 26 15:35:06 volumiozac volumio[3396]: verbose: New Socket.io Connection to 192.168.2.114 from 192.168.2.85 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: Received Get System Info Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 15:35:06 volumiozac volumio[3396]: info: Discovery: Getting this device information Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 15:35:06 volumiozac volumio[3396]: info: Received Get System Info Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 15:35:06 volumiozac volumio[3396]: info: Discovery: Getting this device information Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:06 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:06 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:06 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:07 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPlay Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::play index undefined Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::startPlaybackTimer Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: [1732628109844] ControllerUPNPBrowser::resume Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand play Nov 26 15:35:09 volumiozac volumio[3396]: info: Nov 26 15:35:09 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand play took 7 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:09 volumiozac volumio[3396]: info: Nov 26 15:35:09 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:09 volumiozac volumio[3396]: info: Nov 26 15:35:09 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand status took 4 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand status took 4 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand status took 2 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:09 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:09 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:09 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus play Nov 26 15:35:09 volumiozac volumio[3396]: info: Received an update from plugin. extracting info from payload Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:09 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:09 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus play Nov 26 15:35:09 volumiozac volumio[3396]: info: Received an update from plugin. extracting info from payload Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:09 volumiozac volumio[3396]: info: ------------------------------ 52ms Nov 26 15:35:09 volumiozac volumio[3396]: info: ------------------------------ 51ms Nov 26 15:35:09 volumiozac volumio[3396]: info: ------------------------------ 50ms Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: pause Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] Togle GPIO: ON Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: play Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: play Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: play Nov 26 15:35:09 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: play Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:09 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:10 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:10 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:10 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:10 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":142238,"duration":270,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:10 volumiozac volumio[3396]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Nov 26 15:35:10 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:10 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:10 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"play","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":143965,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:13 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:13 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::servicePause Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::servicePause Nov 26 15:35:14 volumiozac volumio[3396]: info: [1732628114202] ControllerUPNPBrowser::pause Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand pause Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand pause took 1 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: info: Nov 26 15:35:14 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:14 volumiozac volumio[3396]: info: Nov 26 15:35:14 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:14 volumiozac volumio[3396]: info: Nov 26 15:35:14 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand status took 4 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand status took 3 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand status took 2 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:14 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148217,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:14 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148217,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:14 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:14 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":148217,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:35:14 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::stPlaybackTimer Nov 26 15:35:14 volumiozac volumio[3396]: info: ------------------------------ 27ms Nov 26 15:35:14 volumiozac volumio[3396]: info: ------------------------------ 27ms Nov 26 15:35:14 volumiozac volumio[3396]: info: ------------------------------ 26ms Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] CurState: pause PrevState: play Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CoreStateMachine::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: CorePlayQueue::getQueue Nov 26 15:35:14 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":148221,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:14 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":148221,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:14 volumiozac volumio[3396]: info: ONKYO-CONTROL: New state: {"status":"pause","position":0,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","albumart":"/albumart?cacheid=20&web=Alice%20Cooper/*ROCK%20%26%20BALLADS%20COLLECTION/extralarge&path=&metadata=false","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","seek":148221,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"updatedb":false,"volatile":false,"service":"upnp_browser"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.2.72","model":"VSX-933"} Nov 26 15:35:14 volumiozac volumio[3396]: info: [ASDebug] Togle GPIO: OFF Nov 26 15:35:16 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 26 15:35:19 volumiozac volumio[3396]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Nov 26 15:35:19 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 15:35:19 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 15:35:23 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:23 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:23 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:23 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:33 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:33 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:35 volumiozac volumio[3396]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STOPPED ******** Nov 26 15:35:35 volumiozac volumio[3396]: info: Disabling plugin onkyo_control Nov 26 15:35:35 volumiozac volumio[3396]: info: Done. Nov 26 15:35:39 volumiozac volumio[3396]: info: [ASDebug] Port: 24 Nov 26 15:35:39 volumiozac volumio[3396]: info: [ASDebug] Inverted: true Nov 26 15:35:39 volumiozac volumio[3396]: info: [ASDebug] Delay: 720 Nov 26 15:35:43 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:43 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:43 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:43 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:35:48 volumiozac volumio[3396]: info: Starting Uninstall of plugin system_hardware - onkyo_control Nov 26 15:35:48 volumiozac volumio[3396]: info: Uninstalling plugin onkyo_control Nov 26 15:35:48 volumiozac volumio[3396]: info: Disabling plugin onkyo_control Nov 26 15:35:48 volumiozac volumio[3396]: info: Checking if uninstall.sh is present Nov 26 15:35:48 volumiozac volumio[3396]: info: Executing uninstall.sh Nov 26 15:35:49 volumiozac sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/onkyo_control/uninstall.sh Nov 26 15:35:49 volumiozac sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 15:35:49 volumiozac sudo[1706]: pam_unix(sudo:session): session closed for user root Nov 26 15:35:49 volumiozac volumio[3396]: info: Uninstall script completed Nov 26 15:35:49 volumiozac volumio[3396]: info: Removing plugin onkyo_control from configuration Nov 26 15:35:49 volumiozac volumio[3396]: info: Successfully removed onkyo_control configuration files Nov 26 15:35:49 volumiozac volumio[3396]: info: Plugin folders cleanup Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning into folder /volumio/app/plugins/ Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category audio_interface Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category miscellanea Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category music_service Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category plugins.json Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category system_controller Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category user_interface Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning into folder /data/plugins/ Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category music_service Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category system_controller Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category system_hardware Nov 26 15:35:49 volumiozac volumio[3396]: info: Cleaning folder for onkyo_control Nov 26 15:35:49 volumiozac volumio[3396]: info: Scanning category user_interface Nov 26 15:35:49 volumiozac volumio[3396]: info: Plugin folders cleanup completed Nov 26 15:35:49 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 15:35:49 volumiozac volumio[3396]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Nov 26 15:35:49 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 15:35:53 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:35:53 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:35:53 volumiozac nmbd[697]: [2024/11/26 15:35:53.667450, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 26 15:35:53 volumiozac nmbd[697]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.114 for name WORKGROUP<1d>. Nov 26 15:35:53 volumiozac nmbd[697]: This response was from IP 192.168.2.77, reporting an IP address of 192.168.2.77. Nov 26 15:35:56 volumiozac volumio[3396]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 26 15:35:56 volumiozac volumio[3396]: info: Uninstalling plugin ampswitch Nov 26 15:35:56 volumiozac volumio[3396]: info: [ASDebug] Port: 24 Nov 26 15:35:56 volumiozac volumio[3396]: info: [ASDebug] Inverted: true Nov 26 15:35:56 volumiozac volumio[3396]: info: [ASDebug] Delay: 720 Nov 26 15:36:03 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:03 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:03 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:03 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:13 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:13 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:23 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:23 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:23 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:23 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:33 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:33 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:37 volumiozac volumio[3396]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 26 15:36:37 volumiozac volumio[3396]: info: Uninstalling plugin ampswitch Nov 26 15:36:37 volumiozac volumio[3396]: info: [ASDebug] Port: 24 Nov 26 15:36:37 volumiozac volumio[3396]: info: [ASDebug] Inverted: true Nov 26 15:36:37 volumiozac volumio[3396]: info: [ASDebug] Delay: 720 Nov 26 15:36:43 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:43 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:43 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:43 volumiozac volumio[3396]: info: Listing playlists Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioGetState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPlay Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::play index undefined Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::startPlaybackTimer Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: [1732628205770] ControllerUPNPBrowser::resume Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand play Nov 26 15:36:45 volumiozac volumio[3396]: info: Nov 26 15:36:45 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand play took 6 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:36:45 volumiozac volumio[3396]: info: Nov 26 15:36:45 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:36:45 volumiozac volumio[3396]: info: Nov 26 15:36:45 volumiozac volumio[3396]: ---------------------------- MPD announces state update: player Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::getState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand status Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand status took 5 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand status took 3 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand status took 3 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseState Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:36:45 volumiozac volumio[3396]: verbose: ControllerMpd::parseTrackInfo Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149211,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:36:45 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus pause Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149211,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:36:45 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus play Nov 26 15:36:45 volumiozac volumio[3396]: info: Received an update from plugin. extracting info from payload Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:36:45 volumiozac volumio[3396]: info: ControllerMpd::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::servicePushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149211,"duration":271,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Poison","artist":"Alice Cooper","album":"*ROCK & BALLADS COLLECTION","uri":"http://192.168.2.1:49200/AUDIO/DLNA-1-0/MY%20MUSIC/%ce%9e%ce%95%ce%9d%ce%91/Alice%20Cooper/ROCK%20COLLECTION/Poison.mp3","trackType":"mp3"} Nov 26 15:36:45 volumiozac volumio[3396]: verbose: CURRENT POSITION 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState stateService play Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::syncState currentStatus play Nov 26 15:36:45 volumiozac volumio[3396]: info: Received an update from plugin. extracting info from payload Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreStateMachine::pushState Nov 26 15:36:45 volumiozac volumio[3396]: info: CorePlayQueue::getTrack 0 Nov 26 15:36:45 volumiozac volumio[3396]: info: CoreCommandRouter::volumioPushState Nov 26 15:36:45 volumiozac volumio[3396]: info: ------------------------------ 42ms Nov 26 15:36:45 volumiozac volumio[3396]: info: ------------------------------ 40ms Nov 26 15:36:45 volumiozac volumio[3396]: info: ------------------------------ 40ms Nov 26 15:36:45 volumiozac volumio[3396]: info: [ASDebug] CurState: play PrevState: pause Nov 26 15:36:45 volumiozac volumio[3396]: info: [ASDebug] Togle GPIO: ON Nov 26 15:36:45 volumiozac volumio[3396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 15:36:45 volumiozac volumio[3396]: Error: ESPIPE: invalid seek, write Nov 26 15:36:45 volumiozac volumio[3396]: at Object.writeSync (fs.js:711:3) Nov 26 15:36:45 volumiozac volumio[3396]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 26 15:36:45 volumiozac volumio[3396]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:209:23) Nov 26 15:36:45 volumiozac volumio[3396]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 26 15:36:45 volumiozac volumio[3396]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 26 15:36:45 volumiozac volumio[3396]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 26 15:36:45 volumiozac volumio[3396]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 26 15:36:45 volumiozac volumio[3396]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 26 15:36:45 volumiozac volumio[3396]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 26 15:36:45 volumiozac volumio[3396]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 26 15:36:45 volumiozac volumio[3396]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 26 15:36:45 volumiozac volumio[3396]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 26 15:36:45 volumiozac volumio[3396]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 26 15:36:45 volumiozac volumio[3396]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 26 15:36:45 volumiozac volumio[3396]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 26 15:36:45 volumiozac volumio[3396]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 26 15:36:45 volumiozac volumio[3396]: errno: -29, Nov 26 15:36:45 volumiozac volumio[3396]: syscall: 'write', Nov 26 15:36:45 volumiozac volumio[3396]: code: 'ESPIPE' Nov 26 15:36:45 volumiozac volumio[3396]: } Nov 26 15:36:45 volumiozac volumio[3396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 15:36:46 volumiozac sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 15:35 Nov 26 15:36:46 volumiozac sudo[1917]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"