-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-11-28 17:25:37 CET. -- Nov 28 17:24:00 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 17:24:00 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 28 17:24:03 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesJellyfin Nov 28 17:24:03 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 17:24:03 volumio-rpib3 volumio[1056]: Cannot find translation for source Mixcloud Nov 28 17:24:03 volumio-rpib3 volumio[1056]: Cannot find translation for source SoundCloud Nov 28 17:24:03 volumio-rpib3 volumio[1056]: Cannot find translation for source Podcast Nov 28 17:24:03 volumio-rpib3 volumio[1056]: Cannot find translation for source LastFM Nov 28 17:24:03 volumio-rpib3 volumio[1056]: info: Disabling plugin jellyfin Nov 28 17:24:03 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:24:08 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioGetState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPlay Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::play index undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1897756938@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1908217215%7D Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand stop took 45 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand clear took 4 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:09 volumio-rpib3 volumio[1056]: error: updateQueue error: null Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 14 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 17ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 14 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 12 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 10 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 8 milliseconds Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 218ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 214ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 213ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 212ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 225ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 15ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 13ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 17:24:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:09 volumio-rpib3 volumiologrotate[718]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:09 volumio-rpib3 volumiologrotate[718]: ls: cannot access 'RPIB3+': No such file or directory Nov 28 17:24:09 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:10 volumio-rpib3 sudo[3102]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3102]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:10 volumio-rpib3 sudo[3102]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:10 volumio-rpib3 sudo[3113]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3113]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:10 volumio-rpib3 sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:10 volumio-rpib3 sudo[3121]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3121]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:10 volumio-rpib3 sudo[3121]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 sudo[3124]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:10 volumio-rpib3 sudo[3124]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:10 volumio-rpib3 sudo[3124]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 sudo[3102]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 sudo[3127]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:10 volumio-rpib3 sudo[3127]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:10 volumio-rpib3 sudo[3127]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:10 volumio-rpib3 sudo[3130]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3130]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3133]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3133]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 sudo[3124]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 sudo[3133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:10 volumio-rpib3 sudo[3113]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 sudo[3133]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 sudo[3121]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 sudo[3130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:10 volumio-rpib3 sudo[3130]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Handling event: musicStop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: OFF Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: ------------------------------ 675ms Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: ------------------------------ 671ms Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: sendMpdCommand play took 665 milliseconds Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: ------------------------------ 663ms Nov 28 17:24:10 volumio-rpib3 sudo[3135]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:10 volumio-rpib3 sudo[3135]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: ------------------------------ 660ms Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: ------------------------------ 658ms Nov 28 17:24:10 volumio-rpib3 sudo[3135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:10 volumio-rpib3 sudo[3135]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:10 volumio-rpib3 sudo[3127]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:10 volumio-rpib3 sudo[3130]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:10 volumio-rpib3 sudo[3133]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:10 volumio-rpib3 sudo[3135]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:10 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 42 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 49 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 48 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 130 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 100 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 99 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 90 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 88 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 47 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 46 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 45 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 41 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 39 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 37 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 307ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 305ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 303ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 269 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 258 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 258 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 255 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 254 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 254 milliseconds Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDA4fX19XX0_&Signature=eF5z1hk7wzOpWtwxRrf7fZ2KqByau3Cf25T5oNW8hZMXPaIZlOlJ~M93w6x1lLjLEEglI37n5FJx28H-qY57WbE~G6-k4DbeeNcpdrPx43OGUVLNxa8l4AgMCyp6Z8EEZf08jU-5JT0p78n5x57dB3hJhyDh10nMoDSe2yZN5hNi8UZ9Fp24n1zacIs8d3ALnc7B6wvBT5A4NbN7Qi0yOzBRPyjZZ2gs-cGgJbsxQ~dgp4liy9U1V8fE7v-hKYz8FJveYxf~sc3ZKUvNr1SbdLaMgaXblT9FwIQsx9U6Y4QKKYYMUJ-a~022hRqVbuWUWtmLpWXrK6AABniXOfDlMg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:11 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 644ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 664ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 575ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 574ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: ------------------------------ 572ms Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:11 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:12 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:16 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 17:24:16 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 28 17:24:30 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 17:24:35 volumio-rpib3 kernel: hwmon hwmon1: Voltage normalised Nov 28 17:24:37 volumio-rpib3 kernel: hwmon hwmon1: Undervoltage detected! Nov 28 17:24:38 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 17:24:38 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 28 17:24:45 volumio-rpib3 kernel: hwmon hwmon1: Voltage normalised Nov 28 17:24:45 volumio-rpib3 volumio[1056]: info: Stopping Pirate audio service Nov 28 17:24:45 volumio-rpib3 volumio[1056]: info: Disabling plugin pirateaudio Nov 28 17:24:45 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:24:45 volumio-rpib3 sudo[3209]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:45 volumio-rpib3 sudo[3209]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:45 volumio-rpib3 sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop pirateaudio.service Nov 28 17:24:45 volumio-rpib3 sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:45 volumio-rpib3 sudo[3209]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:47 volumio-rpib3 kernel: hwmon hwmon1: Undervoltage detected! Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPlay Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::play index undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1897756938@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1908217215%7D Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand stop took 26 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand clear took 2 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:49 volumio-rpib3 volumio[1056]: error: updateQueue error: null Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 9 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 11ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 9 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 8 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 6 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 4 milliseconds Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 125ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 122ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 120ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 119ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:49 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: ------------------------------ 129ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 17:24:49 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:49 volumio-rpib3 sudo[3215]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3215]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 sudo[3215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:49 volumio-rpib3 sudo[3215]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 28 17:24:49 volumio-rpib3 sudo[3217]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3217]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 sudo[3217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:49 volumio-rpib3 sudo[3217]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:49 volumio-rpib3 sudo[3220]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3220]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:49 volumio-rpib3 sudo[3215]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:49 volumio-rpib3 sudo[3220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:49 volumio-rpib3 sudo[3220]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:49 volumio-rpib3 sudo[3224]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3224]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:49 volumio-rpib3 sudo[3227]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3227]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 sudo[3224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:49 volumio-rpib3 sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:49 volumio-rpib3 sudo[3217]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:49 volumio-rpib3 sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:49 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status stop Nov 28 17:24:49 volumio-rpib3 sudo[3224]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:49 volumio-rpib3 sudo[3229]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3229]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 sudo[3220]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:49 volumio-rpib3 sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:49 volumio-rpib3 sudo[3232]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:49 volumio-rpib3 sudo[3232]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:49 volumio-rpib3 sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:49 volumio-rpib3 sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:50 volumio-rpib3 sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:50 volumio-rpib3 sudo[3227]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Handling event: musicStop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:50 volumio-rpib3 sudo[3235]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:24:50 volumio-rpib3 sudo[3235]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:50 volumio-rpib3 sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:24:50 volumio-rpib3 sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: OFF Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 573ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 572ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 572ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 570ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand play took 570 milliseconds Nov 28 17:24:50 volumio-rpib3 sudo[3224]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 28ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 27ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 24ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppymeterbasic Daemon Stop Nov 28 17:24:50 volumio-rpib3 sudo[3229]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:50 volumio-rpib3 sudo[3232]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:50 volumio-rpib3 sudo[3235]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: peppyspectrum Daemon Stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 47 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 47 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 47 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 125 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 123 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 91 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 88 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 86 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 47 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 46 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 44 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 41 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 39 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 37 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 319ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 318ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ------------------------------ 315ms Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 297 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 295 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 263 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 262 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 261 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 261 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 260 milliseconds Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDQ3fX19XX0_&Signature=a9cOB~irVZ-G9Uo0nWO1YQL72o~03xYmiCfCMy8h1FM2alcC265TWdEY4s0-WQS19qd3NjxWMUEcv6qgHAmqRSrTO1sjoOYsPShyCdRL5wgbzICPkX5Z5Nnulx8kgdvQIRFognN4UqJbqDDj0h6BxGCKlsM1uYtuGntPVyVmPgy~C-CKoJrlq-g2xdEKg4cKGdj2X-iLj~3fHfIj7N4JfQ7-q-GkDJjNEiACh1KZo4x57xSA28UfCc6FML8-7ZmHGy~dudqMCpnKVDYhcBGdpOq-to13-NRF55ozmYcDD1tNSY4nHle1NENp5q9cAxnVfbxLusxQ67XcLgp2ysG2AA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:24:50 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:24:50 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: ------------------------------ 655ms Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: ------------------------------ 654ms Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: ------------------------------ 569ms Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: ------------------------------ 567ms Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: ------------------------------ 564ms Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppyMeterBasic ---peppymeterbasic status undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: PeppySpectrum ---peppyspectrum status undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:51 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:24:54 volumio-rpib3 volumio[1056]: info: Performing onStop action Nov 28 17:24:54 volumio-rpib3 volumio[1056]: info: Disabling plugin lastfm Nov 28 17:24:54 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:24:55 volumio-rpib3 kernel: hwmon hwmon1: Voltage normalised Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Stopping peppymeterbasic service Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreStateMachine::stop Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Disabling plugin peppymeterbasic Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Preparing to generate the ALSA configuration file Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Reading ALSA contributions from plugins. Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Asound.conf file written Nov 28 17:24:59 volumio-rpib3 sudo[3258]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:59 volumio-rpib3 sudo[3258]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:59 volumio-rpib3 sudo[3258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 28 17:24:59 volumio-rpib3 sudo[3258]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:59 volumio-rpib3 sudo[3258]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:59 volumio-rpib3 sudo[3261]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:59 volumio-rpib3 sudo[3261]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:59 volumio-rpib3 sudo[3261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 28 17:24:59 volumio-rpib3 sudo[3261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:59 volumio-rpib3 sudo[3261]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:59 volumio-rpib3 volumio[1056]: sudo: unable to resolve host volumio-rpib3: System error Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Output device has changed, restarting MPD Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Output device has changed, restarting Shairport Sync Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:24:59 volumio-rpib3 sudo[3266]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:59 volumio-rpib3 sudo[3266]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:59 volumio-rpib3 sudo[3266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 17:24:59 volumio-rpib3 sudo[3266]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:59 volumio-rpib3 sudo[3266]: pam_unix(sudo:session): session closed for user root Nov 28 17:24:59 volumio-rpib3 sudo[3268]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:59 volumio-rpib3 sudo[3268]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:59 volumio-rpib3 sudo[3268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 17:24:59 volumio-rpib3 sudo[3268]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 17:24:59 volumio-rpib3 systemd[1]: Stopping Music Player Daemon... Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: MPD Permissions set Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:24:59 volumio-rpib3 volumio[1056]: info: Starting Shairport Sync Nov 28 17:24:59 volumio-rpib3 systemd[1]: mpd.service: Succeeded. Nov 28 17:24:59 volumio-rpib3 systemd[1]: Stopped Music Player Daemon. Nov 28 17:24:59 volumio-rpib3 systemd[1]: Starting Music Player Daemon... Nov 28 17:24:59 volumio-rpib3 sudo[3280]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:24:59 volumio-rpib3 sudo[3280]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:24:59 volumio-rpib3 sudo[3280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 17:24:59 volumio-rpib3 sudo[3280]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:24:59 volumio-rpib3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 17:24:59 volumio-rpib3 systemd[1]: shairport-sync.service: Succeeded. Nov 28 17:24:59 volumio-rpib3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 17:25:00 volumio-rpib3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 17:25:00 volumio-rpib3 sudo[3280]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Shairport-Sync Started Nov 28 17:25:00 volumio-rpib3 kernel: hwmon hwmon1: Undervoltage detected! Nov 28 17:25:00 volumio-rpib3 sudo[3279]: root : unable to resolve host volumio-rpib3 Nov 28 17:25:00 volumio-rpib3 sudo[3279]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:00 volumio-rpib3 sudo[3279]: sudo: unable to resolve host volumio-rpib3: System error Nov 28 17:25:00 volumio-rpib3 sudo[3279]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 17:25:00 volumio-rpib3 sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:00 volumio-rpib3 sudo[3279]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Stopping peppyspectrum service Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: CoreStateMachine::stop Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Disabling plugin peppyspectrum Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Preparing to generate the ALSA configuration file Nov 28 17:25:00 volumio-rpib3 sudo[3289]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:00 volumio-rpib3 sudo[3289]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:00 volumio-rpib3 sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Nov 28 17:25:00 volumio-rpib3 sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Reading ALSA contributions from plugins. Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Asound.conf file written Nov 28 17:25:00 volumio-rpib3 sudo[3289]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:00 volumio-rpib3 sudo[3292]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:00 volumio-rpib3 sudo[3292]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:00 volumio-rpib3 sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 28 17:25:00 volumio-rpib3 sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:00 volumio-rpib3 sudo[3292]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:00 volumio-rpib3 volumio[1056]: sudo: unable to resolve host volumio-rpib3: System error Nov 28 17:25:00 volumio-rpib3 volumio[1056]: info: Output device has changed, restarting MPD Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: Output device has changed, restarting Shairport Sync Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:25:01 volumio-rpib3 sudo[3297]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:01 volumio-rpib3 sudo[3297]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:01 volumio-rpib3 sudo[3297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 17:25:01 volumio-rpib3 sudo[3297]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:01 volumio-rpib3 sudo[3297]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:01 volumio-rpib3 sudo[3299]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:01 volumio-rpib3 sudo[3299]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:01 volumio-rpib3 sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 17:25:01 volumio-rpib3 sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:25:01 volumio-rpib3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 28 17:25:01 volumio-rpib3 systemd[1]: mpd.service: Succeeded. Nov 28 17:25:01 volumio-rpib3 systemd[1]: Stopped Music Player Daemon. Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: MPD Permissions set Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 systemd[1]: Starting Music Player Daemon... Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: Starting Shairport Sync Nov 28 17:25:01 volumio-rpib3 sudo[3315]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:01 volumio-rpib3 sudo[3315]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:01 volumio-rpib3 sudo[3315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 17:25:01 volumio-rpib3 sudo[3315]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:01 volumio-rpib3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 17:25:01 volumio-rpib3 systemd[1]: shairport-sync.service: Succeeded. Nov 28 17:25:01 volumio-rpib3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 17:25:01 volumio-rpib3 sudo[3305]: root : unable to resolve host volumio-rpib3 Nov 28 17:25:01 volumio-rpib3 sudo[3305]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:01 volumio-rpib3 sudo[3305]: sudo: unable to resolve host volumio-rpib3: System error Nov 28 17:25:01 volumio-rpib3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 17:25:01 volumio-rpib3 sudo[3305]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 17:25:01 volumio-rpib3 sudo[3305]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 17:25:01 volumio-rpib3 sudo[3315]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:01 volumio-rpib3 sudo[3305]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:01 volumio-rpib3 volumio[1056]: info: Shairport-Sync Started Nov 28 17:25:03 volumio-rpib3 mpd[3319]: Nov 28 17:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 17:25:03 volumio-rpib3 systemd[1]: Started Music Player Daemon. Nov 28 17:25:03 volumio-rpib3 sudo[3299]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:03 volumio-rpib3 sudo[3268]: pam_unix(sudo:session): session closed for user root Nov 28 17:25:03 volumio-rpib3 volumio[1056]: error: MPD error: The expression evaluated to a falsy value: Nov 28 17:25:03 volumio-rpib3 volumio[1056]: assert.ok(self.idling) Nov 28 17:25:03 volumio-rpib3 volumio[1056]: error: The expression evaluated to a falsy value: Nov 28 17:25:03 volumio-rpib3 volumio[1056]: assert.ok(self.idling) Nov 28 17:25:03 volumio-rpib3 volumio[1056]: error: updateQueue error: null Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPlay Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CoreStateMachine::play index undefined Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1897756938@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1908217215%7D Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: sendMpdCommand stop took 1 milliseconds Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: sendMpdCommand clear took 10 milliseconds Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: error: updateQueue error: null Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 14ms Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 4 milliseconds Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 4ms Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 6ms Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 3ms Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 17:25:07 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:07 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 11ms Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: sendMpdCommand play took 9 milliseconds Nov 28 17:25:07 volumio-rpib3 volumio[1056]: info: ------------------------------ 8ms Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 32 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 61 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 34 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 32 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 28 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 141ms Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: undefined Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand clearerror took 117 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 114 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 115 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: sendMpdCommand playlistinfo took 115 milliseconds Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseTrackInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":5239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KLAUDIA GAWLAS @ NATURE ONE 2024 (CENTURY CIRCUS)","artist":"KLAUDIA GAWLAS","album":"SoundCloud Track","uri":"https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDY1fX19XX0_&Signature=ZuVsQnz16J51TJi17lE5jD1rFsvi4C3AaQR0yq7iMfNncUlH0ID81wnAketpI2FOh7SGpL4~xlFQWCqwUazcPXdnIvwoU7hF3I3g71NxCAyq0z7TLzM4YR4NQYWCjMsvtz9AOaT46z~5sIQ2qumPLv1vG2J-cxHJ3kGuBhRIHJmzwBc22rZp6UJaH4WLPdlUJw6bgwlg-wqPKbGnGBBiTfUUukFUH0HdtThD8ls5QlbPRerPbvN5hVtXFUhuoeKu9LSBMQx3zfBjmRoihFx7rZ-ZZ-uhHTTT0PsZWDX9-0nupFaOEaNGJU8x3zx2Mz6Lz4MuXoBOWcpifpRoENV-KA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Nov 28 17:25:09 volumio-rpib3 volumio[1056]: verbose: CURRENT POSITION 2 Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState stateService pause Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::syncState currentStatus stop Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 329ms Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 325ms Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: ------------------------------ 295ms Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: PrevState: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: ON Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: undefined PrevState: undefined Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:25:09 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: undefined Nov 28 17:25:10 volumio-rpib3 volumiologrotate[718]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 28 17:25:10 volumio-rpib3 volumiologrotate[718]: ls: cannot access 'RPIB3+': No such file or directory Nov 28 17:25:18 volumio-rpib3 volumio[1056]: info: Disabling plugin remotepi Nov 28 17:25:18 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:25:20 volumio-rpib3 kernel: hwmon hwmon1: Voltage normalised Nov 28 17:25:25 volumio-rpib3 kernel: hwmon hwmon1: Undervoltage detected! Nov 28 17:25:25 volumio-rpib3 volumio[1056]: info: Disabling plugin backup_restore Nov 28 17:25:25 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:25:28 volumio-rpib3 volumio[1056]: info: AutoStart - onStop Nov 28 17:25:28 volumio-rpib3 volumio[1056]: info: Disabling plugin autostart Nov 28 17:25:28 volumio-rpib3 volumio[1056]: info: Done. Nov 28 17:25:29 volumio-rpib3 kernel: hwmon hwmon1: Voltage normalised Nov 28 17:25:30 volumio-rpib3 volumio[1056]: info: [ASDebug] Port: 24 Nov 28 17:25:30 volumio-rpib3 volumio[1056]: info: [ASDebug] Inverted: false Nov 28 17:25:30 volumio-rpib3 volumio[1056]: info: [ASDebug] Delay: 450 Nov 28 17:25:34 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioGetState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPlay Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::play index undefined Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::startPlaybackTimer Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1897756938@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1908217215%7D Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand stop took 43 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand clear took 4 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDkzfX19XX0_&Signature=WXSzwazn-jsy47~kRhvqcjB9nhVjtK-p54V4Va0daqGgsLko2scJvIyeG64zkHhtkwy9XGKY8PU1FBn1kK8UORFqeSp1KUZD2mAsTa~96p84SOfW0aU7ASjnMMwlywo0OMH3gMxpSfIfr32arlMw-HKp4f2Y7fS-GnuNB4VO3n8Mb2FEl09tU-~MUXujsgPAQi5asZbu-iIcYCywXLATdfdeFMewtItgqx2833Oy50YR9saNHTqPfSoHW6DrwF6LWEVij9TRwQuD5h0HtKk7u6sANGkKVT25oAkEqJAdJ1FaPGz3PxAKHx~0dzA0B6Q8eKkTXnwo~xVQcueGyf1TLA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces state update: player Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ControllerMpd::getState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Nov 28 17:25:35 volumio-rpib3 volumio[1056]: error: updateQueue error: null Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 7 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 10ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand addid "https://cf-hls-media.sndcdn.com/playlist/MiDRZalF8OLx.128.mp3/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1tZWRpYS5zbmRjZG4uY29tL3BsYXlsaXN0L01pRFJaYWxGOE9MeC4xMjgubXAzL3BsYXlsaXN0Lm0zdTgqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzMyODE2NDkzfX19XX0_&Signature=WXSzwazn-jsy47~kRhvqcjB9nhVjtK-p54V4Va0daqGgsLko2scJvIyeG64zkHhtkwy9XGKY8PU1FBn1kK8UORFqeSp1KUZD2mAsTa~96p84SOfW0aU7ASjnMMwlywo0OMH3gMxpSfIfr32arlMw-HKp4f2Y7fS-GnuNB4VO3n8Mb2FEl09tU-~MUXujsgPAQi5asZbu-iIcYCywXLATdfdeFMewtItgqx2833Oy50YR9saNHTqPfSoHW6DrwF6LWEVij9TRwQuD5h0HtKk7u6sANGkKVT25oAkEqJAdJ1FaPGz3PxAKHx~0dzA0B6Q8eKkTXnwo~xVQcueGyf1TLA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 7 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand status took 6 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::parseState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: MPD COMMAND [object Object] Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ControllerMpd::pushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::servicePushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::pushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreCommandRouter::volumioPushState Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CorePlayQueue::getTrack 2 Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 95ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 91ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 95ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 5ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 17:25:35 volumio-rpib3 volumio[1056]: verbose: ControllerMpd::sendMpdCommand play Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ---------------------------- MPD announces system playlist update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: Ignoring MPD Status Update Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [ASDebug] CurState: stop PrevState: undefined Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [ASDebug] InitTimeout - Amp off in: 450 ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Handling event: musicStop Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [GPIO_Control] Status changed: stop Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 49ms Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: sendMpdCommand play took 48 milliseconds Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: ------------------------------ 47ms Nov 28 17:25:35 volumio-rpib3 kernel: hwmon hwmon1: Undervoltage detected! Nov 28 17:25:35 volumio-rpib3 volumio[1056]: info: [ASDebug] Togle GPIO: OFF Nov 28 17:25:35 volumio-rpib3 volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:25:35 volumio-rpib3 volumio[1056]: Error: EBADF: bad file descriptor, write Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at Object.writeSync (fs.js:711:3) Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at listOnTimeout (internal/timers.js:554:17) Nov 28 17:25:35 volumio-rpib3 volumio[1056]: at processTimers (internal/timers.js:497:7) { Nov 28 17:25:35 volumio-rpib3 volumio[1056]: errno: -9, Nov 28 17:25:35 volumio-rpib3 volumio[1056]: syscall: 'write', Nov 28 17:25:35 volumio-rpib3 volumio[1056]: code: 'EBADF' Nov 28 17:25:35 volumio-rpib3 volumio[1056]: } Nov 28 17:25:35 volumio-rpib3 volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 17:25:37 volumio-rpib3 sudo[3455]: volumio : unable to resolve host volumio-rpib3 Nov 28 17:25:37 volumio-rpib3 sudo[3455]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Nov 28 17:25:37 volumio-rpib3 sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 17:24 Nov 28 17:25:37 volumio-rpib3 sudo[3455]: 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"