-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-02-24 06:13:44 CET. -- Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPlay Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CoreStateMachine::play index undefined Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 24 06:12:00 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:00 volumio-rpi volumio[1127]: info: [1740373920458] ControllerWebradio::clearAddPlayTrack Feb 24 06:12:00 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 06:12:00 volumio-rpi volumio[1127]: info: sendMpdCommand stop took 3 milliseconds Feb 24 06:12:00 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 06:12:00 volumio-rpi volumio[1127]: info: Feb 24 06:12:00 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:00 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:00 volumio-rpi volumio[1127]: info: sendMpdCommand clear took 3 milliseconds Feb 24 06:12:00 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand load "https://stream.100p.nl" Feb 24 06:12:00 volumio-rpi volumio[1127]: error: updateQueue error: null Feb 24 06:12:00 volumio-rpi volumio[1127]: info: ------------------------------ 7ms Feb 24 06:12:03 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand add "https://stream.100p.nl" Feb 24 06:12:03 volumio-rpi volumio[1127]: info: Feb 24 06:12:03 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:03 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:03 volumio-rpi volumio[1127]: info: sendMpdCommand add "https://stream.100p.nl" took 2 milliseconds Feb 24 06:12:03 volumio-rpi volumio[1127]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 06:12:03 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand play Feb 24 06:12:03 volumio-rpi volumio[1127]: info: ------------------------------ 38ms Feb 24 06:12:03 volumio-rpi volumio[1127]: info: sendMpdCommand play took 36 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Feb 24 06:12:04 volumio-rpi volumio[1127]: ---------------------------- MPD announces state update: player Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::getState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Feb 24 06:12:04 volumio-rpi volumio[1127]: ---------------------------- MPD announces state update: player Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand status took 60 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::getState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 24 06:12:04 volumio-rpi volumio[1127]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Feb 24 06:12:04 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Feb 24 06:12:04 volumio-rpi volumio[1127]: ---------------------------- MPD announces state update: player Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand status took 16 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::getState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseTrackInfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.100p.nl","artist":null,"album":null,"uri":"https://stream.100p.nl","trackType":"nl"} Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: CURRENT POSITION 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState stateService play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState currentStatus stop Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ------------------------------ 94ms Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ------------------------------ 21ms Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand status took 17 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseState Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseTrackInfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.100p.nl","artist":"100%NL FM Stream","album":null,"uri":"https://stream.100p.nl","trackType":"nl"} Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: CURRENT POSITION 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState stateService play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState currentStatus play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Received an update from plugin. extracting info from payload Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ------------------------------ 99ms Feb 24 06:12:04 volumio-rpi volumio[1127]: info: sendMpdCommand playlistinfo took 73 milliseconds Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseTrackInfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ControllerMpd::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1257,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.100p.nl","artist":"100%NL FM Stream","album":null,"uri":"https://stream.100p.nl","trackType":"nl"} Feb 24 06:12:04 volumio-rpi volumio[1127]: verbose: CURRENT POSITION 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState stateService play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState currentStatus play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: Received an update from plugin. extracting info from payload Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:04 volumio-rpi volumio[1127]: info: ------------------------------ 156ms Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [GPIO_Control] Handling event: musicPlay Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [LastFM] Current track has sufficient metadata: title (100% NL) and artist (100%NL FM Stream) passed on explicitly Feb 24 06:12:04 volumio-rpi volumio[1127]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: stop Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [ASDebug] Togle GPIO: ON Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:04 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:04 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:04 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:04 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:04 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:05 volumio-rpi sudo[3400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:05 volumio-rpi sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:05 volumio-rpi sudo[3396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:05 volumio-rpi sudo[3408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:05 volumio-rpi sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3396]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:05 volumio-rpi sudo[3398]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:05 volumio-rpi sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:05 volumio-rpi sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:05 volumio-rpi sudo[3402]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi sudo[3400]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:05 volumio-rpi systemd[1]: Started peppymeterbasic Daemon. Feb 24 06:12:05 volumio-rpi systemd[1]: Started peppyspectrum Daemon. Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 24 06:12:05 volumio-rpi sudo[3408]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi volumio[1127]: info: Received Get System Version Feb 24 06:12:05 volumio-rpi sudo[3404]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi sudo[3406]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 06:12:05 volumio-rpi volumio[1127]: info: Received Get System Info Feb 24 06:12:05 volumio-rpi sudo[3402]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi sudo[3410]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi sudo[3396]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi sudo[3400]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 06:12:05 volumio-rpi volumio[1127]: info: Discovery: Getting this device information Feb 24 06:12:05 volumio-rpi sudo[3398]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:06 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:06 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:06 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:06 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:06 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:07 volumio-rpi volumio[1127]: info: Feb 24 06:12:07 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:07 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:07 volumio-rpi volumio[1127]: info: Feb 24 06:12:07 volumio-rpi volumio[1127]: ---------------------------- MPD announces state update: player Feb 24 06:12:07 volumio-rpi volumio[1127]: info: ControllerMpd::getState Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand status Feb 24 06:12:07 volumio-rpi volumio[1127]: info: ------------------------------ 20ms Feb 24 06:12:07 volumio-rpi volumio[1127]: info: sendMpdCommand status took 19 milliseconds Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseState Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 06:12:07 volumio-rpi volumio[1127]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: ControllerMpd::parseTrackInfo Feb 24 06:12:07 volumio-rpi volumio[1127]: info: ControllerMpd::pushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28479,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Coldplay - Feelslikeimfallinginlove","artist":"100%NL FM Stream","album":null,"uri":"https://stream.100p.nl","trackType":"nl"} Feb 24 06:12:07 volumio-rpi volumio[1127]: verbose: CURRENT POSITION 0 Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState stateService play Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreStateMachine::syncState currentStatus play Feb 24 06:12:07 volumio-rpi volumio[1127]: info: Received an update from plugin. extracting info from payload Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:07 volumio-rpi volumio[1127]: info: ------------------------------ 79ms Feb 24 06:12:07 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: play Feb 24 06:12:07 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: play Feb 24 06:12:07 volumio-rpi volumio[1127]: info: [LastFM] Split composite title into artist: Coldplay and title: Feelslikeimfallinginlove Feb 24 06:12:07 volumio-rpi volumio[1127]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Feb 24 06:12:07 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:07 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:07 volumio-rpi sudo[3430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:07 volumio-rpi sudo[3430]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:08 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:08 volumio-rpi sudo[3430]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:08 volumio-rpi sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:08 volumio-rpi sudo[3433]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:08 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:08 volumio-rpi sudo[3433]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:08 volumio-rpi sudo[3436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:08 volumio-rpi sudo[3436]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:08 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:08 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:08 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:08 volumio-rpi sudo[3439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:08 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:08 volumio-rpi sudo[3439]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:08 volumio-rpi sudo[3436]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:08 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:08 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:08 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:08 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:08 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:08 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:08 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:08 volumio-rpi sudo[3439]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:08 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:09 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:09 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:09 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:09 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:10 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:10 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:10 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:10 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:11 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:11 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:11 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:11 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:12 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:12 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:12 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:12 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:12 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3421]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:13 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:13 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:13 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:13 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:13 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:14 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:14 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:14 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:14 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:15 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:15 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:15 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:15 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:16 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:16 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:16 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:16 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:17 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:17 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:17 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:17 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:18 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:18 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:18 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:18 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:19 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:19 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:19 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:19 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:20 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:20 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:20 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:20 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:21 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:21 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:21 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:21 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:22 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:22 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:23 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:23 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:23 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:23 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:24 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:24 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:24 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:24 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:25 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:25 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:25 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:25 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:26 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:26 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:26 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:26 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:27 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:27 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:27 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:27 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:28 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:28 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:28 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:28 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:29 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:29 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:29 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:29 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:30 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:31 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:31 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:31 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:31 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:32 volumio-rpi volumio[1127]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Feb 24 06:12:32 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:32 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:32 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:32 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:33 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:33 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:33 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:33 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:34 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:34 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:34 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:34 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:34 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:35 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:35 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:35 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:35 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:36 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:36 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:36 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:36 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:37 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:37 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:37 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:37 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:38 volumio-rpi volumio[1127]: info: FusionDsp - Stopping FusionDsp service Feb 24 06:12:38 volumio-rpi volumio[1127]: info: camilladsp stopping service pid 2829... Feb 24 06:12:38 volumio-rpi kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 24 06:12:38 volumio-rpi volumio[1127]: info: camilladsp service terminated, instance 1 Feb 24 06:12:38 volumio-rpi volumio[1127]: info: Disabling plugin fusiondsp Feb 24 06:12:38 volumio-rpi volumio[1127]: info: Preparing to generate the ALSA configuration file Feb 24 06:12:38 volumio-rpi sudo[3564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Feb 24 06:12:38 volumio-rpi sudo[3564]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:38 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:38 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:38 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:38 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:38 volumio-rpi volumio[1127]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 24 06:12:38 volumio-rpi volumio[1127]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 24 06:12:38 volumio-rpi volumio[1127]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 24 06:12:38 volumio-rpi volumio[1127]: info: Reading ALSA contributions from plugins. Feb 24 06:12:38 volumio-rpi volumio[1785]: Backend configuration: Feb 24 06:12:38 volumio-rpi volumio[1785]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Feb 24 06:12:38 volumio-rpi volumio[1785]: active_config_txt: null Feb 24 06:12:38 volumio-rpi volumio[1785]: camilla_host: 127.0.0.1 Feb 24 06:12:38 volumio-rpi volumio[1785]: camilla_port: 9876 Feb 24 06:12:38 volumio-rpi volumio[1785]: coeff_dir: /data/INTERNAL/FusionDsp/filters Feb 24 06:12:38 volumio-rpi volumio[1785]: config_dir: /data/configuration/audio_interface/fusiondsp Feb 24 06:12:38 volumio-rpi volumio[1785]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Feb 24 06:12:38 volumio-rpi volumio[1785]: log_file: /tmp/camilladsp.log Feb 24 06:12:38 volumio-rpi volumio[1785]: on_get_active_config: null Feb 24 06:12:38 volumio-rpi volumio[1785]: on_set_active_config: null Feb 24 06:12:38 volumio-rpi volumio[1785]: port: 5011 Feb 24 06:12:38 volumio-rpi volumio[1785]: supported_capture_types: null Feb 24 06:12:38 volumio-rpi volumio[1785]: supported_playback_types: null Feb 24 06:12:38 volumio-rpi volumio[1785]: update_config_symlink: false Feb 24 06:12:38 volumio-rpi volumio[1785]: update_config_txt: false Feb 24 06:12:38 volumio-rpi volumio[1785]: ======== Running on http://0.0.0.0:5011 ======== Feb 24 06:12:38 volumio-rpi volumio[1785]: (Press CTRL+C to quit) Feb 24 06:12:38 volumio-rpi volumio[1127]: info: Asound.conf file written Feb 24 06:12:38 volumio-rpi systemd[1]: Stopping FusionDsp Daemon... Feb 24 06:12:38 volumio-rpi sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 06:12:38 volumio-rpi sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:39 volumio-rpi sudo[3567]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:39 volumio-rpi systemd[1]: fusiondsp.service: Succeeded. Feb 24 06:12:39 volumio-rpi systemd[1]: Stopped FusionDsp Daemon. Feb 24 06:12:39 volumio-rpi sudo[3564]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:39 volumio-rpi volumio[1127]: info: Output device has changed, restarting MPD Feb 24 06:12:39 volumio-rpi sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 06:12:39 volumio-rpi sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:39 volumio-rpi sudo[3576]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:39 volumio-rpi volumio[1127]: info: Output device has changed, restarting Shairport Sync Feb 24 06:12:39 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:12:39 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 06:12:39 volumio-rpi sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 06:12:39 volumio-rpi sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:39 volumio-rpi systemd[1]: musicservicesshield.service: Succeeded. Feb 24 06:12:39 volumio-rpi systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Feb 24 06:12:39 volumio-rpi systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Feb 24 06:12:39 volumio-rpi systemd[1]: Stopping Music Player Daemon... Feb 24 06:12:39 volumio-rpi volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 06:12:39 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:40 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:40 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:40 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:40 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:40 volumio-rpi volumio[1127]: info: MPD Permissions set Feb 24 06:12:40 volumio-rpi volumio[1127]: info: FusionDsp - Reporting Fusion DSP Disabled Feb 24 06:12:40 volumio-rpi volumio[1127]: info: Removing fusiondspeq DSP Signal Path Element Feb 24 06:12:40 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 06:12:41 volumio-rpi volumio[1127]: info: Starting Shairport Sync Feb 24 06:12:41 volumio-rpi volumio[1127]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPause Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreStateMachine::pause Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreStateMachine::stPlaybackTimer Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreStateMachine::servicePause Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePause Feb 24 06:12:41 volumio-rpi volumio[1127]: info: [1740373961634] ControllerWebradio::pause Feb 24 06:12:41 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand pause Feb 24 06:12:41 volumio-rpi volumio[1127]: info: mpdhttpout --- Volumio set on pause Feb 24 06:12:41 volumio-rpi volumio[1127]: error: MPD error: Error: write EPIPE Feb 24 06:12:41 volumio-rpi volumio[1127]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:41 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:41 volumio-rpi sudo[3593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop roonbridge.service Feb 24 06:12:41 volumio-rpi sudo[3594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 06:12:41 volumio-rpi sudo[3594]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:41 volumio-rpi sudo[3593]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:42 volumio-rpi systemd[1]: Stopping RoonBridge... Feb 24 06:12:42 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 06:12:42 volumio-rpi systemd[1]: roonbridge.service: Main process exited, code=killed, status=15/TERM Feb 24 06:12:42 volumio-rpi systemd[1]: roonbridge.service: Succeeded. Feb 24 06:12:42 volumio-rpi systemd[1]: Stopped RoonBridge. Feb 24 06:12:42 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:42 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:42 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:42 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:42 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Feb 24 06:12:42 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 06:12:42 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 06:12:42 volumio-rpi sudo[3594]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:42 volumio-rpi volumio[1127]: info: Shairport-Sync Started Feb 24 06:12:43 volumio-rpi sudo[3593]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:43 volumio-rpi volumio[1127]: info: Disabling plugin RoonBridge Feb 24 06:12:43 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:43 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:43 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:43 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:43 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:43 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 24 06:12:43 volumio-rpi sudo[3614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 06:12:43 volumio-rpi sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:44 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:44 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:44 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:44 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesdmd2_music Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Jellyfin Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Mother Earth Radio Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Pandora Radio Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Radio Paradise Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:12:45 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:12:45 volumio-rpi volumio[1127]: info: Stopping AccessToken refresher cron for DMD2 Music Feb 24 06:12:45 volumio-rpi volumio[1127]: info: Disabling plugin dmd2_music Feb 24 06:12:45 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:45 volumio-rpi systemd[1]: mpd.service: Succeeded. Feb 24 06:12:45 volumio-rpi systemd[1]: Stopped Music Player Daemon. Feb 24 06:12:45 volumio-rpi systemd[1]: Starting Music Player Daemon... Feb 24 06:12:45 volumio-rpi sudo[3618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 06:12:45 volumio-rpi sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:45 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:45 volumio-rpi sudo[3618]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesJellyfin Feb 24 06:12:46 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Mother Earth Radio Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Pandora Radio Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Radio Paradise Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:12:46 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:12:46 volumio-rpi volumio[1127]: info: Disabling plugin jellyfin Feb 24 06:12:46 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:47 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:47 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:47 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:47 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:48 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:48 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:48 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:48 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:48 volumio-rpi mpd[3620]: Feb 24 06:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 06:12:48 volumio-rpi systemd[1]: Started Music Player Daemon. Feb 24 06:12:48 volumio-rpi sudo[3614]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:48 volumio-rpi sudo[3579]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:48 volumio-rpi volumio[1127]: error: MPD error: The expression evaluated to a falsy value: Feb 24 06:12:48 volumio-rpi volumio[1127]: assert.ok(self.idling) Feb 24 06:12:48 volumio-rpi volumio[1127]: error: The expression evaluated to a falsy value: Feb 24 06:12:48 volumio-rpi volumio[1127]: assert.ok(self.idling) Feb 24 06:12:48 volumio-rpi volumio[1127]: error: updateQueue error: null Feb 24 06:12:48 volumio-rpi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Feb 24 06:12:49 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:49 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:49 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:49 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:50 volumio-rpi volumio[3626]: cset: --> shielding system active with Feb 24 06:12:50 volumio-rpi volumio[3626]: cset: "system" cpuset of CPUSPEC(1-3) with 233 tasks running Feb 24 06:12:50 volumio-rpi volumio[3626]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 24 06:12:50 volumio-rpi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Feb 24 06:12:50 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:50 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:50 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:50 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:51 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:51 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:51 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:51 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:52 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:52 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:52 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:52 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMother Earth Radio Feb 24 06:12:53 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Pandora Radio Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Radio Paradise Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:12:53 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:12:53 volumio-rpi volumio[1127]: info: Disabling plugin motherearthradio Feb 24 06:12:53 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:54 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:54 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:54 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:54 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:54 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:54 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: [1740373975633] ControllerPandora::flushPandora Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CorePlayQueue::clearAddPlayQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CorePlayQueue::saveQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 24 06:12:55 volumio-rpi volumio[1127]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushQueue Feb 24 06:12:55 volumio-rpi volumio[1127]: info: [1740373975645] ControllerPandora::stop Feb 24 06:12:55 volumio-rpi volumio[1127]: info: ControllerMpd::stop Feb 24 06:12:55 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 06:12:55 volumio-rpi volumio[1127]: info: sendMpdCommand stop took 9 milliseconds Feb 24 06:12:55 volumio-rpi volumio[1127]: info: ControllerMpd::clear Feb 24 06:12:55 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Feb 24 06:12:55 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:55 volumio-rpi volumio[1127]: info: sendMpdCommand clear took 2 milliseconds Feb 24 06:12:55 volumio-rpi volumio[1127]: info: [1740373975661] ControllerPandora::pushState Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::servicePushState Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received pandora Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreStateMachine::setConsumeUpdateService pandora Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesPandora Radio Feb 24 06:12:55 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Radio Paradise Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:12:55 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Disabling plugin pandora Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Feb 24 06:12:55 volumio-rpi volumio[1127]: ---------------------------- MPD announces system playlist update Feb 24 06:12:55 volumio-rpi volumio[1127]: info: Ignoring MPD Status Update Feb 24 06:12:55 volumio-rpi volumio[1127]: error: updateQueue error: null Feb 24 06:12:55 volumio-rpi volumio[1127]: info: ------------------------------ 84ms Feb 24 06:12:55 volumio-rpi volumio[1127]: info: ------------------------------ 5ms Feb 24 06:12:55 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: play Feb 24 06:12:55 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 24 06:12:55 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status play Feb 24 06:12:55 volumio-rpi volumio[1127]: info: [ASDebug] CurState: play PrevState: play Feb 24 06:12:55 volumio-rpi volumio[1127]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 24 06:12:56 volumio-rpi sudo[3659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 24 06:12:56 volumio-rpi sudo[3657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 24 06:12:56 volumio-rpi sudo[3659]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:56 volumio-rpi sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:12:56 volumio-rpi sudo[3657]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:56 volumio-rpi sudo[3659]: pam_unix(sudo:session): session closed for user root Feb 24 06:12:56 volumio-rpi volumio[1127]: info: peppymeterbasic Daemon Started Feb 24 06:12:56 volumio-rpi volumio[1127]: info: peppyspectrum Daemon Started Feb 24 06:12:56 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:56 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:56 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:56 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:56 volumio-rpi volumio[1127]: info: Disabling plugin personal_radio Feb 24 06:12:56 volumio-rpi volumio[1127]: info: Done. Feb 24 06:12:57 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:57 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:57 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:57 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:58 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:58 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:58 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:58 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:12:59 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:12:59 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:12:59 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:12:59 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRadio Paradise Feb 24 06:13:00 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:13:00 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:13:00 volumio-rpi volumio[1127]: info: Disabling plugin radio_paradise Feb 24 06:13:00 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:01 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:01 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:01 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:01 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:02 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:02 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:02 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:02 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source Bandcamp Discover Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source Calm Radio Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source SoundCloud Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source YouTube2 Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source YouTube Music Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source 80s80s Radio Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source Personal Radio Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source Podcast Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source RADIO 357 Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source SWARM Station Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source Volusonic Feb 24 06:13:03 volumio-rpi volumio[1127]: Cannot find translation for source LastFM Feb 24 06:13:03 volumio-rpi volumio[1127]: info: Disabling plugin spop Feb 24 06:13:03 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:03 volumio-rpi volumio[1127]: info: Connection to go-librespot Websocket closed Feb 24 06:13:03 volumio-rpi sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Feb 24 06:13:03 volumio-rpi sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:03 volumio-rpi systemd[1]: Stopping go-librespot Daemon... Feb 24 06:13:03 volumio-rpi systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Feb 24 06:13:03 volumio-rpi systemd[1]: go-librespot-daemon.service: Succeeded. Feb 24 06:13:03 volumio-rpi systemd[1]: Stopped go-librespot Daemon. Feb 24 06:13:03 volumio-rpi sudo[3667]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:03 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:04 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:04 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:04 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:05 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:05 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:05 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:06 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:06 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:06 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:06 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:07 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:07 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:07 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:08 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:08 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:08 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:08 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:09 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:09 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:09 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:09 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:10 volumio-rpi volumio[1127]: info: [ASDebug] Port: 24 Feb 24 06:13:10 volumio-rpi volumio[1127]: info: [ASDebug] Inverted: false Feb 24 06:13:10 volumio-rpi volumio[1127]: info: [ASDebug] Delay: 720 Feb 24 06:13:10 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:10 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:10 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:10 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:11 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:11 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:11 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:11 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:12 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:12 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:12 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:12 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:13 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:13 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 24 06:13:13 volumio-rpi volumio[1127]: info: CoreStateMachine::getQueue Feb 24 06:13:13 volumio-rpi volumio[1127]: info: CorePlayQueue::getQueue Feb 24 06:13:14 volumio-rpi sudo[3704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop evo_oled2.service Feb 24 06:13:14 volumio-rpi sudo[3704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:14 volumio-rpi systemd[1]: Stopping OLED Display Service for EVO SABRE... Feb 24 06:13:14 volumio-rpi sudo[1881]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:14 volumio-rpi systemd[1]: evo_oled2.service: Succeeded. Feb 24 06:13:14 volumio-rpi systemd[1]: Stopped OLED Display Service for EVO SABRE. Feb 24 06:13:14 volumio-rpi sudo[3704]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:14 volumio-rpi volumio[1127]: info: EVO SABRE : systemd cmd stop evo_oled2.service : success Feb 24 06:13:15 volumio-rpi sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Feb 24 06:13:15 volumio-rpi sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:15 volumio-rpi systemd[1]: Stopping Flexible IR remote input/output application support... Feb 24 06:13:15 volumio-rpi lircd[2085]: lircd-0.10.1[2085]: Notice: caught signal Feb 24 06:13:15 volumio-rpi lircd-0.10.1[2085]: Notice: caught signal Feb 24 06:13:15 volumio-rpi systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Feb 24 06:13:15 volumio-rpi systemd[1]: lircd.service: Succeeded. Feb 24 06:13:15 volumio-rpi systemd[1]: Stopped Flexible IR remote input/output application support. Feb 24 06:13:15 volumio-rpi systemd[1]: irexec.service: Succeeded. Feb 24 06:13:15 volumio-rpi systemd[1]: evo_irexec.service: Succeeded. Feb 24 06:13:15 volumio-rpi sudo[3708]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:15 volumio-rpi volumio[1127]: info: EVO SABRE : systemd cmd stop lircd.service : success Feb 24 06:13:15 volumio-rpi sudo[3711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop irexec.service Feb 24 06:13:15 volumio-rpi sudo[3711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:15 volumio-rpi sudo[3711]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:15 volumio-rpi volumio[1127]: info: EVO SABRE : systemd cmd stop irexec.service : success Feb 24 06:13:15 volumio-rpi volumio[1127]: info: Disabling plugin audiophonics_evo_sabre Feb 24 06:13:15 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:16 volumio-rpi volumio[1127]: info: performing onStop action Feb 24 06:13:16 volumio-rpi volumio[1127]: info: Disabling plugin audiophonicsonoff Feb 24 06:13:16 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:17 volumio-rpi volumio[1127]: info: GPIO-Buttons stopped Feb 24 06:13:17 volumio-rpi volumio[1127]: info: Disabling plugin gpio-buttons Feb 24 06:13:17 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:18 volumio-rpi volumio[1127]: info: [GPIO_Control] GPIOs destroyed Feb 24 06:13:18 volumio-rpi volumio[1127]: info: Disabling plugin gpio_control Feb 24 06:13:18 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:20 volumio-rpi sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lircd.service Feb 24 06:13:20 volumio-rpi sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:20 volumio-rpi sudo[3720]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:20 volumio-rpi volumio[1127]: info: ir_controller: systemctl stop lircd.service succeeded. Feb 24 06:13:20 volumio-rpi volumio[1127]: info: Disabling plugin ir_controller Feb 24 06:13:20 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:20 volumio-rpi sudo[3725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Feb 24 06:13:20 volumio-rpi sudo[3725]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:20 volumio-rpi sudo[3725]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:20 volumio-rpi volumio[1127]: info: ir_controller: gpio-ir overlay removed. Feb 24 06:13:21 volumio-rpi sudo[3736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Feb 24 06:13:21 volumio-rpi sudo[3736]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:21 volumio-rpi volumio[1127]: info: Disabling plugin mpd_oled Feb 24 06:13:21 volumio-rpi volumio[1127]: info: Preparing to generate the ALSA configuration file Feb 24 06:13:21 volumio-rpi sudo[3736]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:21 volumio-rpi volumio[1127]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 24 06:13:21 volumio-rpi volumio[1127]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 24 06:13:21 volumio-rpi volumio[1127]: info: Reading ALSA contributions from plugins. Feb 24 06:13:21 volumio-rpi volumio[1127]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Feb 24 06:13:21 volumio-rpi volumio[1127]: info: Asound.conf file written Feb 24 06:13:21 volumio-rpi sudo[3741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 06:13:21 volumio-rpi sudo[3741]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:21 volumio-rpi sudo[3741]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:21 volumio-rpi volumio[1127]: info: Output device has changed, restarting MPD Feb 24 06:13:21 volumio-rpi sudo[3748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 06:13:21 volumio-rpi sudo[3748]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:22 volumio-rpi sudo[3748]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:22 volumio-rpi volumio[1127]: info: Output device has changed, restarting Shairport Sync Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 06:13:22 volumio-rpi sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 06:13:22 volumio-rpi sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:22 volumio-rpi systemd[1]: musicservicesshield.service: Succeeded. Feb 24 06:13:22 volumio-rpi systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Feb 24 06:13:22 volumio-rpi systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Feb 24 06:13:22 volumio-rpi systemd[1]: Stopping Music Player Daemon... Feb 24 06:13:22 volumio-rpi volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 06:13:22 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:22 volumio-rpi systemd[1]: mpd.service: Succeeded. Feb 24 06:13:22 volumio-rpi systemd[1]: Stopped Music Player Daemon. Feb 24 06:13:22 volumio-rpi volumio[1127]: info: MPD Permissions set Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi systemd[1]: Starting Music Player Daemon... Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:22 volumio-rpi volumio[1127]: info: Starting Shairport Sync Feb 24 06:13:22 volumio-rpi volumio[1127]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPause Feb 24 06:13:22 volumio-rpi volumio[1127]: info: CoreStateMachine::pause Feb 24 06:13:22 volumio-rpi volumio[1127]: info: mpdhttpout --- Volumio set on pause Feb 24 06:13:22 volumio-rpi sudo[3763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 06:13:22 volumio-rpi sudo[3763]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:22 volumio-rpi sudo[3756]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 06:13:22 volumio-rpi sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:22 volumio-rpi sudo[3756]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:22 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 24 06:13:22 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Feb 24 06:13:22 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 24 06:13:22 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 24 06:13:22 volumio-rpi sudo[3763]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:22 volumio-rpi volumio[1127]: info: Shairport-Sync Started Feb 24 06:13:23 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:23 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 06:13:24 volumio-rpi mpd[3766]: Feb 24 06:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 06:13:24 volumio-rpi systemd[1]: Started Music Player Daemon. Feb 24 06:13:24 volumio-rpi sudo[3751]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:24 volumio-rpi volumio[1127]: error: updateQueue error: null Feb 24 06:13:24 volumio-rpi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Feb 24 06:13:24 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 24 06:13:24 volumio-rpi sudo[3796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 06:13:24 volumio-rpi sudo[3796]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:24 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 24 06:13:24 volumio-rpi systemd[1]: Stopping Music Player Daemon... Feb 24 06:13:24 volumio-rpi systemd[1]: mpd.service: Succeeded. Feb 24 06:13:24 volumio-rpi systemd[1]: Stopped Music Player Daemon. Feb 24 06:13:24 volumio-rpi systemd[1]: Starting Music Player Daemon... Feb 24 06:13:24 volumio-rpi sudo[3798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 06:13:24 volumio-rpi sudo[3798]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:24 volumio-rpi sudo[3798]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:25 volumio-rpi volumio[3786]: cset: --> shielding system active with Feb 24 06:13:25 volumio-rpi volumio[3786]: cset: "system" cpuset of CPUSPEC(1-3) with 212 tasks running Feb 24 06:13:25 volumio-rpi volumio[3786]: cset: "user" cpuset of CPUSPEC(0) with 0 tasks running Feb 24 06:13:25 volumio-rpi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Feb 24 06:13:26 volumio-rpi mpd[3806]: Feb 24 06:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 06:13:26 volumio-rpi systemd[1]: Started Music Player Daemon. Feb 24 06:13:26 volumio-rpi sudo[3796]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:26 volumio-rpi volumio[1127]: error: updateQueue error: null Feb 24 06:13:30 volumio-rpi volumio[1127]: info: Disabling plugin rotaryencoder2 Feb 24 06:13:30 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:31 volumio-rpi volumio[1127]: info: Disabling plugin Systeminfo Feb 24 06:13:31 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:32 volumio-rpi volumio[1127]: info: Enabling plugin Systeminfo Feb 24 06:13:32 volumio-rpi volumio[1127]: info: Loading plugin "Systeminfo"... Feb 24 06:13:32 volumio-rpi volumio[1127]: info: PLUGIN START: Systeminfo Feb 24 06:13:32 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:35 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:13:35 volumio-rpi volumio[3420]: libpng warning: iCCP: known incorrect sRGB profile Feb 24 06:13:36 volumio-rpi volumio[1127]: info: Performing onStop action Feb 24 06:13:36 volumio-rpi volumio[1127]: info: Disabling plugin lastfm Feb 24 06:13:36 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:39 volumio-rpi volumio[1127]: info: Stopping peppymeterbasic service Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::stop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::updateTrackBlock Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrackBlock Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::stPlaybackTimer Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::pushState Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreStateMachine::serviceStop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 24 06:13:39 volumio-rpi volumio[1127]: info: CoreCommandRouter::serviceStop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: [1740374019590] ControllerWebradio::stop Feb 24 06:13:39 volumio-rpi volumio[1127]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: Disabling plugin peppymeterbasic Feb 24 06:13:39 volumio-rpi volumio[1127]: info: Preparing to generate the ALSA configuration file Feb 24 06:13:39 volumio-rpi volumio[1127]: info: [GPIO_Control] Status changed: stop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: [GPIO_Control] Handling event: musicStop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 24 06:13:39 volumio-rpi volumio[1127]: info: PeppySpectrum ---peppyspectrum status stop Feb 24 06:13:39 volumio-rpi sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 24 06:13:39 volumio-rpi sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:39 volumio-rpi volumio[1127]: info: [ASDebug] CurState: stop PrevState: play Feb 24 06:13:39 volumio-rpi volumio[1127]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 24 06:13:39 volumio-rpi volumio[1127]: info: sendMpdCommand stop took 155 milliseconds Feb 24 06:13:39 volumio-rpi systemd[1]: Stopping peppymeterbasic Daemon... Feb 24 06:13:39 volumio-rpi systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Feb 24 06:13:39 volumio-rpi sudo[3880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 24 06:13:39 volumio-rpi sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:39 volumio-rpi systemd[1]: Stopping peppyspectrum Daemon... Feb 24 06:13:39 volumio-rpi systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Feb 24 06:13:39 volumio-rpi volumio[1127]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 24 06:13:39 volumio-rpi volumio[1127]: info: Reading ALSA contributions from plugins. Feb 24 06:13:39 volumio-rpi volumio[1127]: info: Asound.conf file written Feb 24 06:13:40 volumio-rpi sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 06:13:40 volumio-rpi sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 24 06:13:40 volumio-rpi sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:40 volumio-rpi sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:40 volumio-rpi sudo[3887]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi volumio[1127]: info: Output device has changed, restarting MPD Feb 24 06:13:40 volumio-rpi sudo[3893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 06:13:40 volumio-rpi sudo[3893]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:40 volumio-rpi sudo[3893]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi volumio[1127]: info: Output device has changed, restarting Shairport Sync Feb 24 06:13:40 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 06:13:40 volumio-rpi volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 06:13:40 volumio-rpi sudo[3896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 06:13:40 volumio-rpi sudo[3896]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:40 volumio-rpi systemd[1]: musicservicesshield.service: Succeeded. Feb 24 06:13:40 volumio-rpi systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Feb 24 06:13:40 volumio-rpi systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Feb 24 06:13:40 volumio-rpi systemd[1]: Stopping Music Player Daemon... Feb 24 06:13:40 volumio-rpi volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 06:13:40 volumio-rpi systemd[1]: peppymeterbasic.service: Succeeded. Feb 24 06:13:40 volumio-rpi systemd[1]: Stopped peppymeterbasic Daemon. Feb 24 06:13:40 volumio-rpi sudo[3883]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi sudo[3877]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi systemd[1]: peppyspectrum.service: Succeeded. Feb 24 06:13:40 volumio-rpi systemd[1]: Stopped peppyspectrum Daemon. Feb 24 06:13:40 volumio-rpi sudo[3880]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi volumio[1127]: info: Done. Feb 24 06:13:40 volumio-rpi systemd[1]: mpd.service: Succeeded. Feb 24 06:13:40 volumio-rpi systemd[1]: Stopped Music Player Daemon. Feb 24 06:13:40 volumio-rpi volumio[1127]: info: [ASDebug] Togle GPIO: OFF Feb 24 06:13:40 volumio-rpi systemd[1]: Starting Music Player Daemon... Feb 24 06:13:40 volumio-rpi volumio[1127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 06:13:40 volumio-rpi sudo[3901]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 06:13:40 volumio-rpi sudo[3901]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 06:13:40 volumio-rpi sudo[3901]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:40 volumio-rpi volumio[1127]: Error: ESPIPE: invalid seek, write Feb 24 06:13:40 volumio-rpi volumio[1127]: at Object.writeSync (fs.js:711:3) Feb 24 06:13:40 volumio-rpi volumio[1127]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 24 06:13:40 volumio-rpi volumio[1127]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 24 06:13:40 volumio-rpi volumio[1127]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 24 06:13:40 volumio-rpi volumio[1127]: at listOnTimeout (internal/timers.js:554:17) Feb 24 06:13:40 volumio-rpi volumio[1127]: at processTimers (internal/timers.js:497:7) { Feb 24 06:13:40 volumio-rpi volumio[1127]: errno: -29, Feb 24 06:13:40 volumio-rpi volumio[1127]: syscall: 'write', Feb 24 06:13:40 volumio-rpi volumio[1127]: code: 'ESPIPE' Feb 24 06:13:40 volumio-rpi volumio[1127]: } Feb 24 06:13:40 volumio-rpi volumio[1127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 06:13:42 volumio-rpi mpd[3904]: Feb 24 06:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 06:13:42 volumio-rpi systemd[1]: Started Music Player Daemon. Feb 24 06:13:42 volumio-rpi sudo[3896]: pam_unix(sudo:session): session closed for user root Feb 24 06:13:42 volumio-rpi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Feb 24 06:13:43 volumio-rpi volumio[3915]: cset: --> shielding system active with Feb 24 06:13:43 volumio-rpi volumio[3915]: cset: "system" cpuset of CPUSPEC(1-3) with 208 tasks running Feb 24 06:13:43 volumio-rpi volumio[3915]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 24 06:13:43 volumio-rpi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Feb 24 06:13:44 volumio-rpi sudo[3947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 06:12 Feb 24 06:13:44 volumio-rpi sudo[3947]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"